builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0006 starttime: 1483958865.74 results: success (0) buildid: 20170109013307 builduid: 856b7af4cc25479082738c72e92cb6a2 revision: 97896f92f196462e0072f8304b97c29217da1327 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 02:47:45.736574) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 02:47:45.737016) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 02:47:45.737284) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 02:47:45.762302) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 02:47:45.762653) ========= 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.42mKVHTo4y/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.p8bcECqf3S/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-09 02:47:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.6M=0.001s 2017-01-09 02:47:46 (13.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090529 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 02:47:45.876586) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 02:47:45.876916) ========= 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.42mKVHTo4y/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.p8bcECqf3S/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.019906 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 02:47:45.925264) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 02:47:45.925605) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 97896f92f196462e0072f8304b97c29217da1327 --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 97896f92f196462e0072f8304b97c29217da1327 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.42mKVHTo4y/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.p8bcECqf3S/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-09 02:47:46,198 truncating revision to first 12 chars 2017-01-09 02:47:46,198 Setting DEBUG logging. 2017-01-09 02:47:46,198 attempt 1/10 2017-01-09 02:47:46,198 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/97896f92f196?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 02:47:47,427 unpacking tar archive at: mozilla-central-97896f92f196/testing/mozharness/ program finished with exit code 0 elapsedTime=1.479761 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 02:47:47.430828) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 02:47:47.431244) ========= script_repo_revision: 97896f92f196462e0072f8304b97c29217da1327 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 02:47:47.431769) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 02:47:47.432215) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 02:47:47.452148) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-09 02:47:47.452486) ========= /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.42mKVHTo4y/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.p8bcECqf3S/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 02:47:47 INFO - MultiFileLogger online at 20170109 02:47:47 in /builds/slave/test 02:47:47 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 02:47:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:47:47 INFO - {'append_to_log': False, 02:47:47 INFO - 'base_work_dir': '/builds/slave/test', 02:47:47 INFO - 'blob_upload_branch': 'Firefox', 02:47:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:47:47 INFO - 'branch': 'Firefox', 02:47:47 INFO - 'buildbot_json_path': 'buildprops.json', 02:47:47 INFO - 'config_files': ('talos/mac_config.py',), 02:47:47 INFO - 'default_actions': ('clobber', 02:47:47 INFO - 'read-buildbot-config', 02:47:47 INFO - 'download-and-extract', 02:47:47 INFO - 'populate-webroot', 02:47:47 INFO - 'create-virtualenv', 02:47:47 INFO - 'install', 02:47:47 INFO - 'run-tests'), 02:47:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:47:47 INFO - 'download_minidump_stackwalk': True, 02:47:47 INFO - 'download_symbols': 'ondemand', 02:47:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:47:47 INFO - 'tooltool.py': '/tools/tooltool.py', 02:47:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:47:47 INFO - '/tools/misc-python/virtualenv.py')}, 02:47:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:47:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:47:47 INFO - 'installer_path': 'installer.exe', 02:47:47 INFO - 'log_level': 'info', 02:47:47 INFO - 'log_name': 'talos', 02:47:47 INFO - 'log_to_console': True, 02:47:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:47:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:47:47 INFO - 'opt_config_files': (), 02:47:47 INFO - 'pip_index': False, 02:47:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:47:47 INFO - 'cmd': ('bash', 02:47:47 INFO - '-c', 02:47:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:47:47 INFO - 'enabled': True, 02:47:47 INFO - 'halt_on_failure': False, 02:47:47 INFO - 'name': 'check_screen_resolution'},), 02:47:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:47:47 INFO - 'cmd': ('bash', 02:47:47 INFO - '-c', 02:47:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:47:47 INFO - 'enabled': True, 02:47:47 INFO - 'halt_on_failure': False, 02:47:47 INFO - 'name': 'check_screen_resolution'},), 02:47:47 INFO - 'run_cmd_checks_enabled': True, 02:47:47 INFO - 'sps_profile': False, 02:47:47 INFO - 'sps_profile_interval': 0, 02:47:47 INFO - 'suite': 'dromaeojs', 02:47:47 INFO - 'system_bits': '32', 02:47:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:47:47 INFO - 'title': 't-yosemite-r7-0006', 02:47:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:47:47 INFO - 'use_talos_json': True, 02:47:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:47:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:47:47 INFO - 'work_dir': 'build'} 02:47:47 INFO - [mozharness: 2017-01-09 10:47:47.853597Z] Running clobber step. 02:47:47 INFO - Running pre-action listener: _resource_record_pre_action 02:47:47 INFO - Running main action method: clobber 02:47:47 INFO - rmtree: /builds/slave/test/build 02:47:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:47:48 INFO - Running post-action listener: _resource_record_post_action 02:47:48 INFO - [mozharness: 2017-01-09 10:47:48.759312Z] Finished clobber step (success) 02:47:48 INFO - [mozharness: 2017-01-09 10:47:48.759546Z] Running read-buildbot-config step. 02:47:48 INFO - Running pre-action listener: _resource_record_pre_action 02:47:48 INFO - Running main action method: read_buildbot_config 02:47:48 INFO - Using buildbot properties: 02:47:48 INFO - { 02:47:48 INFO - "project": "", 02:47:48 INFO - "product": "firefox", 02:47:48 INFO - "script_repo_revision": "production", 02:47:48 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 02:47:48 INFO - "repository": "", 02:47:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 02:47:48 INFO - "buildid": "20170109013307", 02:47:48 INFO - "pgo_build": "False", 02:47:48 INFO - "basedir": "/builds/slave/test", 02:47:48 INFO - "buildnumber": 56, 02:47:48 INFO - "slavename": "t-yosemite-r7-0006", 02:47:48 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:47:48 INFO - "platform": "yosemite_r7", 02:47:48 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 02:47:48 INFO - "branch": "mozilla-central", 02:47:48 INFO - "repo_path": "mozilla-central", 02:47:48 INFO - "revision": "97896f92f196462e0072f8304b97c29217da1327", 02:47:48 INFO - "stage_platform": "macosx64", 02:47:48 INFO - "builduid": "856b7af4cc25479082738c72e92cb6a2", 02:47:48 INFO - "slavebuilddir": "test" 02:47:48 INFO - } 02:47:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 02:47:48 INFO - Running post-action listener: _resource_record_post_action 02:47:48 INFO - [mozharness: 2017-01-09 10:47:48.762984Z] Finished read-buildbot-config step (success) 02:47:48 INFO - [mozharness: 2017-01-09 10:47:48.763109Z] Running download-and-extract step. 02:47:48 INFO - Running pre-action listener: _resource_record_pre_action 02:47:48 INFO - Running main action method: download_and_extract 02:47:48 INFO - mkdir: /builds/slave/test/build/tests 02:47:48 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')]} 02:47:48 INFO - trying https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 02:47:48 INFO - Downloading https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/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 02:47:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/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 02:47:50 INFO - Downloaded 1199 bytes. 02:47:50 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 02:47:50 INFO - Using the following test package requirements: 02:47:50 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 02:47:50 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:47:50 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 02:47:50 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:47:50 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 02:47:50 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:47:50 INFO - u'jsshell-mac.zip'], 02:47:50 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:47:50 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 02:47:50 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 02:47:50 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:47:50 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 02:47:50 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:47:50 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 02:47:50 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:47:50 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 02:47:50 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 02:47:50 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 02:47:50 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:47:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 02:47:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:47:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 02:47:51 INFO - Expected file size: 16304894 02:47:51 INFO - Obtained file size: 16304894 02:47:57 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 02:47:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 02:47:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:47:57 INFO - Fetch https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 02:47:58 INFO - Expected file size: 16304894 02:47:58 INFO - Obtained file size: 16304894 02:48:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 02:48:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:48:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 02:48:05 INFO - Expected file size: 14403537 02:48:05 INFO - Obtained file size: 14403537 02:48: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')]} 02:48:05 INFO - trying https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 02:48:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 02:48:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/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 02:48:07 INFO - Downloaded 69770109 bytes. 02:48:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 02:48:07 INFO - mkdir: /builds/slave/test/properties 02:48:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:48:07 INFO - Writing to file /builds/slave/test/properties/build_url 02:48:07 INFO - Contents: 02:48:07 INFO - build_url:https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 02:48:08 INFO - Running post-action listener: _resource_record_post_action 02:48:08 INFO - Running post-action listener: set_extra_try_arguments 02:48:08 INFO - [mozharness: 2017-01-09 10:48:08.611852Z] Finished download-and-extract step (success) 02:48:08 INFO - [mozharness: 2017-01-09 10:48:08.611956Z] Running populate-webroot step. 02:48:08 INFO - Running pre-action listener: _resource_record_pre_action 02:48:08 INFO - Running main action method: populate_webroot 02:48:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:48:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:48:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:48:08 INFO - '--tppagecycles', 02:48:08 INFO - '7'], 02:48:08 INFO - 'tests': ['tsvgm']}}, 02:48:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:48:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:48:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:48:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:48:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:48:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:48:08 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 02:48:08 INFO - 'talos_options': ['--disable-e10s'], 02:48:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:48:08 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 02:48:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:48:08 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 02:48:08 INFO - 'talos_options': ['--disable-e10s'], 02:48:08 INFO - 'tests': ['damp', 'tps']}, 02:48:08 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 02:48:08 INFO - 'tests': ['damp', 'tps']}, 02:48:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:48:08 INFO - 'tests': ['dromaeo_dom']}, 02:48:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:48:08 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:48:08 INFO - 'tests': ['basic_compositor_video']}, 02:48:08 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:48:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:48:08 INFO - 'tests': ['a11yr', 02:48:08 INFO - 'ts_paint', 02:48:08 INFO - 'tpaint', 02:48:08 INFO - 'sessionrestore', 02:48:08 INFO - 'sessionrestore_no_auto_restore', 02:48:08 INFO - 'tabpaint']}, 02:48:08 INFO - 'other-e10s': {'tests': ['a11yr', 02:48:08 INFO - 'ts_paint', 02:48:08 INFO - 'tpaint', 02:48:08 INFO - 'sessionrestore', 02:48:08 INFO - 'sessionrestore_no_auto_restore', 02:48:08 INFO - 'tabpaint']}, 02:48:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:48:08 INFO - 'ts_paint', 02:48:08 INFO - 'tpaint', 02:48:08 INFO - 'sessionrestore', 02:48:08 INFO - 'sessionrestore_no_auto_restore', 02:48:08 INFO - 'tabpaint']}, 02:48:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:48:08 INFO - 'ts_paint', 02:48:08 INFO - 'tpaint', 02:48:08 INFO - 'sessionrestore', 02:48:08 INFO - 'sessionrestore_no_auto_restore', 02:48:08 INFO - 'tabpaint']}, 02:48:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:48:08 INFO - 'tests': ['a11yr', 02:48:08 INFO - 'ts_paint', 02:48:08 INFO - 'tpaint', 02:48:08 INFO - 'sessionrestore', 02:48:08 INFO - 'sessionrestore_no_auto_restore', 02:48:08 INFO - 'tabpaint']}, 02:48:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:48:08 INFO - 'tests': ['a11yr', 02:48:08 INFO - 'ts_paint', 02:48:08 INFO - 'tpaint', 02:48:08 INFO - 'sessionrestore', 02:48:08 INFO - 'sessionrestore_no_auto_restore', 02:48:08 INFO - 'tabpaint']}, 02:48:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:48:08 INFO - 'tests': ['tsvgx', 02:48:08 INFO - 'tsvgr_opacity', 02:48:08 INFO - 'tart', 02:48:08 INFO - 'tscrollx', 02:48:08 INFO - 'cart', 02:48:08 INFO - 'tsvg_static']}, 02:48:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:48:08 INFO - 'tsvgr_opacity', 02:48:08 INFO - 'tart', 02:48:08 INFO - 'tscrollx', 02:48:08 INFO - 'cart', 02:48:08 INFO - 'tsvg_static']}, 02:48:08 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 02:48:08 INFO - 'talos_options': ['--disable-e10s'], 02:48:08 INFO - 'tests': ['tp5o']}, 02:48:08 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 02:48:08 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 02:48:08 INFO - 'talos_options': ['--disable-e10s', 02:48:08 INFO - '--xperf_path', 02:48:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:48:08 INFO - 'tests': ['tp5n']}, 02:48:08 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 02:48:08 INFO - 'talos_options': ['--xperf_path', 02:48:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:48:08 INFO - 'tests': ['tp5n']}}, 02:48:08 INFO - 'talos.zip': {'path': '', 02:48:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:48:08 INFO - Running post-action listener: _resource_record_post_action 02:48:08 INFO - [mozharness: 2017-01-09 10:48:08.630716Z] Finished populate-webroot step (success) 02:48:08 INFO - [mozharness: 2017-01-09 10:48:08.630852Z] Running create-virtualenv step. 02:48:08 INFO - Running pre-action listener: _resource_record_pre_action 02:48:08 INFO - Running main action method: create_virtualenv 02:48:08 INFO - Creating virtualenv /builds/slave/test/build/venv 02:48:08 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 02:48:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:48:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:48:08 INFO - Using real prefix '/tools/python27' 02:48:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:48:09 INFO - Installing distribute.............................................................................................................................................................................................done. 02:48:12 INFO - Installing pip.................done. 02:48:12 INFO - Return code: 0 02:48:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:48:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:48:12 INFO - Reading from file tmpfile_stdout 02:48:12 INFO - Output received: 02:48:12 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) 02:48:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e9dcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9df768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb03d7bda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7bad30>, '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 0x10e6e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7afbe8>, '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.42mKVHTo4y/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.p8bcECqf3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 02:48:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:48:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.42mKVHTo4y/Render', 02:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:12 INFO - 'HOME': '/Users/cltbld', 02:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:12 INFO - 'LOGNAME': 'cltbld', 02:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:12 INFO - 'MOZ_NO_REMOTE': '1', 02:48:12 INFO - 'NO_EM_RESTART': '1', 02:48:12 INFO - 'PAGER': '/bin/cat', 02:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:12 INFO - 'PWD': '/builds/slave/test', 02:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:12 INFO - 'SHELL': '/bin/bash', 02:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p8bcECqf3S/Listeners', 02:48:12 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 02:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:12 INFO - 'USER': 'cltbld', 02:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:12 INFO - 'XPC_FLAGS': '0x0', 02:48:12 INFO - 'XPC_SERVICE_NAME': '0', 02:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:12 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 02:48:12 INFO - Cleaning up... 02:48:12 INFO - Return code: 0 02:48:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e9dcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9df768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb03d7bda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7bad30>, '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 0x10e6e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7afbe8>, '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.42mKVHTo4y/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.p8bcECqf3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 02:48:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:48:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:48:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.42mKVHTo4y/Render', 02:48:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:12 INFO - 'HOME': '/Users/cltbld', 02:48:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:12 INFO - 'LOGNAME': 'cltbld', 02:48:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:12 INFO - 'MOZ_NO_REMOTE': '1', 02:48:12 INFO - 'NO_EM_RESTART': '1', 02:48:12 INFO - 'PAGER': '/bin/cat', 02:48:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:12 INFO - 'PWD': '/builds/slave/test', 02:48:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:12 INFO - 'SHELL': '/bin/bash', 02:48:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p8bcECqf3S/Listeners', 02:48:12 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 02:48:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:12 INFO - 'USER': 'cltbld', 02:48:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:12 INFO - 'XPC_FLAGS': '0x0', 02:48:12 INFO - 'XPC_SERVICE_NAME': '0', 02:48:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:13 INFO - Downloading/unpacking psutil>=3.1.1 02:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:48:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:48:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:48:16 INFO - Installing collected packages: psutil 02:48:16 INFO - Running setup.py install for psutil 02:48:16 INFO - building 'psutil._psutil_osx' extension 02:48:16 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 02:48:17 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 02:48:17 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 02:48:17 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 02:48:17 INFO - building 'psutil._psutil_posix' extension 02:48:17 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 02:48:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:48:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:48:17 INFO - ^ 02:48:17 INFO - 1 warning generated. 02:48:17 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 02:48:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:48:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:48:17 INFO - Successfully installed psutil 02:48:17 INFO - Cleaning up... 02:48:17 INFO - Return code: 0 02:48:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:48: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')]} 02:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48: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')]} 02:48:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e9dcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9df768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb03d7bda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7bad30>, '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 0x10e6e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7afbe8>, '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.42mKVHTo4y/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.p8bcECqf3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 02:48: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:48: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 mozsystemmonitor==0.3 02:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.42mKVHTo4y/Render', 02:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:17 INFO - 'HOME': '/Users/cltbld', 02:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:17 INFO - 'LOGNAME': 'cltbld', 02:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:17 INFO - 'MOZ_NO_REMOTE': '1', 02:48:17 INFO - 'NO_EM_RESTART': '1', 02:48:17 INFO - 'PAGER': '/bin/cat', 02:48:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:17 INFO - 'PWD': '/builds/slave/test', 02:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:17 INFO - 'SHELL': '/bin/bash', 02:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p8bcECqf3S/Listeners', 02:48:17 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 02:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:17 INFO - 'USER': 'cltbld', 02:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:17 INFO - 'XPC_FLAGS': '0x0', 02:48:17 INFO - 'XPC_SERVICE_NAME': '0', 02:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:17 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:48:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:48:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:48:20 INFO - Installing collected packages: mozsystemmonitor 02:48:20 INFO - Running setup.py install for mozsystemmonitor 02:48:20 INFO - Successfully installed mozsystemmonitor 02:48:20 INFO - Cleaning up... 02:48:20 INFO - Return code: 0 02:48:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:48:20 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')]} 02:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:20 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')]} 02:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:20 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 0x10e9dcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9df768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb03d7bda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7bad30>, '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 0x10e6e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7afbe8>, '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.42mKVHTo4y/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.p8bcECqf3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 02:48:20 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 02:48:20 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 02:48:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.42mKVHTo4y/Render', 02:48:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:20 INFO - 'HOME': '/Users/cltbld', 02:48:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:20 INFO - 'LOGNAME': 'cltbld', 02:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:20 INFO - 'MOZ_NO_REMOTE': '1', 02:48:20 INFO - 'NO_EM_RESTART': '1', 02:48:20 INFO - 'PAGER': '/bin/cat', 02:48:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:20 INFO - 'PWD': '/builds/slave/test', 02:48:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:20 INFO - 'SHELL': '/bin/bash', 02:48:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p8bcECqf3S/Listeners', 02:48:20 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 02:48:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:20 INFO - 'USER': 'cltbld', 02:48:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:20 INFO - 'XPC_FLAGS': '0x0', 02:48:20 INFO - 'XPC_SERVICE_NAME': '0', 02:48:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:20 INFO - Downloading/unpacking jsonschema==2.5.1 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48: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 02:48:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:48:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:23 INFO - Downloading functools32-3.2.3-2.tar.gz 02:48:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:48:24 INFO - warning: no files found matching '*.txt' 02:48:24 INFO - no previously-included directories found matching 'build' 02:48:24 INFO - no previously-included directories found matching 'dist' 02:48:24 INFO - no previously-included directories found matching '.git*' 02:48:24 INFO - Installing collected packages: jsonschema, functools32 02:48:24 INFO - Running setup.py install for functools32 02:48:24 INFO - warning: no files found matching '*.txt' 02:48:24 INFO - no previously-included directories found matching 'build' 02:48:24 INFO - no previously-included directories found matching 'dist' 02:48:24 INFO - no previously-included directories found matching '.git*' 02:48:24 INFO - Successfully installed jsonschema functools32 02:48:24 INFO - Cleaning up... 02:48:24 INFO - Return code: 0 02:48:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:24 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 0x10e9dcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9df768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb03d7bda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7bad30>, '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 0x10e6e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7afbe8>, '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.42mKVHTo4y/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.p8bcECqf3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 02:48:24 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 02:48:24 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 02:48:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.42mKVHTo4y/Render', 02:48:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:24 INFO - 'HOME': '/Users/cltbld', 02:48:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:24 INFO - 'LOGNAME': 'cltbld', 02:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:24 INFO - 'MOZ_NO_REMOTE': '1', 02:48:24 INFO - 'NO_EM_RESTART': '1', 02:48:24 INFO - 'PAGER': '/bin/cat', 02:48:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:24 INFO - 'PWD': '/builds/slave/test', 02:48:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:24 INFO - 'SHELL': '/bin/bash', 02:48:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p8bcECqf3S/Listeners', 02:48:24 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 02:48:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:24 INFO - 'USER': 'cltbld', 02:48:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:24 INFO - 'XPC_FLAGS': '0x0', 02:48:24 INFO - 'XPC_SERVICE_NAME': '0', 02:48:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:48:24 INFO - Cleaning up... 02:48:24 INFO - Return code: 0 02:48:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:24 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 0x10e9dcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9df768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb03d7bda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7bad30>, '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 0x10e6e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7afbe8>, '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.42mKVHTo4y/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.p8bcECqf3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 02:48:24 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 02:48:24 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 02:48:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.42mKVHTo4y/Render', 02:48:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:24 INFO - 'HOME': '/Users/cltbld', 02:48:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:24 INFO - 'LOGNAME': 'cltbld', 02:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:24 INFO - 'MOZ_NO_REMOTE': '1', 02:48:24 INFO - 'NO_EM_RESTART': '1', 02:48:24 INFO - 'PAGER': '/bin/cat', 02:48:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:24 INFO - 'PWD': '/builds/slave/test', 02:48:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:24 INFO - 'SHELL': '/bin/bash', 02:48:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p8bcECqf3S/Listeners', 02:48:24 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 02:48:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:24 INFO - 'USER': 'cltbld', 02:48:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:24 INFO - 'XPC_FLAGS': '0x0', 02:48:24 INFO - 'XPC_SERVICE_NAME': '0', 02:48:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:24 INFO - Downloading/unpacking blobuploader==1.2.4 02:48:24 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 02:48:24 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 02:48:24 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 02:48:24 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 02:48:24 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 02:48:24 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 02:48:27 INFO - Downloading blobuploader-1.2.4.tar.gz 02:48:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:48:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:48:27 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 02:48:27 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 02:48:27 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 02:48:27 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 02:48:27 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 02:48:27 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 02:48:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:48:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:48:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:48:28 INFO - Downloading docopt-0.6.1.tar.gz 02:48:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:48:28 INFO - Installing collected packages: blobuploader, requests, docopt 02:48:28 INFO - Running setup.py install for blobuploader 02:48:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:48:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:48:28 INFO - Running setup.py install for requests 02:48:29 INFO - Running setup.py install for docopt 02:48:29 INFO - Successfully installed blobuploader requests docopt 02:48:29 INFO - Cleaning up... 02:48:29 INFO - Return code: 0 02:48:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:29 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 0x10e9dcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9df768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb03d7bda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7bad30>, '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 0x10e6e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7afbe8>, '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.42mKVHTo4y/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.p8bcECqf3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 02:48:29 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 02:48:29 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 02:48:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.42mKVHTo4y/Render', 02:48:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:29 INFO - 'HOME': '/Users/cltbld', 02:48:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:29 INFO - 'LOGNAME': 'cltbld', 02:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:29 INFO - 'MOZ_NO_REMOTE': '1', 02:48:29 INFO - 'NO_EM_RESTART': '1', 02:48:29 INFO - 'PAGER': '/bin/cat', 02:48:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:29 INFO - 'PWD': '/builds/slave/test', 02:48:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:29 INFO - 'SHELL': '/bin/bash', 02:48:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p8bcECqf3S/Listeners', 02:48:29 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 02:48:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:29 INFO - 'USER': 'cltbld', 02:48:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:29 INFO - 'XPC_FLAGS': '0x0', 02:48:29 INFO - 'XPC_SERVICE_NAME': '0', 02:48:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:48:29 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-AarI0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-CNh5A9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-pOFAex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-aU45Ni-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-7x8aOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-TKi3bE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-B_EU2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-Th6wWW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-urAIJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:48:30 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-jfoulx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:48:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-oBhrxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:48:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-6jGdfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:48:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-2FfMYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:48:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-G4wrDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-xE3KwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:48:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-6rxnqJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:48:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:48:31 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-UDHzHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:48:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:48:31 INFO - Running setup.py install for manifestparser 02:48:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:48:31 INFO - Running setup.py install for mozcrash 02:48:32 INFO - Running setup.py install for mozdebug 02:48:32 INFO - Running setup.py install for mozdevice 02:48:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:48:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:48:32 INFO - Running setup.py install for mozfile 02:48:32 INFO - Running setup.py install for mozhttpd 02:48:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:48:32 INFO - Running setup.py install for mozinfo 02:48:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:48:32 INFO - Running setup.py install for mozInstall 02:48:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:48:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:48:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:48:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:48:32 INFO - Running setup.py install for mozleak 02:48:33 INFO - Running setup.py install for mozlog 02:48:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:48:33 INFO - Running setup.py install for moznetwork 02:48:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:48:33 INFO - Running setup.py install for mozprocess 02:48:33 INFO - Running setup.py install for mozprofile 02:48:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:48:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:48:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:48:33 INFO - Running setup.py install for mozrunner 02:48:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:48:33 INFO - Running setup.py install for mozscreenshot 02:48:33 INFO - Running setup.py install for moztest 02:48:34 INFO - Running setup.py install for mozversion 02:48:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:48:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:48:34 INFO - Cleaning up... 02:48:34 INFO - Return code: 0 02:48:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:34 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 0x10e9dcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9df768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb03d7bda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7bad30>, '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 0x10e6e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7afbe8>, '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.42mKVHTo4y/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.p8bcECqf3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 02:48:34 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 02:48:34 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 02:48:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.42mKVHTo4y/Render', 02:48:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:34 INFO - 'HOME': '/Users/cltbld', 02:48:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:34 INFO - 'LOGNAME': 'cltbld', 02:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:34 INFO - 'MOZ_NO_REMOTE': '1', 02:48:34 INFO - 'NO_EM_RESTART': '1', 02:48:34 INFO - 'PAGER': '/bin/cat', 02:48:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:34 INFO - 'PWD': '/builds/slave/test', 02:48:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:34 INFO - 'SHELL': '/bin/bash', 02:48:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p8bcECqf3S/Listeners', 02:48:34 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 02:48:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:34 INFO - 'USER': 'cltbld', 02:48:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:34 INFO - 'XPC_FLAGS': '0x0', 02:48:34 INFO - 'XPC_SERVICE_NAME': '0', 02:48:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:48:34 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-VmqWCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:48:34 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)) 02:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:48:34 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-urpfTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:48:34 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)) 02:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:48:34 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-imZl1W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:48:34 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)) 02:48:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:48:34 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-8DEiL1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:48:35 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)) 02:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:48:35 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-nd6Ush-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:48:35 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)) 02:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:48:35 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-YBNupU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:48:35 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)) 02:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:48:35 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-6Cocyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:48:35 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)) 02:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:48:35 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-YBXF0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:48:35 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)) 02:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:48:35 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-WzB2hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:48:35 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)) 02:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:48:35 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-bfptrQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:48:35 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)) 02:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:48:35 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-kc8D9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:48:35 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)) 02:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:48:35 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-ePKNPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:48:35 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-lFLniY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:48:35 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)) 02:48:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:48:35 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-JDix0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:48:36 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)) 02:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:36 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-TAn4OQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:48:36 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)) 02:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:48:36 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-egCwoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:48:36 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)) 02:48:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:48:36 INFO - Running setup.py (path:/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/pip-IMWPpQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:48:36 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)) 02:48:36 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)) 02:48:36 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)) 02:48:36 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)) 02:48:36 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)) 02:48:36 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)) 02:48:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:48:36 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 02:48:36 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 02:48:36 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 02:48:36 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 02:48:36 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 02:48:36 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 02:48:38 INFO - Downloading blessings-1.6.tar.gz 02:48:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:48:39 INFO - Installing collected packages: blessings 02:48:39 INFO - Running setup.py install for blessings 02:48:39 INFO - Successfully installed blessings 02:48:39 INFO - Cleaning up... 02:48:39 INFO - Return code: 0 02:48:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:48:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:48:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:48:39 INFO - Reading from file tmpfile_stdout 02:48:39 INFO - Current package versions: 02:48:39 INFO - blessings == 1.6 02:48:39 INFO - blobuploader == 1.2.4 02:48:39 INFO - docopt == 0.6.1 02:48:39 INFO - functools32 == 3.2.3-2 02:48:39 INFO - jsonschema == 2.5.1 02:48:39 INFO - manifestparser == 1.1 02:48:39 INFO - mozInstall == 1.12 02:48:39 INFO - mozcrash == 1.0 02:48:39 INFO - mozdebug == 0.1 02:48:39 INFO - mozdevice == 0.49 02:48:39 INFO - mozfile == 1.2 02:48:39 INFO - mozhttpd == 0.7 02:48:39 INFO - mozinfo == 0.9 02:48:39 INFO - mozleak == 0.1 02:48:39 INFO - mozlog == 3.4 02:48:39 INFO - moznetwork == 0.27 02:48:39 INFO - mozprocess == 0.24 02:48:39 INFO - mozprofile == 0.28 02:48:39 INFO - mozrunner == 6.13 02:48:39 INFO - mozscreenshot == 0.1 02:48:39 INFO - mozsystemmonitor == 0.3 02:48:39 INFO - moztest == 0.8 02:48:39 INFO - mozversion == 1.4 02:48:39 INFO - psutil == 3.1.1 02:48:39 INFO - requests == 1.2.3 02:48:39 INFO - wsgiref == 0.1.2 02:48:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:48:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:39 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 0x10e9dcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9df768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb03d7bda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7bad30>, '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 0x10e6e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7afbe8>, '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.42mKVHTo4y/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.p8bcECqf3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 02:48:39 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 02:48:39 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 02:48:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.42mKVHTo4y/Render', 02:48:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:39 INFO - 'HOME': '/Users/cltbld', 02:48:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:39 INFO - 'LOGNAME': 'cltbld', 02:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:39 INFO - 'MOZ_NO_REMOTE': '1', 02:48:39 INFO - 'NO_EM_RESTART': '1', 02:48:39 INFO - 'PAGER': '/bin/cat', 02:48:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:39 INFO - 'PWD': '/builds/slave/test', 02:48:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:39 INFO - 'SHELL': '/bin/bash', 02:48:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p8bcECqf3S/Listeners', 02:48:39 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 02:48:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:39 INFO - 'USER': 'cltbld', 02:48:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:39 INFO - 'XPC_FLAGS': '0x0', 02:48:39 INFO - 'XPC_SERVICE_NAME': '0', 02:48:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:39 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)) 02:48:39 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)) 02:48:39 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)) 02:48:39 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)) 02:48:39 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)) 02:48:39 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)) 02:48:39 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)) 02:48:39 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)) 02:48:39 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)) 02:48:39 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)) 02:48:39 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)) 02:48:39 INFO - Cleaning up... 02:48:39 INFO - Return code: 0 02:48:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:48:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:48:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:48:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:48:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:48:39 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 0x10e9dcb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e9df768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb03d7bda0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e7bad30>, '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 0x10e6e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7afbe8>, '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.42mKVHTo4y/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.p8bcECqf3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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 02:48:39 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 02:48:39 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 02:48:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.42mKVHTo4y/Render', 02:48:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:39 INFO - 'HOME': '/Users/cltbld', 02:48:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:39 INFO - 'LOGNAME': 'cltbld', 02:48:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:39 INFO - 'MOZ_NO_REMOTE': '1', 02:48:39 INFO - 'NO_EM_RESTART': '1', 02:48:39 INFO - 'PAGER': '/bin/cat', 02:48:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:39 INFO - 'PWD': '/builds/slave/test', 02:48:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:39 INFO - 'SHELL': '/bin/bash', 02:48:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p8bcECqf3S/Listeners', 02:48:39 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 02:48:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:39 INFO - 'USER': 'cltbld', 02:48:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:39 INFO - 'XPC_FLAGS': '0x0', 02:48:39 INFO - 'XPC_SERVICE_NAME': '0', 02:48:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:48:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:48:40 INFO - Cleaning up... 02:48:40 INFO - Return code: 0 02:48:40 INFO - Running post-action listener: _resource_record_post_action 02:48:40 INFO - Running post-action listener: _start_resource_monitoring 02:48:40 INFO - Starting resource monitoring. 02:48:40 INFO - [mozharness: 2017-01-09 10:48:40.177332Z] Finished create-virtualenv step (success) 02:48:40 INFO - [mozharness: 2017-01-09 10:48:40.178284Z] Running install step. 02:48:40 INFO - Running pre-action listener: _resource_record_pre_action 02:48:40 INFO - Running main action method: install 02:48:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:48:40 INFO - Reading from file tmpfile_stdout 02:48:40 INFO - Detecting whether we're running mozinstall >=1.0... 02:48:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:48:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:48:40 INFO - Reading from file tmpfile_stdout 02:48:40 INFO - Output received: 02:48:40 INFO - Usage: mozinstall [options] installer 02:48:40 INFO - Options: 02:48:40 INFO - -h, --help show this help message and exit 02:48:40 INFO - -d DEST, --destination=DEST 02:48:40 INFO - Directory to install application into. [default: 02:48:40 INFO - "/builds/slave/test"] 02:48:40 INFO - --app=APP Application being installed. [default: firefox] 02:48:40 INFO - mkdir: /builds/slave/test/build/application 02:48:40 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'] 02:48:40 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 02:49:02 INFO - Reading from file tmpfile_stdout 02:49:02 INFO - Output received: 02:49:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:49:02 INFO - Running post-action listener: _resource_record_post_action 02:49:02 INFO - [mozharness: 2017-01-09 10:49:02.214797Z] Finished install step (success) 02:49:02 INFO - [mozharness: 2017-01-09 10:49:02.214919Z] Running run-tests step. 02:49:02 INFO - Running pre-action listener: _resource_record_pre_action 02:49:02 INFO - Running main action method: run_tests 02:49:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:49:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:49:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:49:02 INFO - 2017-01-09 02:49:02.229 screenresolution[1896:14112] starting screenresolution argv=screenresolution get 02:49:02 INFO - 2017-01-09 02:49:02.232 screenresolution[1896:14112] Display 0: 1600x1200x32@60 02:49:02 INFO - 2017-01-09 02:49:02.237 screenresolution[1897:14115] starting screenresolution argv=screenresolution list 02:49:02 INFO - Available Modes on Display 0 02:49:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:49:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:49:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:49:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:49:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:49:02 INFO - Intel Iris: 02:49:02 INFO - Chipset Model: Intel Iris 02:49:02 INFO - Type: GPU 02:49:02 INFO - Bus: Built-In 02:49:02 INFO - VRAM (Dynamic, Max): 1536 MB 02:49:02 INFO - Vendor: Intel (0x8086) 02:49:02 INFO - Device ID: 0x0a2e 02:49:02 INFO - Revision ID: 0x0009 02:49:02 INFO - Displays: 02:49:02 INFO - Display: 02:49:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:49:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:49:02 INFO - Display Serial Number: 1600x1200 60 02:49:02 INFO - Main Display: Yes 02:49:02 INFO - Mirror: Off 02:49:02 INFO - Online: Yes 02:49:02 INFO - Rotation: Supported 02:49:02 INFO - Return code: 0 02:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:49:02 INFO - Python 2.7.3 02:49:02 INFO - Return code: 0 02:49:02 INFO - grabbing minidump binary from tooltool 02:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:49:02 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 0x10e7bad30>, '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 0x10e6e1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e7afbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:49:02 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 02:49:02 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 02:49:02 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 02:49:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:49:02 INFO - Return code: 0 02:49:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:49:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:49:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:49:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:49:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:49:02 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/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--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 02:49:02 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/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0006 --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 02:49:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.42mKVHTo4y/Render', 02:49:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:49:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:49:02 INFO - 'HOME': '/Users/cltbld', 02:49:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:49:02 INFO - 'LOGNAME': 'cltbld', 02:49:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:49:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:49:02 INFO - 'MOZ_NO_REMOTE': '1', 02:49:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:49:02 INFO - 'NO_EM_RESTART': '1', 02:49:02 INFO - 'PAGER': '/bin/cat', 02:49:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:49:02 INFO - 'PWD': '/builds/slave/test', 02:49:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:49:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:49:02 INFO - 'SHELL': '/bin/bash', 02:49:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p8bcECqf3S/Listeners', 02:49:02 INFO - 'TMPDIR': '/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/', 02:49:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:49:02 INFO - 'USER': 'cltbld', 02:49:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:49:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:49:02 INFO - 'XPC_FLAGS': '0x0', 02:49:02 INFO - 'XPC_SERVICE_NAME': '0', 02:49:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:49:02 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/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0006', '--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 02:49:02 INFO - mozversion application_buildid: 20170109013307 02:49:02 INFO - mozversion application_changeset: 97896f92f196462e0072f8304b97c29217da1327 02:49:02 INFO - mozversion application_display_name: Nightly 02:49:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:49:02 INFO - mozversion application_name: Firefox 02:49:02 INFO - mozversion application_remotingname: firefox 02:49:02 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 02:49:02 INFO - mozversion application_vendor: Mozilla 02:49:02 INFO - mozversion application_version: 53.0a1 02:49:02 INFO - mozversion platform_buildid: 20170109013307 02:49:02 INFO - mozversion platform_changeset: 97896f92f196462e0072f8304b97c29217da1327 02:49:02 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 02:49:02 INFO - mozversion platform_version: 53.0a1 02:49:02 INFO - using testdate: 1483958942 02:49:02 INFO - actual date: 1483958942 02:49:02 INFO - starting webserver on 'localhost:49377' 02:49:02 INFO - SUITE-START | Running 2 tests 02:49:02 INFO - TEST-START | dromaeo_css 02:49:02 INFO - operating with platform_type : mac_ 02:49:02 INFO - Initialising browser for dromaeo_css test... 02:49:02 INFO - TEST-INFO | started process 1907 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpV64I16/profile http://localhost:49377/getInfo.html) 02:49:03 INFO - PROCESS | 1907 | 1483958943379 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:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 02:49:05 INFO - PROCESS | 1907 | __metrics Screen width/height:1600/1200 02:49:05 INFO - PROCESS | 1907 | colorDepth:24 02:49:05 INFO - PROCESS | 1907 | Browser inner width/height: 1024/689 02:49:05 INFO - PROCESS | 1907 | __metrics 02:49:05 INFO - PROCESS | 1907 | 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" 02:49:05 INFO - PROCESS | 1907 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:49:05 INFO - TEST-INFO | 1907: exit 0 02:49:05 INFO - Browser initialized. 02:49:05 INFO - Running cycle 1/1 for dromaeo_css test... 02:49:05 INFO - TEST-INFO | started process 1909 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpV64I16/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:49:17 INFO - PROCESS | 1909 | RSS: Main: 234864640 02:49:17 INFO - PROCESS | 1909 | 02:52:23 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49377/tests/dromaeo/cssquery-ext.html) 02:52:24 INFO - PROCESS | 1909 | RSS: Main: 352530432 02:52:24 INFO - PROCESS | 1909 | 02:54:06 INFO - PROCESS | 1909 | ************************* 02:54:06 INFO - PROCESS | 1909 | A coding exception was thrown and uncaught in a Task. 02:54:06 INFO - PROCESS | 1909 | 02:54:06 INFO - PROCESS | 1909 | Full message: ReferenceError: fetch is not defined 02:54:06 INFO - PROCESS | 1909 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 02:54:06 INFO - PROCESS | 1909 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 02:54:06 INFO - PROCESS | 1909 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 02:54:06 INFO - PROCESS | 1909 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 02:54:06 INFO - PROCESS | 1909 | TaskImpl@resource://gre/modules/Task.jsm:277:3 02:54:06 INFO - PROCESS | 1909 | asyncFunction@resource://gre/modules/Task.jsm:252:14 02:54:06 INFO - PROCESS | 1909 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 02:54:06 INFO - PROCESS | 1909 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 02:54:06 INFO - PROCESS | 1909 | TaskImpl@resource://gre/modules/Task.jsm:277:3 02:54:06 INFO - PROCESS | 1909 | asyncFunction@resource://gre/modules/Task.jsm:252:14 02:54:06 INFO - PROCESS | 1909 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 02:54:06 INFO - PROCESS | 1909 | 02:54:06 INFO - PROCESS | 1909 | ************************* 02:55:27 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-ext.html (next: http://localhost:49377/tests/dromaeo/cssquery-jquery.html) 02:55:27 INFO - PROCESS | 1909 | RSS: Main: 364556288 02:55:27 INFO - PROCESS | 1909 | 02:58:30 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49377/tests/dromaeo/cssquery-mootools.html) 02:58:30 INFO - PROCESS | 1909 | RSS: Main: 338169856 02:58:30 INFO - PROCESS | 1909 | 03:01:33 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49377/tests/dromaeo/cssquery-prototype.html) 03:01:33 INFO - PROCESS | 1909 | RSS: Main: 343859200 03:01:33 INFO - PROCESS | 1909 | 03:04:37 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49377/tests/dromaeo/cssquery-yui.html) 03:04:37 INFO - PROCESS | 1909 | RSS: Main: 328712192 03:04:37 INFO - PROCESS | 1909 | 03:07:41 INFO - PROCESS | 1909 | Cycle 1(1): loaded http://localhost:49377/tests/dromaeo/cssquery-yui.html (next: http://localhost:49377/tests/dromaeo/cssquery-dojo.html) 03:07:41 INFO - PROCESS | 1909 | RSS: Main: 447275008 03:07:41 INFO - PROCESS | 1909 | 03:07:41 INFO - PROCESS | 1909 | __start_tp_report 03:07:41 INFO - PROCESS | 1909 | _x_x_mozilla_page_load 03:07:41 INFO - PROCESS | 1909 | _x_x_mozilla_page_load_details 03:07:41 INFO - PROCESS | 1909 | |i|pagename|runs| 03:07:41 INFO - PROCESS | 1909 | |0;dojo.html;2762.254191368331;3311.2681294161407;3349.3301339732043;3369.4777309517003;3397.048826328629;5553.972230138844;5597.2723545939025;5598.636088654234;5617.747201375938;5627.296587926509;5620.185073164389;5637.295338082739;5692.402297758737;5712.086066229404;5746.281714785652;5078.568321692661;5096.108181068316;5144.768485418146;5149.922751158736;5166.018456493254;5176.7929282828645;5270.762815673284;5273.578113750891;5276.894462110775;5319.069162896478;5233.267342572043;5233.293505376763;5255.421903590602;5267.604929630274;5276.577873770108;5047.293378926953;5107.463716309809;5112.284280200774;5131.153359695646;5151.716655583941;5069.9746501267255;5111.565516931066;5117.948820511784;5134.05020071288;5148.665336753099;5576.888462230732;5608.49523542879;5619.719014049322;5621.7751289948355;5630.155476678531;6104.004449388181;6123.811611823097;6143.389059649631;6151.89265932132;6169.96915015422;5246.921296180561;6220.377962203788;6280.623162610258;6311.179546658905;6311.558190926637;5737.512311453532;5799.449052340021;5816.621919575214;5822.854428639318;5830.737616807254;6175;6214;6234.220722409699;6245.125682404467;6253.093301471261;5032.622553308516;6119.296280927661;6165.691715414211;6166.229221347332;6172.5679202456295;6082.574219804571;6286;6292.339304373067;6294.339094395114;6300.180976473029;5037.143685573461;6125.6017461851925;6131.223750760734;6133.44798968095;6134.5092392608485;6194.318624951252;6236.158118653923;6237.282712488031;6239.625622462667;6263.404976527223;5021.096202683552;5028.924566131511;5029.5724863386295;5031.723255220998;5045.672031317952;4352.2818734909015;4392.297232442794;4404.163208990281;4418.911621767546;4470.932936005963;5783.65298082108;5797;5813.59304848665;5851.327097383855;5866.530677545786;6164.630122192682;6173.290071641819;6236.438720515175;6245.281792593819;6275.278342990523;5671.858203544944;5754.683492407958;5828.6502809831545;5831.533477321804;5835.970820145957;4373.2128216921255;5232.92150617741;5400;5422.945770542323;5456.045192091368;6287.339829317856;6309.14826498426;6314.494840412756;6315.400036996478;6356.4279214870885;6214.9689251553455;6234.657093859882;6237.688115594293;6242.781502647385;6243.96878015607;6121.148138557471;6200.410960958701;6211.534134939807;6240.282367527792;6257.1240026395435;6102.640737559752;6111.257501106225;6114.540688159484;6117.005394430456;6118.741008805671;6020.187274717857;6023.216981792514;6152.138700582012;6163.445289923929;6177.907331389943;5852.239208902815;5896.115582662464;5937.406259374028;6002.339742628395;6014.0678194879865;5962.165296858357;5969;6014.759409623566;6020.608660437058;6107.144999699956;5777.133429985536;5805.535557155333;5809.680467574324;5891.11633254998;5893.734781934738;5020.648554601289;5996.010658241341;6003.369646187088;6042.607230530001;6056.242969628796;5744.885102298014;5947.494462970696;5950.077737950624;5952.672603006876;5954 03:07:41 INFO - PROCESS | 1909 | |1;ext.html;457.8485109948478;462.2326937284108;489.20015774209156;502.48495292325384;528.2182370092262;30792.38415231694;40950.7952460238;42357.36463953043;42431.57568424315;44418.7779061105;43480.91297717558;43778.78110609442;43863.3420498692;43910;44036.779816100956;8412.579371031454;9265.90734092659;9479.857802132974;9496.762580935463;9507.714768556954;8452.366072544584;8612.655493780241;8627.61175747093;8636.524991125485;11189.104871610292;11219.719507012309;11433.256838305526;11478.770424591503;11481.827772583418;11493.252938559008;11387.601433949814;11448.828267575993;11464.656060318204;11477.827832582518;11489.195756297062;9474.336796424253;11376.976072153464;11379.772404551863;11388.658340249805;11399.03108235801;40992.59007409948;41304.793476032726;41326.58673413257;41349.1730165395;41363;3823.9866435394642;3831.272058308912;3944.6449819516383;4016.959830401717;4046.5548789633117;26679.466410671874;30686.232844178852;31326.37347253065;31396;31823.522647160313;3140.3248301615035;3182.840857957116;3236.4821628539557;3343.8996830095134;3346.130006198406;469255;474016;474976;475366;478462.6076869628;63546;63834.68082659557;64009;64035.67982160059;64064.67967660225;67509.3249067503;67607;67695.3230467689;67755;67853;16376.508704738877;16579.336826527044;16579.751303730212;16580.502584922473;16604.58488537772;12717.982561395067;12773.297468639128;12777.16948398351;12795.680107997374;12796.936015319863;14219.64450888736;14427.567172984827;16022.519324420053;16052.27764750589;16577.363696343302;7123.2191952012;7213.747518836921;7229.770938940393;7263.709451621981;7319.15829679583;704.1303989572873;868.2316150207113;891.741394995444;922.4280945813505;931.0875342164717;25237.11670091538;30068.699313007026;30167.944121955625;30900;30910;1057.5241141486363;1068.7007637861257;1091.9235653504338;1096.2271598523023;1218.5491368193825;14647.974641774974;15309.311081001382;15832.445864394691;15891.947553373815;16204.918975405048;600003;604480;604593;605327;605505;61794.76410471765;61984.69007655023;62017.68991155105;62026;62488.06267905985;1331.187975335045;1370.6093763277418;1377.2562816079405;1380.2339701465783;1413.9575812725634;24258.636120457853;25071.87464062668;28547.28631784222;29670.109896703132;30513.389732205676;52940.4705952951;53464.93070138653;53475.19787203273;53506.197407039705;53561.46438535565;411394.9430252949;412963;413100;413967;414304;24567;25102.874485627453;25222.49555008926;41393.37909931414;41868.162636746485;36692.34884430154;37316.440253395675;44480;46392.072158555966;46645.53354466548;35165.47251791276;36367.45448818217;36860;37968.620313796506;38062.85811425662;30919.454027298994;31926.361472770877;35811.462828057076;35987;37324;35647.465288021216;35668.643313566536;35672.464913025804;42077.15845683008;43190.704278871686;37290.6270937298;38712.41931371088;42493.575064250195;43742.343864841416;43775.34336985012;29384.090034147714;30878.691213087583;31695.841520792248;32341;33054.843080492064 03:07:41 INFO - PROCESS | 1909 | |2;jquery.html;334.29964224948725;343.5945584210632;408.5689597474664;418.81153480933585;420.72022105299976;19751.308704195355;21419.892900535513;21420.304684511957;21737.456563585918;22097.779022209772;20787;21428.46428839276;21626;22277.997490112943;22817.429564260903;4928;5472.398036216013;5526.171074338841;5540;5540.612157149001;3897.4153876918404;4099.836006559734;5426.158945363475;5465.836024919259;5486.890262194754;4123.154753275571;4140.792960351985;6806.285340039299;6865.622390768505;6866.519343645947;6566.474682025474;6675.599464032173;6829.351211634887;6845.281245469254;6852.897206541931;6046.0326347784585;6057.182280392135;6063.575549711522;6064.363241859585;6071.969640151815;25131.24606261815;25786.613200802003;26025.479490410085;26439.339016524547;26815.463690726076;2704.323919020245;2714.945701085987;2723.577845433961;2735.5076086304457;2768.709285525011;10167.339122956984;10328.79342413155;10449.373037617692;10548.47257637115;15437.0737755735;1832.3403574713093;1864.3567969050655;1874.6438176746374;1878.539757759344;1882.632886587116;121434;125242.37378813047;125347;126941.36529317386;127758.3612081943;11191.776164476827;11711.1802173847;11748.353840538852;11810.40947952616;11965;15720.371185152695;15746.976446530938;15765.448209312619;15768.290426930585;15793.526194214191;6852.588844669236;8068.435209535393;8103.797405064921;8699.869501957475;8889.866652000224;8492.108328625529;8562.957185214034;8574.742757717278;8651.740447786577;8761.38670293086;3829.751066180689;4768.928466072943;4770.42754869411;4885.951140488621;4896.216605343128;1815.546113471632;1839.9632007359778;2002.6495363311653;2011.4468521156798;2012.2956965062403;868.6785889220903;944.7921457279388;1006.7936073105143;1044.7440377107505;1077.7251800790748;20139.19443222284;21326.466838329165;21391.46521336979;21403.892980534998;21467.57064858694;909.7627226971314;1008.1078245752561;1051.2431049644397;1089.362722807151;1092.2463500184847;9603.607476915808;10229.846552301722;10575.894241057491;11234.3259404436;11250.831237531444;617417.6906387772;685457;691716;696286;709787.8042439122;121647;144768.27615861854;149603.00793984276;153549;154650.22674886778;1059.2003037706636;1068.6847380022912;1093.3221402730517;1093.8577984861918;1100.3617901617042;19338.70991935094;19753.604927901648;19872.628021835175;20264.392068237976;20555;53235;60034.39965600288;60395.69802150961;61035;63277;64628.67685661541;80291;87899.6815047762;89552.65671014809;90368.54815725879;5786.218860453953;6351.5073957619;7056.576605403692;8271.917280827114;8276.710315139182;13123.950083993066;13142.868571314164;13184.077114602269;13614;13691.315434228449;11492.885071149181;11539.653810385702;11664.183507154417;11916.582919597773;11960.58137965167;11422.143339249424;11446.599369022044;11600.6519804406;11711.648650540497;12072.75854482923;9976.102150806462;10154.136898363722;10157.695269141937;11948.415475357531;11995.580154694544;8351.665933362598;8489.278411334859;8516.276116529916;8717.084706105768;8763.25512331459;8624.870626940476;11258.493367798304;11291.943540282246;11694.356810375511;11717.472713727731 03:07:41 INFO - PROCESS | 1909 | |3;mootools.html;1464.1068947941747;1480.2154857925298;1496.1845794042247;1501.3469140923698;1509.2830905319975;8045.23570260825;8462.06917239104;8467.83064338713;8472.025717042541;8474.23731864132;6193.7832175873855;7676.96161519193;7696.4997275177075;7759.961200194005;8261.50430974142;2091.968620470694;2131.0942849289067;2260.773922607734;2338.4738433852417;2343.9531209375887;2348.013834189636;2363.3737059679197;2365.207655435431;2367.917122900699;2374.8812559371963;2252.2680128958073;2274.1471948019494;2283.5775381554467;2310.4223944013997;2312.560613483441;2265.4902646904397;2273.158931195468;2294.4952110535655;2297;2308.665243539694;2300.344401845483;2301.022065622104;2314.4329639238495;2315.9305220843403;2332.8017118544776;8312.83374332516;8342.916570834335;8418.947631546056;8427.789305267355;8476.830463390697;4984.426790919055;5144.279800827887;5145.30538377318;5155.046316431435;5180.4042535108565;3476.1309672581856;3479.704225140866;3499.3526197653514;3559.75081744278;3582.2119133790784;1183.2013390961154;1197.766435545069;1226.9754604907941;1231.8891299783063;1274.3309762374736;10855.457227138611;10865.40240286784;10966.890331096667;10972.725681857937;11008.449577521173;3924.9215015699524;4305.526392096866;4512.278035514302;4610.170169369479;4693.178693728652;9138.725838224864;9268.212201962773;9281.143771245643;9286.628534858664;9307.90692093084;5182.948170518322;5186.6369354144845;5193.221016847503;5198.012377648235;5210.739463026909;4301.41930839339;4413.691041627119;4727.57451829337;4772.618190544748;4783.7129772213775;3059.709327613873;3548.1484443733316;3616.439451884962;3725.1432170600915;3744.4570537271943;1101.0090918173707;1225.656816091478;1255.6672481792332;1256.4534427524056;1276.763798697244;885.3979294079959;901.5221932375852;921.764949937775;931.6832277025844;932.2075741865323;5700.201971723962;5720.570957178228;5897.557683173779;5974.731137098841;6099.96950015256;952.2762700347647;978.921686265097;1011.8532812742259;1021.2493817044466;1047.1936608811172;3393.185635447475;3599.94600080999;3764.962350376461;3850.595687452777;3943.231069941391;9747.122758951728;10039.749006274755;10093.293469457214;10118.797624047478;10125.949370253102;7807.453478256582;9716.076972687584;9841.901580984098;9841.950790246146;10099.444530550743;7768.883466748003;7939.603019848984;7944.364450844034;7951.960240198878;7955.602219888982;5504.311961004874;5509.614326997072;5598.496135347838;5629.324481062299;5658;9141.542922853963;9146.359754817366;9340.159385655052;9346.906530934604;9374.5781439834;7145.249748776511;9313.208377288007;9330.253579713471;9421.293402994666;9430.10414010668;7095.787126386217;7206.56760594366;7225.060742103493;7250.238724934017;7253.020842186237;5915.585908986502;7385;7388.224236455095;7777.300042996158;7881.84236315259;6991.545549539263;6998.405135563534;7224.277572242734;7248.891266630899;7292.708291668486;5956.255468066492;7106.040684507525;7150.141982962078;7162.068931038932;7198.5680859148615;6366.808995730135;6398.488120950406;6422.587030853205;6458.160439142881;6470;4933.087378834927;4972.254161875603;5037.974810126073;5066.189409694432;5200;4977.925331119964;5047.116754567863;5101.897962040813;5182.40402353719;5266 03:07:41 INFO - PROCESS | 1909 | |4;prototype.html;360.31899709548946;363.8471841826433;370.3851606333486;383.65087770129173;384.1012031845481;4423.071155057438;5054.671446355985;5180.300659410974;5218.921716174261;5253.159494480884;4395;4408.6112874444525;5004.5746111580465;5260.710660913648;5286.418493965661;3638.7452878298395;3777.3011992781285;4164.396162556428;4174.956260934774;4189.245935731567;3643.872464463744;3923.8626648067325;3962.9405558916637;4181.623653871151;4197.91604167918;4082.306007978636;4087.9182416351655;4107.466029416187;4141.917161656765;4187.497500299968;3738.626137386253;4073.4500842386196;4096.508418989711;4123.587641235882;4123.711340206187;3079.3995170941675;3669.743117981742;3671.4492826076034;3691.464737613031;3702.5371828521434;4878.438979517366;5051.974740126313;5155.40712818027;5232.843014709565;5303.920441193385;4529.207388706996;4548.499665036843;4552.248878934972;4564.269716845322;4593.724376537419;5493.972530137364;5570.805021824257;5574.442555744434;5592.720363981825;5614.438556144394;3114.595102636643;3354.480055591363;3583.301256255031;3694.963050369489;3820.9808950955066;5272.789088436458;5776.364599894008;5782.508486778629;5861.238039054895;5867.794627188028;3852.5954774748807;3875.922481550353;3876.651101400831;3885.320068987916;3889.8833035008997;3619.1904047976013;3642.4900513928073;3645.3620616392027;3929.6070392960473;3938.3107956107565;3926.3521518949055;4029.71791974559;4236.563975713977;4369;4564.0871825635;4945.67853089548;5422;5608.653293697417;5620.353659329147;5621.943780562142;1163.8661553921409;1210.7396909664465;1217.1236709569096;1268.4545645372577;1273.4269578689627;715.9511315922199;740.1084505582953;745.3440971944763;856.2721686566493;873.5370253765515;4442.3563281585375;4892.363992680928;4930.556249937523;4966.726830024383;4976.651634385631;4964.975175124101;5397.007767814755;5421.1297102499575;5450.727463626803;5511.559075273968;4241.34259189826;4555.886102847456;4757.098170116226;4888.584470319992;4986.002799440126;2521.7100033496017;2528.696556413242;2682.8792704328353;2809.9157025288864;2850.5439129739143;5029.189491782955;5213.3222681051975;5448.591355648342;5579.844682677569;5669.2346533218315;4927.901441971212;5218.504242096995;5460.726963651801;5854.882902341929;5862.325832529228;5488.0243112480275;5935.406459354116;5954.404559544011;5984.3668785734135;6015.849603759941;4335.696501244883;5648.237487939075;5696.401877802937;5702.6293290935955;5789.594728368974;4749.477557468606;4998.500149984972;5685.56525909791;5708.514776244066;5733.39799321082;5755.201566965782;6013.428724271187;6013.759449621966;6041.214642096676;6061.515078793598;3305.405027095146;3837.002379381325;3839.520059992501;3898.7329118036187;3905.0042239228815;5029.01353965189;5713.400092990176;5748.6838223898085;5773.595848290747;5781.595288329777;5299.44355842631;5652.745626446737;5653.049697854233;5667.121596152603;5673.659580425187;4859.416869975626;5026.974865125651;5566.995881482744;5599.776008959596;5680.460356266148;4323.956760432481;5410.3507579090765;5447.645903016291;5464.562834973272;5497.509081733048;5144.948550514597;5303.416624171261;5564.276644036249;5626.1842032905815;5647.887042259214;4597.724126557376;4608.7004344718825;4920.753962301942;5263.34208223972;5487.807926722546 03:07:41 INFO - PROCESS | 1909 | |5;yui.html;738.8928605352221;781.6990458673409;814.6049166154414;826.5701835045776;847.5253857839608;15171.393144274229;15735.763963540541;15914.045157290571;15979.999999999985;16005.519834404957;14192.432302707904;14433.061850979679;14617.049891757028;14738.778918316208;14970.17664028478;891.7413949954522;904.683360823713;913.4382354851751;913.6025828764475;914.6295750221137;683.6615875141812;711.903892974447;889.4840992224526;891.5052146058935;891.6700820696351;751.5866273549553;926.3746970794721;960.8414611589079;963.3160456076195;975.4781192062248;906.3112034853493;949.02724707175;949.7957939043105;954.3844220477753;958.5159494455326;690.3683129936103;694.1600663197537;695.2352412346429;695.7008486350867;697.8115908704622;12252.75494490114;12703.618891433272;12757.425915833717;12758.298293593849;14754.336054877449;14681;14808.62978425548;14856.480023199245;15137.772933406008;15236.314365853563;11436.256643318158;11500.25248358854;11569.016633586156;11815.350155741431;12073.335966521838;3669.4512222393246;3784.7539909906045;3793.165503589206;3798.601146879593;3912.197999410114;105261;109554;111347.44326278397;112336;112353;10318.896811031944;10487.528061237263;11279.548818047351;11510.30938143714;11920.52317907274;24224.394390140387;24642.260732178063;24669;24670.629940550905;24692.38269043288;7778.338841198448;7811.45319827618;7829.843403132019;7876.212378762078;8062.1937806220085;3623.637636236408;4210.663146948237;4225.852095176654;4315.201687687788;4398.966242932972;3350.8827191048194;3411.6417776133644;3511.157322242643;3526.012716439401;3526.9823650882095;648.9774889029811;658.127980425928;662.0484908461158;662.7845950066152;666.8266250774833;551.4265164229157;702.2485940044206;702.7083760239545;704.8414106825924;714.2821464428216;9605.951970240105;10282.537285822156;10311.742206444898;10594.576216951236;10760.569577216824;663.93692599203;675.9053732477457;686.4302628818069;705.8200158959432;713.5825542057954;5008.248762685626;5038.395392552844;5091.8981620367385;5104.515071068243;5115.820946266863;104500;105161.47419263008;106446;107665;110281.89718102876;89735.97735957513;90182.54908725414;90972.54513727389;91119.14295855288;91943.54028229816;65248.67375663186;82172.17827821645;83677.58161209276;85740.57129714561;85979.8505037379;9619.326647134912;9735.036492723902;9744.210730890643;9825.312228144247;9857.950710246403;39256.60743392644;53791.193132102264;55327.17009244784;56021;56072.439275606725;49421.752891235315;70465.23836904118;70695.64652176706;70856.29143708703;72429.27570724225;11718.23831450953;12079.818802717791;12148.635540933787;12199.451024704087;12351.320677362832;11878.940605296919;11934.343611101125;12076.154669173073;12146.574869879512;12182.269063856196;12090.954522738632;12112.939435302767;12117.27296362221;12130.75738485243;12644.3677816107;11907.106966977684;12029.141073806659;12332.568360107352;12573.1198816082;12580.685482863002;11931.343776092399;11956.402179891145;12154.878451215374;12493.937530312654;12528.561500347445;8604.23322683701;9995.100440960348;10078.546465409221;10086.747831304276;10190.541425635716;9289.86065209036;9669.758256043655;11198.38408887519;11566.42167891619;11659.475323610288 03:07:41 INFO - PROCESS | 1909 | __end_tp_report 03:07:41 INFO - PROCESS | 1909 | __start_cc_report 03:07:41 INFO - PROCESS | 1909 | _x_x_mozilla_cycle_collect,1226 03:07:41 INFO - PROCESS | 1909 | __end_cc_report 03:07:41 INFO - PROCESS | 1909 | __startTimestamp1483960061408__endTimestamp 03:07:41 INFO - PROCESS | 1909 | 03:07:41 INFO - PROCESS | 1909 | ------- Summary: start ------- 03:07:41 INFO - PROCESS | 1909 | Number of tests: 6 03:07:41 INFO - PROCESS | 1909 | 03:07:41 INFO - PROCESS | 1909 | [#0] dojo.html Cycles:165 Average:5682.16 Median:5852.24 stddev:652.40 (11.1%) stddev-sans-first:612.87 03:07:41 INFO - PROCESS | 1909 | Values: 2762.3 3311.3 3349.3 3369.5 3397.0 5554.0 5597.3 5598.6 5617.7 5627.3 5620.2 5637.3 5692.4 5712.1 5746.3 5078.6 5096.1 5144.8 5149.9 5166.0 5176.8 5270.8 5273.6 5276.9 5319.1 5233.3 5233.3 5255.4 5267.6 5276.6 5047.3 5107.5 5112.3 5131.2 5151.7 5070.0 5111.6 5117.9 5134.1 5148.7 5576.9 5608.5 5619.7 5621.8 5630.2 6104.0 6123.8 6143.4 6151.9 6170.0 5246.9 6220.4 6280.6 6311.2 6311.6 5737.5 5799.4 5816.6 5822.9 5830.7 6175.0 6214.0 6234.2 6245.1 6253.1 5032.6 6119.3 6165.7 6166.2 6172.6 6082.6 6286.0 6292.3 6294.3 6300.2 5037.1 6125.6 6131.2 6133.4 6134.5 6194.3 6236.2 6237.3 6239.6 6263.4 5021.1 5028.9 5029.6 5031.7 5045.7 4352.3 4392.3 4404.2 4418.9 4470.9 5783.7 5797.0 5813.6 5851.3 5866.5 6164.6 6173.3 6236.4 6245.3 6275.3 5671.9 5754.7 5828.7 5831.5 5836.0 4373.2 5232.9 5400.0 5422.9 5456.0 6287.3 6309.1 6314.5 6315.4 6356.4 6215.0 6234.7 6237.7 6242.8 6244.0 6121.1 6200.4 6211.5 6240.3 6257.1 6102.6 6111.3 6114.5 6117.0 6118.7 6020.2 6023.2 6152.1 6163.4 6177.9 5852.2 5896.1 5937.4 6002.3 6014.1 5962.2 5969.0 6014.8 6020.6 6107.1 5777.1 5805.5 5809.7 5891.1 5893.7 5020.6 5996.0 6003.4 6042.6 6056.2 5744.9 5947.5 5950.1 5952.7 5954.0 03:07:41 INFO - PROCESS | 1909 | 03:07:41 INFO - PROCESS | 1909 | [#1] ext.html Cycles:180 Average:64957.01 Median:3827.63 stddev:134021.65 (3501.4%) stddev-sans-first:134310.12 03:07:41 INFO - PROCESS | 1909 | Values: 457.8 462.2 489.2 502.5 528.2 30792.4 40950.8 42357.4 42431.6 44418.8 43480.9 43778.8 43863.3 43910.0 44036.8 8412.6 9265.9 9479.9 9496.8 9507.7 8452.4 8612.7 8627.6 8636.5 11189.1 11219.7 11433.3 11478.8 11481.8 11493.3 11387.6 11448.8 11464.7 11477.8 11489.2 9474.3 11377.0 11379.8 11388.7 11399.0 40992.6 41304.8 41326.6 41349.2 41363.0 3824.0 3831.3 3944.6 4017.0 4046.6 26679.5 30686.2 31326.4 31396.0 31823.5 3140.3 3182.8 3236.5 3343.9 3346.1 469255.0 474016.0 474976.0 475366.0 478462.6 63546.0 63834.7 64009.0 64035.7 64064.7 67509.3 67607.0 67695.3 67755.0 67853.0 16376.5 16579.3 16579.8 16580.5 16604.6 12718.0 12773.3 12777.2 12795.7 12796.9 14219.6 14427.6 16022.5 16052.3 16577.4 7123.2 7213.7 7229.8 7263.7 7319.2 704.1 868.2 891.7 922.4 931.1 25237.1 30068.7 30167.9 30900.0 30910.0 1057.5 1068.7 1091.9 1096.2 1218.5 14648.0 15309.3 15832.4 15891.9 16204.9 600003.0 604480.0 604593.0 605327.0 605505.0 61794.8 61984.7 62017.7 62026.0 62488.1 1331.2 1370.6 1377.3 1380.2 1414.0 24258.6 25071.9 28547.3 29670.1 30513.4 52940.5 53464.9 53475.2 53506.2 53561.5 411394.9 412963.0 413100.0 413967.0 414304.0 24567.0 25102.9 25222.5 41393.4 41868.2 36692.3 37316.4 44480.0 46392.1 46645.5 35165.5 36367.5 36860.0 37968.6 38062.9 30919.5 31926.4 35811.5 35987.0 37324.0 35647.5 35668.6 35672.5 42077.2 43190.7 37290.6 38712.4 42493.6 43742.3 43775.3 29384.1 30878.7 31695.8 32341.0 33054.8 03:07:41 INFO - PROCESS | 1909 | 03:07:41 INFO - PROCESS | 1909 | [#2] jquery.html Cycles:180 Average:38514.40 Median:21917.62 stddev:113574.56 (518.2%) stddev-sans-first:113856.98 03:07:41 INFO - PROCESS | 1909 | Values: 334.3 343.6 408.6 418.8 420.7 19751.3 21419.9 21420.3 21737.5 22097.8 20787.0 21428.5 21626.0 22278.0 22817.4 4928.0 5472.4 5526.2 5540.0 5540.6 3897.4 4099.8 5426.2 5465.8 5486.9 4123.2 4140.8 6806.3 6865.6 6866.5 6566.5 6675.6 6829.4 6845.3 6852.9 6046.0 6057.2 6063.6 6064.4 6072.0 25131.2 25786.6 26025.5 26439.3 26815.5 2704.3 2714.9 2723.6 2735.5 2768.7 10167.3 10328.8 10449.4 10548.5 15437.1 1832.3 1864.4 1874.6 1878.5 1882.6 121434.0 125242.4 125347.0 126941.4 127758.4 11191.8 11711.2 11748.4 11810.4 11965.0 15720.4 15747.0 15765.4 15768.3 15793.5 6852.6 8068.4 8103.8 8699.9 8889.9 8492.1 8563.0 8574.7 8651.7 8761.4 3829.8 4768.9 4770.4 4886.0 4896.2 1815.5 1840.0 2002.6 2011.4 2012.3 868.7 944.8 1006.8 1044.7 1077.7 20139.2 21326.5 21391.5 21403.9 21467.6 909.8 1008.1 1051.2 1089.4 1092.2 9603.6 10229.8 10575.9 11234.3 11250.8 617417.7 685457.0 691716.0 696286.0 709787.8 121647.0 144768.3 149603.0 153549.0 154650.2 1059.2 1068.7 1093.3 1093.9 1100.4 19338.7 19753.6 19872.6 20264.4 20555.0 53235.0 60034.4 60395.7 61035.0 63277.0 64628.7 80291.0 87899.7 89552.7 90368.5 5786.2 6351.5 7056.6 8271.9 8276.7 13124.0 13142.9 13184.1 13614.0 13691.3 11492.9 11539.7 11664.2 11916.6 11960.6 11422.1 11446.6 11600.7 11711.6 12072.8 9976.1 10154.1 10157.7 11948.4 11995.6 8351.7 8489.3 8516.3 8717.1 8763.3 8624.9 11258.5 11291.9 11694.4 11717.5 03:07:41 INFO - PROCESS | 1909 | 03:07:41 INFO - PROCESS | 1909 | [#3] mootools.html Cycles:180 Average:5391.56 Median:5182.68 stddev:2921.71 (56.4%) stddev-sans-first:2914.99 03:07:41 INFO - PROCESS | 1909 | Values: 1464.1 1480.2 1496.2 1501.3 1509.3 8045.2 8462.1 8467.8 8472.0 8474.2 6193.8 7677.0 7696.5 7760.0 8261.5 2092.0 2131.1 2260.8 2338.5 2344.0 2348.0 2363.4 2365.2 2367.9 2374.9 2252.3 2274.1 2283.6 2310.4 2312.6 2265.5 2273.2 2294.5 2297.0 2308.7 2300.3 2301.0 2314.4 2315.9 2332.8 8312.8 8342.9 8418.9 8427.8 8476.8 4984.4 5144.3 5145.3 5155.0 5180.4 3476.1 3479.7 3499.4 3559.8 3582.2 1183.2 1197.8 1227.0 1231.9 1274.3 10855.5 10865.4 10966.9 10972.7 11008.4 3924.9 4305.5 4512.3 4610.2 4693.2 9138.7 9268.2 9281.1 9286.6 9307.9 5182.9 5186.6 5193.2 5198.0 5210.7 4301.4 4413.7 4727.6 4772.6 4783.7 3059.7 3548.1 3616.4 3725.1 3744.5 1101.0 1225.7 1255.7 1256.5 1276.8 885.4 901.5 921.8 931.7 932.2 5700.2 5720.6 5897.6 5974.7 6100.0 952.3 978.9 1011.9 1021.2 1047.2 3393.2 3599.9 3765.0 3850.6 3943.2 9747.1 10039.7 10093.3 10118.8 10125.9 7807.5 9716.1 9841.9 9842.0 10099.4 7768.9 7939.6 7944.4 7952.0 7955.6 5504.3 5509.6 5598.5 5629.3 5658.0 9141.5 9146.4 9340.2 9346.9 9374.6 7145.2 9313.2 9330.3 9421.3 9430.1 7095.8 7206.6 7225.1 7250.2 7253.0 5915.6 7385.0 7388.2 7777.3 7881.8 6991.5 6998.4 7224.3 7248.9 7292.7 5956.3 7106.0 7150.1 7162.1 7198.6 6366.8 6398.5 6422.6 6458.2 6470.0 4933.1 4972.3 5038.0 5066.2 5200.0 4977.9 5047.1 5101.9 5182.4 5266.0 03:07:41 INFO - PROCESS | 1909 | 03:07:41 INFO - PROCESS | 1909 | [#4] prototype.html Cycles:180 Average:4466.13 Median:4965.85 stddev:1386.25 (27.9%) stddev-sans-first:1355.45 03:07:41 INFO - PROCESS | 1909 | Values: 360.3 363.8 370.4 383.7 384.1 4423.1 5054.7 5180.3 5218.9 5253.2 4395.0 4408.6 5004.6 5260.7 5286.4 3638.7 3777.3 4164.4 4175.0 4189.2 3643.9 3923.9 3962.9 4181.6 4197.9 4082.3 4087.9 4107.5 4141.9 4187.5 3738.6 4073.5 4096.5 4123.6 4123.7 3079.4 3669.7 3671.4 3691.5 3702.5 4878.4 5052.0 5155.4 5232.8 5303.9 4529.2 4548.5 4552.2 4564.3 4593.7 5494.0 5570.8 5574.4 5592.7 5614.4 3114.6 3354.5 3583.3 3695.0 3821.0 5272.8 5776.4 5782.5 5861.2 5867.8 3852.6 3875.9 3876.7 3885.3 3889.9 3619.2 3642.5 3645.4 3929.6 3938.3 3926.4 4029.7 4236.6 4369.0 4564.1 4945.7 5422.0 5608.7 5620.4 5621.9 1163.9 1210.7 1217.1 1268.5 1273.4 716.0 740.1 745.3 856.3 873.5 4442.4 4892.4 4930.6 4966.7 4976.7 4965.0 5397.0 5421.1 5450.7 5511.6 4241.3 4555.9 4757.1 4888.6 4986.0 2521.7 2528.7 2682.9 2809.9 2850.5 5029.2 5213.3 5448.6 5579.8 5669.2 4927.9 5218.5 5460.7 5854.9 5862.3 5488.0 5935.4 5954.4 5984.4 6015.8 4335.7 5648.2 5696.4 5702.6 5789.6 4749.5 4998.5 5685.6 5708.5 5733.4 5755.2 6013.4 6013.8 6041.2 6061.5 3305.4 3837.0 3839.5 3898.7 3905.0 5029.0 5713.4 5748.7 5773.6 5781.6 5299.4 5652.7 5653.0 5667.1 5673.7 4859.4 5027.0 5567.0 5599.8 5680.5 4324.0 5410.4 5447.6 5464.6 5497.5 5144.9 5303.4 5564.3 5626.2 5647.9 4597.7 4608.7 4920.8 5263.3 5487.8 03:07:41 INFO - PROCESS | 1909 | 03:07:41 INFO - PROCESS | 1909 | [#5] yui.html Cycles:180 Average:20715.48 Median:21584.40 stddev:30454.90 (141.1%) stddev-sans-first:30503.40 03:07:41 INFO - PROCESS | 1909 | Values: 738.9 781.7 814.6 826.6 847.5 15171.4 15735.8 15914.0 15980.0 16005.5 14192.4 14433.1 14617.0 14738.8 14970.2 891.7 904.7 913.4 913.6 914.6 683.7 711.9 889.5 891.5 891.7 751.6 926.4 960.8 963.3 975.5 906.3 949.0 949.8 954.4 958.5 690.4 694.2 695.2 695.7 697.8 12252.8 12703.6 12757.4 12758.3 14754.3 14681.0 14808.6 14856.5 15137.8 15236.3 11436.3 11500.3 11569.0 11815.4 12073.3 3669.5 3784.8 3793.2 3798.6 3912.2 105261.0 109554.0 111347.4 112336.0 112353.0 10318.9 10487.5 11279.5 11510.3 11920.5 24224.4 24642.3 24669.0 24670.6 24692.4 7778.3 7811.5 7829.8 7876.2 8062.2 3623.6 4210.7 4225.9 4315.2 4399.0 3350.9 3411.6 3511.2 3526.0 3527.0 649.0 658.1 662.0 662.8 666.8 551.4 702.2 702.7 704.8 714.3 9606.0 10282.5 10311.7 10594.6 10760.6 663.9 675.9 686.4 705.8 713.6 5008.2 5038.4 5091.9 5104.5 5115.8 104500.0 105161.5 106446.0 107665.0 110281.9 89736.0 90182.5 90972.5 91119.1 91943.5 65248.7 82172.2 83677.6 85740.6 85979.9 9619.3 9735.0 9744.2 9825.3 9858.0 39256.6 53791.2 55327.2 56021.0 56072.4 49421.8 70465.2 70695.6 70856.3 72429.3 11718.2 12079.8 12148.6 12199.5 12351.3 11878.9 11934.3 12076.2 12146.6 12182.3 12091.0 12112.9 12117.3 12130.8 12644.4 11907.1 12029.1 12332.6 12573.1 12580.7 11931.3 11956.4 12154.9 12493.9 12528.6 8604.2 9995.1 10078.5 10086.7 10190.5 9289.9 9669.8 11198.4 11566.4 11659.5 03:07:41 INFO - PROCESS | 1909 | -------- Summary: end -------- 03:07:41 INFO - PROCESS | 1909 | 03:07:42 INFO - TEST-INFO | 1909: exit 0 03:07:42 INFO - TEST-OK | dromaeo_css | took 1119262ms 03:07:42 INFO - TEST-START | kraken 03:07:42 INFO - operating with platform_type : mac_ 03:07:42 INFO - Initialising browser for kraken test... 03:07:42 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpvdMl0J/profile http://localhost:49377/getInfo.html) 03:07:42 INFO - PROCESS | 1914 | 1483960062572 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:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 03:07:44 INFO - PROCESS | 1914 | __metrics Screen width/height:1600/1200 03:07:44 INFO - PROCESS | 1914 | colorDepth:24 03:07:44 INFO - PROCESS | 1914 | Browser inner width/height: 1024/689 03:07:44 INFO - PROCESS | 1914 | __metrics 03:07:44 INFO - PROCESS | 1914 | 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" 03:07:44 INFO - PROCESS | 1914 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:07:45 INFO - TEST-INFO | 1914: exit 0 03:07:45 INFO - Browser initialized. 03:07:45 INFO - Running cycle 1/1 for kraken test... 03:07:45 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/T/tmpvdMl0J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:07:57 INFO - PROCESS | 1915 | RSS: Main: 234717184 03:07:57 INFO - PROCESS | 1915 | 03:07:59 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49377/tests/kraken/driver.html?testName=audio-beat-detection) 03:08:00 INFO - PROCESS | 1915 | RSS: Main: 286863360 03:08:00 INFO - PROCESS | 1915 | 03:08:03 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49377/tests/kraken/driver.html?testName=audio-dft) 03:08:04 INFO - PROCESS | 1915 | RSS: Main: 286593024 03:08:04 INFO - PROCESS | 1915 | 03:08:07 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49377/tests/kraken/driver.html?testName=audio-fft) 03:08:08 INFO - PROCESS | 1915 | RSS: Main: 380534784 03:08:08 INFO - PROCESS | 1915 | 03:08:10 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49377/tests/kraken/driver.html?testName=audio-oscillator) 03:08:10 INFO - PROCESS | 1915 | RSS: Main: 281415680 03:08:10 INFO - PROCESS | 1915 | 03:08:12 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49377/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:08:13 INFO - PROCESS | 1915 | RSS: Main: 285220864 03:08:13 INFO - PROCESS | 1915 | 03:08:16 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49377/tests/kraken/driver.html?testName=imaging-darkroom) 03:08:16 INFO - PROCESS | 1915 | RSS: Main: 327655424 03:08:16 INFO - PROCESS | 1915 | 03:08:19 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49377/tests/kraken/driver.html?testName=imaging-desaturate) 03:08:20 INFO - PROCESS | 1915 | RSS: Main: 329371648 03:08:20 INFO - PROCESS | 1915 | 03:08:22 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49377/tests/kraken/driver.html?testName=json-parse-financial) 03:08:23 INFO - PROCESS | 1915 | RSS: Main: 329191424 03:08:23 INFO - PROCESS | 1915 | 03:08:25 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49377/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:08:25 INFO - PROCESS | 1915 | RSS: Main: 303517696 03:08:25 INFO - PROCESS | 1915 | 03:08:27 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:08:28 INFO - PROCESS | 1915 | RSS: Main: 304455680 03:08:28 INFO - PROCESS | 1915 | 03:08:30 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:08:31 INFO - PROCESS | 1915 | RSS: Main: 308555776 03:08:31 INFO - PROCESS | 1915 | 03:08:34 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:08:35 INFO - PROCESS | 1915 | RSS: Main: 325410816 03:08:35 INFO - PROCESS | 1915 | 03:08:38 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:08:38 INFO - PROCESS | 1915 | RSS: Main: 319467520 03:08:38 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | Cycle 1(1): loaded http://localhost:49377/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49377/tests/kraken/driver.html?testName=ai-astar) 03:08:40 INFO - PROCESS | 1915 | RSS: Main: 363405312 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | __start_tp_report 03:08:40 INFO - PROCESS | 1915 | _x_x_mozilla_page_load 03:08:40 INFO - PROCESS | 1915 | _x_x_mozilla_page_load_details 03:08:40 INFO - PROCESS | 1915 | |i|pagename|runs| 03:08:40 INFO - PROCESS | 1915 | |0;ai-astar;114;113;114;113;114;114;113;114;114;113 03:08:40 INFO - PROCESS | 1915 | |1;audio-beat-detection;153;140;139;144;118;106;108;95;150;139 03:08:40 INFO - PROCESS | 1915 | |2;audio-dft;185;186;185;184;188;256;184;186;185;183 03:08:40 INFO - PROCESS | 1915 | |3;audio-fft;79;59;59;87;58;60;58;58;74;58 03:08:40 INFO - PROCESS | 1915 | |4;audio-oscillator;91;69;87;70;69;68;85;70;68;84 03:08:40 INFO - PROCESS | 1915 | |5;imaging-gaussian-blur;87;85;87;84;88;84;87;85;86;90 03:08:40 INFO - PROCESS | 1915 | |6;imaging-darkroom;96;95;96;98;95;97;96;99;94;96 03:08:40 INFO - PROCESS | 1915 | |7;imaging-desaturate;87;88;87;85;87;84;85;86;87;95 03:08:40 INFO - PROCESS | 1915 | |8;json-parse-financial;56;56;54;53;54;55;54;54;54;54 03:08:40 INFO - PROCESS | 1915 | |9;json-stringify-tinderbox;65;75;80;73;75;71;71;72;70;70 03:08:40 INFO - PROCESS | 1915 | |10;stanford-crypto-aes;99;97;105;109;90;86;82;87;116;107 03:08:40 INFO - PROCESS | 1915 | |11;stanford-crypto-ccm;151;157;155;130;131;161;152;148;205;161 03:08:40 INFO - PROCESS | 1915 | |12;stanford-crypto-pbkdf2;172;173;172;171;173;172;172;172;172;171 03:08:40 INFO - PROCESS | 1915 | |13;stanford-crypto-sha256-iterative;68;67;67;67;71;82;85;86;79;66 03:08:40 INFO - PROCESS | 1915 | __end_tp_report 03:08:40 INFO - PROCESS | 1915 | __start_cc_report 03:08:40 INFO - PROCESS | 1915 | _x_x_mozilla_cycle_collect,2093 03:08:40 INFO - PROCESS | 1915 | __end_cc_report 03:08:40 INFO - PROCESS | 1915 | __startTimestamp1483960120831__endTimestamp 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | ------- Summary: start ------- 03:08:40 INFO - PROCESS | 1915 | Number of tests: 14 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | [#0] ai-astar Cycles:10 Average:113.60 Median:114.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 03:08:40 INFO - PROCESS | 1915 | Values: 114.0 113.0 114.0 113.0 114.0 114.0 113.0 114.0 114.0 113.0 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:142.00 stddev:20.57 (14.5%) stddev-sans-first:19.94 03:08:40 INFO - PROCESS | 1915 | Values: 153.0 140.0 139.0 144.0 118.0 106.0 108.0 95.0 150.0 139.0 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | [#2] audio-dft Cycles:10 Average:192.20 Median:185.50 stddev:22.46 (12.1%) stddev-sans-first:23.67 03:08:40 INFO - PROCESS | 1915 | Values: 185.0 186.0 185.0 184.0 188.0 256.0 184.0 186.0 185.0 183.0 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | [#3] audio-fft Cycles:10 Average:65.00 Median:59.50 stddev:10.82 (18.2%) stddev-sans-first:10.22 03:08:40 INFO - PROCESS | 1915 | Values: 79.0 59.0 59.0 87.0 58.0 60.0 58.0 58.0 74.0 58.0 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | [#4] audio-oscillator Cycles:10 Average:76.10 Median:77.00 stddev:9.36 (12.2%) stddev-sans-first:8.23 03:08:40 INFO - PROCESS | 1915 | Values: 91.0 69.0 87.0 70.0 69.0 68.0 85.0 70.0 68.0 84.0 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:1.89 (2.2%) stddev-sans-first:1.99 03:08:40 INFO - PROCESS | 1915 | Values: 87.0 85.0 87.0 84.0 88.0 84.0 87.0 85.0 86.0 90.0 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | [#6] imaging-darkroom Cycles:10 Average:96.20 Median:96.00 stddev:1.48 (1.5%) stddev-sans-first:1.56 03:08:40 INFO - PROCESS | 1915 | Values: 96.0 95.0 96.0 98.0 95.0 97.0 96.0 99.0 94.0 96.0 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | [#7] imaging-desaturate Cycles:10 Average:87.10 Median:87.00 stddev:3.03 (3.5%) stddev-sans-first:3.22 03:08:40 INFO - PROCESS | 1915 | Values: 87.0 88.0 87.0 85.0 87.0 84.0 85.0 86.0 87.0 95.0 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.00 stddev:0.97 (1.8%) stddev-sans-first:0.83 03:08:40 INFO - PROCESS | 1915 | Values: 56.0 56.0 54.0 53.0 54.0 55.0 54.0 54.0 54.0 54.0 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:72.50 stddev:3.97 (5.5%) stddev-sans-first:3.24 03:08:40 INFO - PROCESS | 1915 | Values: 65.0 75.0 80.0 73.0 75.0 71.0 71.0 72.0 70.0 70.0 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | [#10] stanford-crypto-aes Cycles:10 Average:97.80 Median:86.50 stddev:11.36 (13.1%) stddev-sans-first:12.04 03:08:40 INFO - PROCESS | 1915 | Values: 99.0 97.0 105.0 109.0 90.0 86.0 82.0 87.0 116.0 107.0 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | [#11] stanford-crypto-ccm Cycles:10 Average:155.10 Median:156.00 stddev:20.69 (13.3%) stddev-sans-first:21.89 03:08:40 INFO - PROCESS | 1915 | Values: 151.0 157.0 155.0 130.0 131.0 161.0 152.0 148.0 205.0 161.0 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.00 Median:172.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 03:08:40 INFO - PROCESS | 1915 | Values: 172.0 173.0 172.0 171.0 173.0 172.0 172.0 172.0 172.0 171.0 03:08:40 INFO - PROCESS | 1915 | 03:08:40 INFO - PROCESS | 1915 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.80 Median:75.00 stddev:8.23 (11.0%) stddev-sans-first:8.46 03:08:40 INFO - PROCESS | 1915 | Values: 68.0 67.0 67.0 67.0 71.0 82.0 85.0 86.0 79.0 66.0 03:08:40 INFO - PROCESS | 1915 | -------- Summary: end -------- 03:08:40 INFO - PROCESS | 1915 | 03:08:41 INFO - TEST-INFO | 1915: exit 0 03:08:41 INFO - TEST-OK | kraken | took 59666ms 03:08:41 INFO - SUITE-END | took 1178s 03:08:41 INFO - Completed test suite (00:19:39) 03:08:41 INFO - javascript benchmark 03:08:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2762.254191368331, 3311.2681294161407, 3349.3301339732043, 3369.4777309517003, 3397.048826328629, 5553.972230138844, 5597.2723545939025, 5598.636088654234, 5617.747201375938, 5627.296587926509, 5620.185073164389, 5637.295338082739, 5692.402297758737, 5712.086066229404, 5746.281714785652, 5078.568321692661, 5096.108181068316, 5144.768485418146, 5149.922751158736, 5166.018456493254, 5176.7929282828645, 5270.762815673284, 5273.578113750891, 5276.894462110775, 5319.069162896478, 5233.267342572043, 5233.293505376763, 5255.421903590602, 5267.604929630274, 5276.577873770108, 5047.293378926953, 5107.463716309809, 5112.284280200774, 5131.153359695646, 5151.716655583941, 5069.9746501267255, 5111.565516931066, 5117.948820511784, 5134.05020071288, 5148.665336753099, 5576.888462230732, 5608.49523542879, 5619.719014049322, 5621.7751289948355, 5630.155476678531, 6104.004449388181, 6123.811611823097, 6143.389059649631, 6151.89265932132, 6169.96915015422, 5246.921296180561, 6220.377962203788, 6280.623162610258, 6311.179546658905, 6311.558190926637, 5737.512311453532, 5799.449052340021, 5816.621919575214, 5822.854428639318, 5830.737616807254, 6175.0, 6214.0, 6234.220722409699, 6245.125682404467, 6253.093301471261, 5032.622553308516, 6119.296280927661, 6165.691715414211, 6166.229221347332, 6172.5679202456295, 6082.574219804571, 6286.0, 6292.339304373067, 6294.339094395114, 6300.180976473029, 5037.143685573461, 6125.6017461851925, 6131.223750760734, 6133.44798968095, 6134.5092392608485, 6194.318624951252, 6236.158118653923, 6237.282712488031, 6239.625622462667, 6263.404976527223, 5021.096202683552, 5028.924566131511, 5029.5724863386295, 5031.723255220998, 5045.672031317952, 4352.2818734909015, 4392.297232442794, 4404.163208990281, 4418.911621767546, 4470.932936005963, 5783.65298082108, 5797.0, 5813.59304848665, 5851.327097383855, 5866.530677545786, 6164.630122192682, 6173.290071641819, 6236.438720515175, 6245.281792593819, 6275.278342990523, 5671.858203544944, 5754.683492407958, 5828.6502809831545, 5831.533477321804, 5835.970820145957, 4373.2128216921255, 5232.92150617741, 5400.0, 5422.945770542323, 5456.045192091368, 6287.339829317856, 6309.14826498426, 6314.494840412756, 6315.400036996478, 6356.4279214870885, 6214.9689251553455, 6234.657093859882, 6237.688115594293, 6242.781502647385, 6243.96878015607, 6121.148138557471, 6200.410960958701, 6211.534134939807, 6240.282367527792, 6257.1240026395435, 6102.640737559752, 6111.257501106225, 6114.540688159484, 6117.005394430456, 6118.741008805671, 6020.187274717857, 6023.216981792514, 6152.138700582012, 6163.445289923929, 6177.907331389943, 5852.239208902815, 5896.115582662464, 5937.406259374028, 6002.339742628395, 6014.0678194879865, 5962.165296858357, 5969.0, 6014.759409623566, 6020.608660437058, 6107.144999699956, 5777.133429985536, 5805.535557155333, 5809.680467574324, 5891.11633254998, 5893.734781934738, 5020.648554601289, 5996.010658241341, 6003.369646187088, 6042.607230530001, 6056.242969628796, 5744.885102298014, 5947.494462970696, 5950.077737950624, 5952.672603006876, 5954.0], "value": 5640.270649029011, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.8485109948478, 462.2326937284108, 489.20015774209156, 502.48495292325384, 528.2182370092262, 30792.38415231694, 40950.7952460238, 42357.36463953043, 42431.57568424315, 44418.7779061105, 43480.91297717558, 43778.78110609442, 43863.3420498692, 43910.0, 44036.779816100956, 8412.579371031454, 9265.90734092659, 9479.857802132974, 9496.762580935463, 9507.714768556954, 8452.366072544584, 8612.655493780241, 8627.61175747093, 8636.524991125485, 11189.104871610292, 11219.719507012309, 11433.256838305526, 11478.770424591503, 11481.827772583418, 11493.252938559008, 11387.601433949814, 11448.828267575993, 11464.656060318204, 11477.827832582518, 11489.195756297062, 9474.336796424253, 11376.976072153464, 11379.772404551863, 11388.658340249805, 11399.03108235801, 40992.59007409948, 41304.793476032726, 41326.58673413257, 41349.1730165395, 41363.0, 3823.9866435394642, 3831.272058308912, 3944.6449819516383, 4016.959830401717, 4046.5548789633117, 26679.466410671874, 30686.232844178852, 31326.37347253065, 31396.0, 31823.522647160313, 3140.3248301615035, 3182.840857957116, 3236.4821628539557, 3343.8996830095134, 3346.130006198406, 469255.0, 474016.0, 474976.0, 475366.0, 478462.6076869628, 63546.0, 63834.68082659557, 64009.0, 64035.67982160059, 64064.67967660225, 67509.3249067503, 67607.0, 67695.3230467689, 67755.0, 67853.0, 16376.508704738877, 16579.336826527044, 16579.751303730212, 16580.502584922473, 16604.58488537772, 12717.982561395067, 12773.297468639128, 12777.16948398351, 12795.680107997374, 12796.936015319863, 14219.64450888736, 14427.567172984827, 16022.519324420053, 16052.27764750589, 16577.363696343302, 7123.2191952012, 7213.747518836921, 7229.770938940393, 7263.709451621981, 7319.15829679583, 704.1303989572873, 868.2316150207113, 891.741394995444, 922.4280945813505, 931.0875342164717, 25237.11670091538, 30068.699313007026, 30167.944121955625, 30900.0, 30910.0, 1057.5241141486363, 1068.7007637861257, 1091.9235653504338, 1096.2271598523023, 1218.5491368193825, 14647.974641774974, 15309.311081001382, 15832.445864394691, 15891.947553373815, 16204.918975405048, 600003.0, 604480.0, 604593.0, 605327.0, 605505.0, 61794.76410471765, 61984.69007655023, 62017.68991155105, 62026.0, 62488.06267905985, 1331.187975335045, 1370.6093763277418, 1377.2562816079405, 1380.2339701465783, 1413.9575812725634, 24258.636120457853, 25071.87464062668, 28547.28631784222, 29670.109896703132, 30513.389732205676, 52940.4705952951, 53464.93070138653, 53475.19787203273, 53506.197407039705, 53561.46438535565, 411394.9430252949, 412963.0, 413100.0, 413967.0, 414304.0, 24567.0, 25102.874485627453, 25222.49555008926, 41393.37909931414, 41868.162636746485, 36692.34884430154, 37316.440253395675, 44480.0, 46392.072158555966, 46645.53354466548, 35165.47251791276, 36367.45448818217, 36860.0, 37968.620313796506, 38062.85811425662, 30919.454027298994, 31926.361472770877, 35811.462828057076, 35987.0, 37324.0, 35647.465288021216, 35668.643313566536, 35672.464913025804, 42077.15845683008, 43190.704278871686, 37290.6270937298, 38712.41931371088, 42493.575064250195, 43742.343864841416, 43775.34336985012, 29384.090034147714, 30878.691213087583, 31695.841520792248, 32341.0, 33054.843080492064], "value": 20177.761733670788, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.29964224948725, 343.5945584210632, 408.5689597474664, 418.81153480933585, 420.72022105299976, 19751.308704195355, 21419.892900535513, 21420.304684511957, 21737.456563585918, 22097.779022209772, 20787.0, 21428.46428839276, 21626.0, 22277.997490112943, 22817.429564260903, 4928.0, 5472.398036216013, 5526.171074338841, 5540.0, 5540.612157149001, 3897.4153876918404, 4099.836006559734, 5426.158945363475, 5465.836024919259, 5486.890262194754, 4123.154753275571, 4140.792960351985, 6806.285340039299, 6865.622390768505, 6866.519343645947, 6566.474682025474, 6675.599464032173, 6829.351211634887, 6845.281245469254, 6852.897206541931, 6046.0326347784585, 6057.182280392135, 6063.575549711522, 6064.363241859585, 6071.969640151815, 25131.24606261815, 25786.613200802003, 26025.479490410085, 26439.339016524547, 26815.463690726076, 2704.323919020245, 2714.945701085987, 2723.577845433961, 2735.5076086304457, 2768.709285525011, 10167.339122956984, 10328.79342413155, 10449.373037617692, 10548.47257637115, 15437.0737755735, 1832.3403574713093, 1864.3567969050655, 1874.6438176746374, 1878.539757759344, 1882.632886587116, 121434.0, 125242.37378813047, 125347.0, 126941.36529317386, 127758.3612081943, 11191.776164476827, 11711.1802173847, 11748.353840538852, 11810.40947952616, 11965.0, 15720.371185152695, 15746.976446530938, 15765.448209312619, 15768.290426930585, 15793.526194214191, 6852.588844669236, 8068.435209535393, 8103.797405064921, 8699.869501957475, 8889.866652000224, 8492.108328625529, 8562.957185214034, 8574.742757717278, 8651.740447786577, 8761.38670293086, 3829.751066180689, 4768.928466072943, 4770.42754869411, 4885.951140488621, 4896.216605343128, 1815.546113471632, 1839.9632007359778, 2002.6495363311653, 2011.4468521156798, 2012.2956965062403, 868.6785889220903, 944.7921457279388, 1006.7936073105143, 1044.7440377107505, 1077.7251800790748, 20139.19443222284, 21326.466838329165, 21391.46521336979, 21403.892980534998, 21467.57064858694, 909.7627226971314, 1008.1078245752561, 1051.2431049644397, 1089.362722807151, 1092.2463500184847, 9603.607476915808, 10229.846552301722, 10575.894241057491, 11234.3259404436, 11250.831237531444, 617417.6906387772, 685457.0, 691716.0, 696286.0, 709787.8042439122, 121647.0, 144768.27615861854, 149603.00793984276, 153549.0, 154650.22674886778, 1059.2003037706636, 1068.6847380022912, 1093.3221402730517, 1093.8577984861918, 1100.3617901617042, 19338.70991935094, 19753.604927901648, 19872.628021835175, 20264.392068237976, 20555.0, 53235.0, 60034.39965600288, 60395.69802150961, 61035.0, 63277.0, 64628.67685661541, 80291.0, 87899.6815047762, 89552.65671014809, 90368.54815725879, 5786.218860453953, 6351.5073957619, 7056.576605403692, 8271.917280827114, 8276.710315139182, 13123.950083993066, 13142.868571314164, 13184.077114602269, 13614.0, 13691.315434228449, 11492.885071149181, 11539.653810385702, 11664.183507154417, 11916.582919597773, 11960.58137965167, 11422.143339249424, 11446.599369022044, 11600.6519804406, 11711.648650540497, 12072.75854482923, 9976.102150806462, 10154.136898363722, 10157.695269141937, 11948.415475357531, 11995.580154694544, 8351.665933362598, 8489.278411334859, 8516.276116529916, 8717.084706105768, 8763.25512331459, 8624.870626940476, 11258.493367798304, 11291.943540282246, 11694.356810375511, 11717.472713727731], "value": 9927.359960345682, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1464.1068947941747, 1480.2154857925298, 1496.1845794042247, 1501.3469140923698, 1509.2830905319975, 8045.23570260825, 8462.06917239104, 8467.83064338713, 8472.025717042541, 8474.23731864132, 6193.7832175873855, 7676.96161519193, 7696.4997275177075, 7759.961200194005, 8261.50430974142, 2091.968620470694, 2131.0942849289067, 2260.773922607734, 2338.4738433852417, 2343.9531209375887, 2348.013834189636, 2363.3737059679197, 2365.207655435431, 2367.917122900699, 2374.8812559371963, 2252.2680128958073, 2274.1471948019494, 2283.5775381554467, 2310.4223944013997, 2312.560613483441, 2265.4902646904397, 2273.158931195468, 2294.4952110535655, 2297.0, 2308.665243539694, 2300.344401845483, 2301.022065622104, 2314.4329639238495, 2315.9305220843403, 2332.8017118544776, 8312.83374332516, 8342.916570834335, 8418.947631546056, 8427.789305267355, 8476.830463390697, 4984.426790919055, 5144.279800827887, 5145.30538377318, 5155.046316431435, 5180.4042535108565, 3476.1309672581856, 3479.704225140866, 3499.3526197653514, 3559.75081744278, 3582.2119133790784, 1183.2013390961154, 1197.766435545069, 1226.9754604907941, 1231.8891299783063, 1274.3309762374736, 10855.457227138611, 10865.40240286784, 10966.890331096667, 10972.725681857937, 11008.449577521173, 3924.9215015699524, 4305.526392096866, 4512.278035514302, 4610.170169369479, 4693.178693728652, 9138.725838224864, 9268.212201962773, 9281.143771245643, 9286.628534858664, 9307.90692093084, 5182.948170518322, 5186.6369354144845, 5193.221016847503, 5198.012377648235, 5210.739463026909, 4301.41930839339, 4413.691041627119, 4727.57451829337, 4772.618190544748, 4783.7129772213775, 3059.709327613873, 3548.1484443733316, 3616.439451884962, 3725.1432170600915, 3744.4570537271943, 1101.0090918173707, 1225.656816091478, 1255.6672481792332, 1256.4534427524056, 1276.763798697244, 885.3979294079959, 901.5221932375852, 921.764949937775, 931.6832277025844, 932.2075741865323, 5700.201971723962, 5720.570957178228, 5897.557683173779, 5974.731137098841, 6099.96950015256, 952.2762700347647, 978.921686265097, 1011.8532812742259, 1021.2493817044466, 1047.1936608811172, 3393.185635447475, 3599.94600080999, 3764.962350376461, 3850.595687452777, 3943.231069941391, 9747.122758951728, 10039.749006274755, 10093.293469457214, 10118.797624047478, 10125.949370253102, 7807.453478256582, 9716.076972687584, 9841.901580984098, 9841.950790246146, 10099.444530550743, 7768.883466748003, 7939.603019848984, 7944.364450844034, 7951.960240198878, 7955.602219888982, 5504.311961004874, 5509.614326997072, 5598.496135347838, 5629.324481062299, 5658.0, 9141.542922853963, 9146.359754817366, 9340.159385655052, 9346.906530934604, 9374.5781439834, 7145.249748776511, 9313.208377288007, 9330.253579713471, 9421.293402994666, 9430.10414010668, 7095.787126386217, 7206.56760594366, 7225.060742103493, 7250.238724934017, 7253.020842186237, 5915.585908986502, 7385.0, 7388.224236455095, 7777.300042996158, 7881.84236315259, 6991.545549539263, 6998.405135563534, 7224.277572242734, 7248.891266630899, 7292.708291668486, 5956.255468066492, 7106.040684507525, 7150.141982962078, 7162.068931038932, 7198.5680859148615, 6366.808995730135, 6398.488120950406, 6422.587030853205, 6458.160439142881, 6470.0, 4933.087378834927, 4972.254161875603, 5037.974810126073, 5066.189409694432, 5200.0, 4977.925331119964, 5047.116754567863, 5101.897962040813, 5182.40402353719, 5266.0], "value": 4405.550232153578, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.31899709548946, 363.8471841826433, 370.3851606333486, 383.65087770129173, 384.1012031845481, 4423.071155057438, 5054.671446355985, 5180.300659410974, 5218.921716174261, 5253.159494480884, 4395.0, 4408.6112874444525, 5004.5746111580465, 5260.710660913648, 5286.418493965661, 3638.7452878298395, 3777.3011992781285, 4164.396162556428, 4174.956260934774, 4189.245935731567, 3643.872464463744, 3923.8626648067325, 3962.9405558916637, 4181.623653871151, 4197.91604167918, 4082.306007978636, 4087.9182416351655, 4107.466029416187, 4141.917161656765, 4187.497500299968, 3738.626137386253, 4073.4500842386196, 4096.508418989711, 4123.587641235882, 4123.711340206187, 3079.3995170941675, 3669.743117981742, 3671.4492826076034, 3691.464737613031, 3702.5371828521434, 4878.438979517366, 5051.974740126313, 5155.40712818027, 5232.843014709565, 5303.920441193385, 4529.207388706996, 4548.499665036843, 4552.248878934972, 4564.269716845322, 4593.724376537419, 5493.972530137364, 5570.805021824257, 5574.442555744434, 5592.720363981825, 5614.438556144394, 3114.595102636643, 3354.480055591363, 3583.301256255031, 3694.963050369489, 3820.9808950955066, 5272.789088436458, 5776.364599894008, 5782.508486778629, 5861.238039054895, 5867.794627188028, 3852.5954774748807, 3875.922481550353, 3876.651101400831, 3885.320068987916, 3889.8833035008997, 3619.1904047976013, 3642.4900513928073, 3645.3620616392027, 3929.6070392960473, 3938.3107956107565, 3926.3521518949055, 4029.71791974559, 4236.563975713977, 4369.0, 4564.0871825635, 4945.67853089548, 5422.0, 5608.653293697417, 5620.353659329147, 5621.943780562142, 1163.8661553921409, 1210.7396909664465, 1217.1236709569096, 1268.4545645372577, 1273.4269578689627, 715.9511315922199, 740.1084505582953, 745.3440971944763, 856.2721686566493, 873.5370253765515, 4442.3563281585375, 4892.363992680928, 4930.556249937523, 4966.726830024383, 4976.651634385631, 4964.975175124101, 5397.007767814755, 5421.1297102499575, 5450.727463626803, 5511.559075273968, 4241.34259189826, 4555.886102847456, 4757.098170116226, 4888.584470319992, 4986.002799440126, 2521.7100033496017, 2528.696556413242, 2682.8792704328353, 2809.9157025288864, 2850.5439129739143, 5029.189491782955, 5213.3222681051975, 5448.591355648342, 5579.844682677569, 5669.2346533218315, 4927.901441971212, 5218.504242096995, 5460.726963651801, 5854.882902341929, 5862.325832529228, 5488.0243112480275, 5935.406459354116, 5954.404559544011, 5984.3668785734135, 6015.849603759941, 4335.696501244883, 5648.237487939075, 5696.401877802937, 5702.6293290935955, 5789.594728368974, 4749.477557468606, 4998.500149984972, 5685.56525909791, 5708.514776244066, 5733.39799321082, 5755.201566965782, 6013.428724271187, 6013.759449621966, 6041.214642096676, 6061.515078793598, 3305.405027095146, 3837.002379381325, 3839.520059992501, 3898.7329118036187, 3905.0042239228815, 5029.01353965189, 5713.400092990176, 5748.6838223898085, 5773.595848290747, 5781.595288329777, 5299.44355842631, 5652.745626446737, 5653.049697854233, 5667.121596152603, 5673.659580425187, 4859.416869975626, 5026.974865125651, 5566.995881482744, 5599.776008959596, 5680.460356266148, 4323.956760432481, 5410.3507579090765, 5447.645903016291, 5464.562834973272, 5497.509081733048, 5144.948550514597, 5303.416624171261, 5564.276644036249, 5626.1842032905815, 5647.887042259214, 4597.724126557376, 4608.7004344718825, 4920.753962301942, 5263.34208223972, 5487.807926722546], "value": 4033.298213144979, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.8928605352221, 781.6990458673409, 814.6049166154414, 826.5701835045776, 847.5253857839608, 15171.393144274229, 15735.763963540541, 15914.045157290571, 15979.999999999985, 16005.519834404957, 14192.432302707904, 14433.061850979679, 14617.049891757028, 14738.778918316208, 14970.17664028478, 891.7413949954522, 904.683360823713, 913.4382354851751, 913.6025828764475, 914.6295750221137, 683.6615875141812, 711.903892974447, 889.4840992224526, 891.5052146058935, 891.6700820696351, 751.5866273549553, 926.3746970794721, 960.8414611589079, 963.3160456076195, 975.4781192062248, 906.3112034853493, 949.02724707175, 949.7957939043105, 954.3844220477753, 958.5159494455326, 690.3683129936103, 694.1600663197537, 695.2352412346429, 695.7008486350867, 697.8115908704622, 12252.75494490114, 12703.618891433272, 12757.425915833717, 12758.298293593849, 14754.336054877449, 14681.0, 14808.62978425548, 14856.480023199245, 15137.772933406008, 15236.314365853563, 11436.256643318158, 11500.25248358854, 11569.016633586156, 11815.350155741431, 12073.335966521838, 3669.4512222393246, 3784.7539909906045, 3793.165503589206, 3798.601146879593, 3912.197999410114, 105261.0, 109554.0, 111347.44326278397, 112336.0, 112353.0, 10318.896811031944, 10487.528061237263, 11279.548818047351, 11510.30938143714, 11920.52317907274, 24224.394390140387, 24642.260732178063, 24669.0, 24670.629940550905, 24692.38269043288, 7778.338841198448, 7811.45319827618, 7829.843403132019, 7876.212378762078, 8062.1937806220085, 3623.637636236408, 4210.663146948237, 4225.852095176654, 4315.201687687788, 4398.966242932972, 3350.8827191048194, 3411.6417776133644, 3511.157322242643, 3526.012716439401, 3526.9823650882095, 648.9774889029811, 658.127980425928, 662.0484908461158, 662.7845950066152, 666.8266250774833, 551.4265164229157, 702.2485940044206, 702.7083760239545, 704.8414106825924, 714.2821464428216, 9605.951970240105, 10282.537285822156, 10311.742206444898, 10594.576216951236, 10760.569577216824, 663.93692599203, 675.9053732477457, 686.4302628818069, 705.8200158959432, 713.5825542057954, 5008.248762685626, 5038.395392552844, 5091.8981620367385, 5104.515071068243, 5115.820946266863, 104500.0, 105161.47419263008, 106446.0, 107665.0, 110281.89718102876, 89735.97735957513, 90182.54908725414, 90972.54513727389, 91119.14295855288, 91943.54028229816, 65248.67375663186, 82172.17827821645, 83677.58161209276, 85740.57129714561, 85979.8505037379, 9619.326647134912, 9735.036492723902, 9744.210730890643, 9825.312228144247, 9857.950710246403, 39256.60743392644, 53791.193132102264, 55327.17009244784, 56021.0, 56072.439275606725, 49421.752891235315, 70465.23836904118, 70695.64652176706, 70856.29143708703, 72429.27570724225, 11718.23831450953, 12079.818802717791, 12148.635540933787, 12199.451024704087, 12351.320677362832, 11878.940605296919, 11934.343611101125, 12076.154669173073, 12146.574869879512, 12182.269063856196, 12090.954522738632, 12112.939435302767, 12117.27296362221, 12130.75738485243, 12644.3677816107, 11907.106966977684, 12029.141073806659, 12332.568360107352, 12573.1198816082, 12580.685482863002, 11931.343776092399, 11956.402179891145, 12154.878451215374, 12493.937530312654, 12528.561500347445, 8604.23322683701, 9995.100440960348, 10078.546465409221, 10086.747831304276, 10190.541425635716, 9289.86065209036, 9669.758256043655, 11198.38408887519, 11566.42167891619, 11659.475323610288], "value": 7479.771452316332, "unit": "score"}], "name": "dromaeo_css", "value": 7290.670110946172, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 113.0, 114.0, 114.0, 113.0, 114.0, 114.0, 113.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 140.0, 139.0, 144.0, 118.0, 106.0, 108.0, 95.0, 150.0, 139.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 185.0, 184.0, 188.0, 256.0, 184.0, 186.0, 185.0, 183.0], "value": 192.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 59.0, 87.0, 58.0, 60.0, 58.0, 58.0, 74.0, 58.0], "value": 65.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 69.0, 87.0, 70.0, 69.0, 68.0, 85.0, 70.0, 68.0, 84.0], "value": 76.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 88.0, 84.0, 87.0, 85.0, 86.0, 90.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 98.0, 95.0, 97.0, 96.0, 99.0, 94.0, 96.0], "value": 96.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 87.0, 85.0, 87.0, 84.0, 85.0, 86.0, 87.0, 95.0], "value": 87.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 54.0, 53.0, 54.0, 55.0, 54.0, 54.0, 54.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 80.0, 73.0, 75.0, 71.0, 71.0, 72.0, 70.0, 70.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 97.0, 105.0, 109.0, 90.0, 86.0, 82.0, 87.0, 116.0, 107.0], "value": 97.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 157.0, 155.0, 130.0, 131.0, 161.0, 152.0, 148.0, 205.0, 161.0], "value": 155.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 172.0, 171.0, 173.0, 172.0, 172.0, 172.0, 172.0, 171.0], "value": 172.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 67.0, 71.0, 82.0, 85.0, 86.0, 79.0, 66.0], "value": 73.8, "unit": "score"}], "name": "kraken", "value": 1471.0, "alertThreshold": 2.0}]} 03:08:42 INFO - Return code: 0 03:08:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:08:42 INFO - # TBPL SUCCESS # 03:08:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:08:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:08:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:08:42 INFO - 2017-01-09 03:08:42.134 screenresolution[1919:16443] starting screenresolution argv=screenresolution get 03:08:42 INFO - 2017-01-09 03:08:42.137 screenresolution[1919:16443] Display 0: 1600x1200x32@60 03:08:42 INFO - 2017-01-09 03:08:42.143 screenresolution[1920:16447] starting screenresolution argv=screenresolution list 03:08:42 INFO - Available Modes on Display 0 03:08:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:08:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:08:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:08:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:08:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:08:42 INFO - Intel Iris: 03:08:42 INFO - Chipset Model: Intel Iris 03:08:42 INFO - Type: GPU 03:08:42 INFO - Bus: Built-In 03:08:42 INFO - VRAM (Dynamic, Max): 1536 MB 03:08:42 INFO - Vendor: Intel (0x8086) 03:08:42 INFO - Device ID: 0x0a2e 03:08:42 INFO - Revision ID: 0x0009 03:08:42 INFO - Displays: 03:08:42 INFO - Display: 03:08:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:08:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:08:42 INFO - Display Serial Number: 1600x1200 60 03:08:42 INFO - Main Display: Yes 03:08:42 INFO - Mirror: Off 03:08:42 INFO - Online: Yes 03:08:42 INFO - Rotation: Supported 03:08:42 INFO - Return code: 0 03:08:42 INFO - Running post-action listener: _resource_record_post_action 03:08:42 INFO - [mozharness: 2017-01-09 11:08:42.376188Z] Finished run-tests step (success) 03:08:42 INFO - Running post-run listener: _resource_record_post_run 03:08:42 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 1081344; Write bytes: 548501504; Read time: 14; Write time: 1774 03:08:42 INFO - TinderboxPrint: CPU usage
53.8% 03:08:42 INFO - TinderboxPrint: I/O read bytes / time
1,081,344 / 14 03:08:42 INFO - TinderboxPrint: I/O write bytes / time
548,501,504 / 1,774 03:08:42 INFO - TinderboxPrint: CPU idle
2,222.6 (46.2%) 03:08:42 INFO - TinderboxPrint: CPU system
966.6 (20.1%) 03:08:42 INFO - TinderboxPrint: CPU user
1,616.5 (33.6%) 03:08:42 INFO - TinderboxPrint: Swap in / out
251,412,480 / 0 03:08:42 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250573312; Write bytes: 250544128; Read time: 18254; Write time: 462 03:08:42 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 1081344; Write bytes: 293728768; Read time: 14; Write time: 1259 03:08:42 INFO - Running post-run listener: _upload_blobber_files 03:08:42 INFO - Blob upload gear active. 03:08:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:08:42 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 03:08:42 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'] 03:08:42 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 03:08:43 INFO - (blobuploader) - INFO - Open directory for files ... 03:08:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:08:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:08:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:44 INFO - (blobuploader) - INFO - Done attempting. 03:08:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:08:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:08:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:45 INFO - (blobuploader) - INFO - Done attempting. 03:08:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 03:08:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 03:08:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:46 INFO - (blobuploader) - INFO - Done attempting. 03:08:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:08:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:08:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:48 INFO - (blobuploader) - INFO - Done attempting. 03:08:48 INFO - (blobuploader) - INFO - Iteration through files over. 03:08:48 INFO - Return code: 0 03:08:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:08:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:08:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10eb5c10837c12e577031b16559a09f12b6207011c3fd546f6bb66be9c503d3899832d8361219d76de72661c49744aa7d7ed2421ca15c5573a6c3ec2e24d2af3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52e5fa2ed2b1b22d361a91422f843900cf5f4909f38c9739e36d35663f8d929f933066cfe2e3970c0368c6d64415224a7782602606a18d0b20967bb5758ae056", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cccf7bd8c7d496a86b2c95277980f9c928a8a899d2bb6d9df1170e121759b8cfeadaad37bc4d5a401c502108e584b944b308b74becc4972dabc8b20ea3df39b7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e73721200978adac03c8ac890366fedb3f29ce5d458ef3636dea9d552b9639a77fa7e477a457640cb5f0974dc83e449d4ec83ceab1d6e0bfce9abf29b020667"} 03:08:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:08:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:08:48 INFO - Contents: 03:08:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/10eb5c10837c12e577031b16559a09f12b6207011c3fd546f6bb66be9c503d3899832d8361219d76de72661c49744aa7d7ed2421ca15c5573a6c3ec2e24d2af3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52e5fa2ed2b1b22d361a91422f843900cf5f4909f38c9739e36d35663f8d929f933066cfe2e3970c0368c6d64415224a7782602606a18d0b20967bb5758ae056", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cccf7bd8c7d496a86b2c95277980f9c928a8a899d2bb6d9df1170e121759b8cfeadaad37bc4d5a401c502108e584b944b308b74becc4972dabc8b20ea3df39b7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e73721200978adac03c8ac890366fedb3f29ce5d458ef3636dea9d552b9639a77fa7e477a457640cb5f0974dc83e449d4ec83ceab1d6e0bfce9abf29b020667"} 03:08:48 INFO - Running post-run listener: copy_logs_to_upload_dir 03:08:48 INFO - Copying logs to upload dir... 03:08:48 INFO - mkdir: /builds/slave/test/build/upload/logs 03:08:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.887758 ========= master_lag: 0.32 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-09 03:08:48.657357) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 03:08:48.661263) ========= 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.42mKVHTo4y/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.p8bcECqf3S/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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/10eb5c10837c12e577031b16559a09f12b6207011c3fd546f6bb66be9c503d3899832d8361219d76de72661c49744aa7d7ed2421ca15c5573a6c3ec2e24d2af3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52e5fa2ed2b1b22d361a91422f843900cf5f4909f38c9739e36d35663f8d929f933066cfe2e3970c0368c6d64415224a7782602606a18d0b20967bb5758ae056", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cccf7bd8c7d496a86b2c95277980f9c928a8a899d2bb6d9df1170e121759b8cfeadaad37bc4d5a401c502108e584b944b308b74becc4972dabc8b20ea3df39b7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e73721200978adac03c8ac890366fedb3f29ce5d458ef3636dea9d552b9639a77fa7e477a457640cb5f0974dc83e449d4ec83ceab1d6e0bfce9abf29b020667"} build_url:https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011150 build_url: 'https://queue.taskcluster.net/v1/task/Yn--7YUyS-WPCx9vxMYuqw/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/10eb5c10837c12e577031b16559a09f12b6207011c3fd546f6bb66be9c503d3899832d8361219d76de72661c49744aa7d7ed2421ca15c5573a6c3ec2e24d2af3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/52e5fa2ed2b1b22d361a91422f843900cf5f4909f38c9739e36d35663f8d929f933066cfe2e3970c0368c6d64415224a7782602606a18d0b20967bb5758ae056", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/cccf7bd8c7d496a86b2c95277980f9c928a8a899d2bb6d9df1170e121759b8cfeadaad37bc4d5a401c502108e584b944b308b74becc4972dabc8b20ea3df39b7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6e73721200978adac03c8ac890366fedb3f29ce5d458ef3636dea9d552b9639a77fa7e477a457640cb5f0974dc83e449d4ec83ceab1d6e0bfce9abf29b020667"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 03:08:48.696660) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 03:08:48.697086) ========= 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.42mKVHTo4y/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.p8bcECqf3S/Listeners TMPDIR=/var/folders/gr/g45hs_z92cb8kdnh3rf3wt0000000w/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.005245 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 03:08:48.745513) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 03:08:48.745825) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 03:08:48.746097) ========= ========= Total master_lag: 0.46 =========