builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0176 starttime: 1484267664.81 results: success (0) buildid: 20170112154018 builduid: 045fd10f615249df8188a946659444d8 revision: 721c28b39396010388fcab52459e98435ee8b33e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 16:34:24.813751) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-12 16:34:24.814222) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 16:34:24.814507) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-12 16:34:24.839771) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 16:34:24.840183) ========= 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.tV1nNWvDi2/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.53gFnKQx1q/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-12 16:34:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.53M=0.001s 2017-01-12 16:34:24 (8.53 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.106273 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 16:34:24.972287) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 16:34:24.972880) ========= 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.tV1nNWvDi2/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.53gFnKQx1q/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.023034 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 16:34:25.021871) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-12 16:34:25.022245) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 721c28b39396010388fcab52459e98435ee8b33e --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 721c28b39396010388fcab52459e98435ee8b33e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tV1nNWvDi2/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.53gFnKQx1q/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-12 16:34:25,103 truncating revision to first 12 chars 2017-01-12 16:34:25,103 Setting DEBUG logging. 2017-01-12 16:34:25,103 attempt 1/10 2017-01-12 16:34:25,103 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/721c28b39396?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-12 16:34:25,890 unpacking tar archive at: mozilla-central-721c28b39396/testing/mozharness/ program finished with exit code 0 elapsedTime=1.038836 ========= master_lag: 0.64 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-12 16:34:26.702488) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 16:34:26.702850) ========= script_repo_revision: 721c28b39396010388fcab52459e98435ee8b33e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-12 16:34:26.703259) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 16:34:26.703531) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-12 16:34:26.724510) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-12 16:34:26.725105) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tV1nNWvDi2/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.53gFnKQx1q/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:34:26 INFO - MultiFileLogger online at 20170112 16:34:26 in /builds/slave/test 16:34:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:34:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:34:26 INFO - {'append_to_log': False, 16:34:26 INFO - 'base_work_dir': '/builds/slave/test', 16:34:26 INFO - 'blob_upload_branch': 'Firefox', 16:34:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:34:26 INFO - 'branch': 'Firefox', 16:34:26 INFO - 'buildbot_json_path': 'buildprops.json', 16:34:26 INFO - 'config_files': ('talos/mac_config.py',), 16:34:26 INFO - 'default_actions': ('clobber', 16:34:26 INFO - 'read-buildbot-config', 16:34:26 INFO - 'download-and-extract', 16:34:26 INFO - 'populate-webroot', 16:34:26 INFO - 'create-virtualenv', 16:34:26 INFO - 'install', 16:34:26 INFO - 'run-tests'), 16:34:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:34:26 INFO - 'download_minidump_stackwalk': True, 16:34:26 INFO - 'download_symbols': 'ondemand', 16:34:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:34:26 INFO - 'tooltool.py': '/tools/tooltool.py', 16:34:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:34:26 INFO - '/tools/misc-python/virtualenv.py')}, 16:34:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:34:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:34:26 INFO - 'installer_path': 'installer.exe', 16:34:26 INFO - 'log_level': 'info', 16:34:26 INFO - 'log_name': 'talos', 16:34:26 INFO - 'log_to_console': True, 16:34:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:34:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:34:26 INFO - 'opt_config_files': (), 16:34:26 INFO - 'pip_index': False, 16:34:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:34:26 INFO - 'cmd': ('bash', 16:34:26 INFO - '-c', 16:34:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:34:26 INFO - 'enabled': True, 16:34:26 INFO - 'halt_on_failure': False, 16:34:26 INFO - 'name': 'check_screen_resolution'},), 16:34:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:34:26 INFO - 'cmd': ('bash', 16:34:26 INFO - '-c', 16:34:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:34:26 INFO - 'enabled': True, 16:34:26 INFO - 'halt_on_failure': False, 16:34:26 INFO - 'name': 'check_screen_resolution'},), 16:34:26 INFO - 'run_cmd_checks_enabled': True, 16:34:26 INFO - 'sps_profile': False, 16:34:26 INFO - 'sps_profile_interval': 0, 16:34:26 INFO - 'suite': 'dromaeojs-e10s', 16:34:26 INFO - 'system_bits': '32', 16:34:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:34:26 INFO - 'title': 't-yosemite-r7-0176', 16:34:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:34:26 INFO - 'use_talos_json': True, 16:34:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:34:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:34:26 INFO - 'work_dir': 'build'} 16:34:26 INFO - [mozharness: 2017-01-13 00:34:26.932724Z] Running clobber step. 16:34:26 INFO - Running pre-action listener: _resource_record_pre_action 16:34:26 INFO - Running main action method: clobber 16:34:26 INFO - rmtree: /builds/slave/test/build 16:34:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:34:29 INFO - Running post-action listener: _resource_record_post_action 16:34:29 INFO - [mozharness: 2017-01-13 00:34:29.184489Z] Finished clobber step (success) 16:34:29 INFO - [mozharness: 2017-01-13 00:34:29.184600Z] Running read-buildbot-config step. 16:34:29 INFO - Running pre-action listener: _resource_record_pre_action 16:34:29 INFO - Running main action method: read_buildbot_config 16:34:29 INFO - Using buildbot properties: 16:34:29 INFO - { 16:34:29 INFO - "project": "", 16:34:29 INFO - "product": "firefox", 16:34:29 INFO - "script_repo_revision": "production", 16:34:29 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:34:29 INFO - "repository": "", 16:34:29 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:34:29 INFO - "buildid": "20170112154018", 16:34:29 INFO - "pgo_build": "False", 16:34:29 INFO - "basedir": "/builds/slave/test", 16:34:29 INFO - "buildnumber": 54, 16:34:29 INFO - "slavename": "t-yosemite-r7-0176", 16:34:29 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 16:34:29 INFO - "platform": "yosemite_r7", 16:34:29 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:34:29 INFO - "branch": "mozilla-central", 16:34:29 INFO - "repo_path": "mozilla-central", 16:34:29 INFO - "revision": "721c28b39396010388fcab52459e98435ee8b33e", 16:34:29 INFO - "stage_platform": "macosx64", 16:34:29 INFO - "builduid": "045fd10f615249df8188a946659444d8", 16:34:29 INFO - "slavebuilddir": "test" 16:34:29 INFO - } 16:34:29 INFO - Found installer url https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 16:34:29 INFO - Running post-action listener: _resource_record_post_action 16:34:29 INFO - [mozharness: 2017-01-13 00:34:29.187622Z] Finished read-buildbot-config step (success) 16:34:29 INFO - [mozharness: 2017-01-13 00:34:29.187729Z] Running download-and-extract step. 16:34:29 INFO - Running pre-action listener: _resource_record_pre_action 16:34:29 INFO - Running main action method: download_and_extract 16:34:29 INFO - mkdir: /builds/slave/test/build/tests 16:34: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')]} 16:34:29 INFO - trying https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 16:34:29 INFO - Downloading https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/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 16:34:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/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 16:34:30 INFO - Downloaded 1199 bytes. 16:34:30 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 16:34:30 INFO - Using the following test package requirements: 16:34:30 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 16:34:30 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:34:30 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 16:34:30 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:34:30 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 16:34:30 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:34:30 INFO - u'jsshell-mac.zip'], 16:34:30 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:34:30 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 16:34:30 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 16:34:30 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:34:30 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 16:34:30 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:34:30 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 16:34:30 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:34:30 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 16:34:30 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 16:34:30 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 16:34:30 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:34:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 16:34:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:34:30 INFO - Fetch https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 16:34:30 INFO - Expected file size: 16314510 16:34:30 INFO - Obtained file size: 16314510 16:34:37 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 16:34:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 16:34:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:34:37 INFO - Fetch https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 16:34:38 INFO - Expected file size: 16314510 16:34:38 INFO - Obtained file size: 16314510 16:34:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 16:34:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:34:44 INFO - Fetch https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 16:34:45 INFO - Expected file size: 14403555 16:34:45 INFO - Obtained file size: 14403555 16:34:46 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')]} 16:34:46 INFO - trying https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:34:46 INFO - Downloading https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 16:34:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/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 16:34:47 INFO - Downloaded 70020049 bytes. 16:34:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:34:47 INFO - mkdir: /builds/slave/test/properties 16:34:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:34:47 INFO - Writing to file /builds/slave/test/properties/build_url 16:34:47 INFO - Contents: 16:34:47 INFO - build_url:https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 16:34:48 INFO - Running post-action listener: _resource_record_post_action 16:34:48 INFO - Running post-action listener: set_extra_try_arguments 16:34:48 INFO - [mozharness: 2017-01-13 00:34:48.649860Z] Finished download-and-extract step (success) 16:34:48 INFO - [mozharness: 2017-01-13 00:34:48.649986Z] Running populate-webroot step. 16:34:48 INFO - Running pre-action listener: _resource_record_pre_action 16:34:48 INFO - Running main action method: populate_webroot 16:34:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:34:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:34:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:34:48 INFO - '--tppagecycles', 16:34:48 INFO - '7'], 16:34:48 INFO - 'tests': ['tsvgm']}}, 16:34:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:34:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:34:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:34:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:34:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:34:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:34:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:34:48 INFO - 'talos_options': ['--disable-e10s'], 16:34:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:34:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:34:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:34:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:34:48 INFO - 'talos_options': ['--disable-e10s'], 16:34:48 INFO - 'tests': ['damp', 'tps']}, 16:34:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:34:48 INFO - 'tests': ['damp', 'tps']}, 16:34:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:34:48 INFO - 'tests': ['dromaeo_dom']}, 16:34:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:34:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:34:48 INFO - 'tests': ['basic_compositor_video']}, 16:34:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:34:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:34:48 INFO - 'tests': ['a11yr', 16:34:48 INFO - 'ts_paint', 16:34:48 INFO - 'tpaint', 16:34:48 INFO - 'sessionrestore', 16:34:48 INFO - 'sessionrestore_no_auto_restore', 16:34:48 INFO - 'tabpaint']}, 16:34:48 INFO - 'other-e10s': {'tests': ['a11yr', 16:34:48 INFO - 'ts_paint', 16:34:48 INFO - 'tpaint', 16:34:48 INFO - 'sessionrestore', 16:34:48 INFO - 'sessionrestore_no_auto_restore', 16:34:48 INFO - 'tabpaint']}, 16:34:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:34:48 INFO - 'ts_paint', 16:34:48 INFO - 'tpaint', 16:34:48 INFO - 'sessionrestore', 16:34:48 INFO - 'sessionrestore_no_auto_restore', 16:34:48 INFO - 'tabpaint']}, 16:34:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:34:48 INFO - 'ts_paint', 16:34:48 INFO - 'tpaint', 16:34:48 INFO - 'sessionrestore', 16:34:48 INFO - 'sessionrestore_no_auto_restore', 16:34:48 INFO - 'tabpaint']}, 16:34:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:34:48 INFO - 'tests': ['a11yr', 16:34:48 INFO - 'ts_paint', 16:34:48 INFO - 'tpaint', 16:34:48 INFO - 'sessionrestore', 16:34:48 INFO - 'sessionrestore_no_auto_restore', 16:34:48 INFO - 'tabpaint']}, 16:34:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:34:48 INFO - 'tests': ['a11yr', 16:34:48 INFO - 'ts_paint', 16:34:48 INFO - 'tpaint', 16:34:48 INFO - 'sessionrestore', 16:34:48 INFO - 'sessionrestore_no_auto_restore', 16:34:48 INFO - 'tabpaint']}, 16:34:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:34:48 INFO - 'tests': ['tsvgx', 16:34:48 INFO - 'tsvgr_opacity', 16:34:48 INFO - 'tart', 16:34:48 INFO - 'tscrollx', 16:34:48 INFO - 'cart', 16:34:48 INFO - 'tsvg_static']}, 16:34:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:34:48 INFO - 'tsvgr_opacity', 16:34:48 INFO - 'tart', 16:34:48 INFO - 'tscrollx', 16:34:48 INFO - 'cart', 16:34:48 INFO - 'tsvg_static']}, 16:34:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:34:48 INFO - 'talos_options': ['--disable-e10s'], 16:34:48 INFO - 'tests': ['tp5o']}, 16:34:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:34:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:34:48 INFO - 'talos_options': ['--disable-e10s', 16:34:48 INFO - '--xperf_path', 16:34:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:34:48 INFO - 'tests': ['tp5n']}, 16:34:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:34:48 INFO - 'talos_options': ['--xperf_path', 16:34:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:34:48 INFO - 'tests': ['tp5n']}}, 16:34:48 INFO - 'talos.zip': {'path': '', 16:34:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:34:48 INFO - Running post-action listener: _resource_record_post_action 16:34:48 INFO - [mozharness: 2017-01-13 00:34:48.668399Z] Finished populate-webroot step (success) 16:34:48 INFO - [mozharness: 2017-01-13 00:34:48.668513Z] Running create-virtualenv step. 16:34:48 INFO - Running pre-action listener: _resource_record_pre_action 16:34:48 INFO - Running main action method: create_virtualenv 16:34:48 INFO - Creating virtualenv /builds/slave/test/build/venv 16:34:48 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 16:34:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:34:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:34:48 INFO - Using real prefix '/tools/python27' 16:34:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:34:49 INFO - Installing distribute.............................................................................................................................................................................................done. 16:34:52 INFO - Installing pip.................done. 16:34:52 INFO - Return code: 0 16:34:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:34:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:34:52 INFO - Reading from file tmpfile_stdout 16:34:52 INFO - Output received: 16:34:52 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) 16:34:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:34:52 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')]} 16:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:52 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')]} 16:34:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:52 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 0x10642eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106431768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530592e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620cd30>, '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 0x106133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106201be8>, '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.tV1nNWvDi2/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.53gFnKQx1q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:34:52 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 16:34:52 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 16:34:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tV1nNWvDi2/Render', 16:34:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:34:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:34:52 INFO - 'HOME': '/Users/cltbld', 16:34:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:34:52 INFO - 'LOGNAME': 'cltbld', 16:34:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:52 INFO - 'MOZ_NO_REMOTE': '1', 16:34:52 INFO - 'NO_EM_RESTART': '1', 16:34:52 INFO - 'PAGER': '/bin/cat', 16:34:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:34:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:52 INFO - 'PWD': '/builds/slave/test', 16:34:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:34:52 INFO - 'SHELL': '/bin/bash', 16:34:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53gFnKQx1q/Listeners', 16:34:52 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:34:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:34:52 INFO - 'USER': 'cltbld', 16:34:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:34:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:34:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:52 INFO - 'XPC_FLAGS': '0x0', 16:34:52 INFO - 'XPC_SERVICE_NAME': '0', 16:34:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:34:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:52 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 16:34:52 INFO - Cleaning up... 16:34:52 INFO - Return code: 0 16:34:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:34:52 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')]} 16:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:52 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')]} 16:34:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:52 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 0x10642eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106431768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530592e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620cd30>, '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 0x106133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106201be8>, '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.tV1nNWvDi2/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.53gFnKQx1q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:34:52 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 16:34:52 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 16:34:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tV1nNWvDi2/Render', 16:34:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:34:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:34:52 INFO - 'HOME': '/Users/cltbld', 16:34:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:34:52 INFO - 'LOGNAME': 'cltbld', 16:34:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:52 INFO - 'MOZ_NO_REMOTE': '1', 16:34:52 INFO - 'NO_EM_RESTART': '1', 16:34:52 INFO - 'PAGER': '/bin/cat', 16:34:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:34:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:52 INFO - 'PWD': '/builds/slave/test', 16:34:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:34:52 INFO - 'SHELL': '/bin/bash', 16:34:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53gFnKQx1q/Listeners', 16:34:52 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:34:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:34:52 INFO - 'USER': 'cltbld', 16:34:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:34:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:34:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:52 INFO - 'XPC_FLAGS': '0x0', 16:34:52 INFO - 'XPC_SERVICE_NAME': '0', 16:34:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:34:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:53 INFO - Downloading/unpacking psutil>=3.1.1 16:34:53 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 16:34:53 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 16:34:53 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 16:34:53 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 16:34:53 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 16:34:53 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 16:34:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:34:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:34:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:34:56 INFO - Installing collected packages: psutil 16:34:56 INFO - Running setup.py install for psutil 16:34:56 INFO - building 'psutil._psutil_osx' extension 16:34:56 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 16:34:57 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 16:34:57 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 16:34:57 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 16:34:57 INFO - building 'psutil._psutil_posix' extension 16:34:57 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 16:34:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:34:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:34:57 INFO - ^ 16:34:57 INFO - 1 warning generated. 16:34:57 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 16:34:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:34:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:34:57 INFO - Successfully installed psutil 16:34:57 INFO - Cleaning up... 16:34:57 INFO - Return code: 0 16:34:57 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:34:57 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')]} 16:34:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:34:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:57 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')]} 16:34:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:34:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:34:57 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 0x10642eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106431768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530592e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620cd30>, '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 0x106133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106201be8>, '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.tV1nNWvDi2/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.53gFnKQx1q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:34:57 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 16:34:57 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 16:34:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tV1nNWvDi2/Render', 16:34:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:34:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:34:57 INFO - 'HOME': '/Users/cltbld', 16:34:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:34:57 INFO - 'LOGNAME': 'cltbld', 16:34:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:34:57 INFO - 'MOZ_NO_REMOTE': '1', 16:34:57 INFO - 'NO_EM_RESTART': '1', 16:34:57 INFO - 'PAGER': '/bin/cat', 16:34:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:34:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:34:57 INFO - 'PWD': '/builds/slave/test', 16:34:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:34:57 INFO - 'SHELL': '/bin/bash', 16:34:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53gFnKQx1q/Listeners', 16:34:57 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:34:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:34:57 INFO - 'USER': 'cltbld', 16:34:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:34:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:34:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:34:57 INFO - 'XPC_FLAGS': '0x0', 16:34:57 INFO - 'XPC_SERVICE_NAME': '0', 16:34:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:34:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:34:57 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:34:57 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 16:34:57 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 16:34:57 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 16:34:57 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 16:34:57 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 16:34:57 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 16:35:00 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:35:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:35:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:35:00 INFO - Installing collected packages: mozsystemmonitor 16:35:00 INFO - Running setup.py install for mozsystemmonitor 16:35:00 INFO - Successfully installed mozsystemmonitor 16:35:00 INFO - Cleaning up... 16:35:00 INFO - Return code: 0 16:35:00 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:35:00 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')]} 16:35:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:00 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')]} 16:35:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:00 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 0x10642eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106431768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530592e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620cd30>, '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 0x106133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106201be8>, '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.tV1nNWvDi2/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.53gFnKQx1q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:35:00 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 16:35:00 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 16:35:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tV1nNWvDi2/Render', 16:35:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:00 INFO - 'HOME': '/Users/cltbld', 16:35:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:00 INFO - 'LOGNAME': 'cltbld', 16:35:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:00 INFO - 'MOZ_NO_REMOTE': '1', 16:35:00 INFO - 'NO_EM_RESTART': '1', 16:35:00 INFO - 'PAGER': '/bin/cat', 16:35:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:00 INFO - 'PWD': '/builds/slave/test', 16:35:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:00 INFO - 'SHELL': '/bin/bash', 16:35:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53gFnKQx1q/Listeners', 16:35:00 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:35:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:00 INFO - 'USER': 'cltbld', 16:35:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:00 INFO - 'XPC_FLAGS': '0x0', 16:35:00 INFO - 'XPC_SERVICE_NAME': '0', 16:35:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:01 INFO - Downloading/unpacking jsonschema==2.5.1 16:35:01 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 16:35:01 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 16:35:01 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 16:35:01 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 16:35:01 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 16:35:01 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 16:35:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:35:03 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:35:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:35:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:35:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:35:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:35:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:35:04 INFO - Downloading functools32-3.2.3-2.tar.gz 16:35:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:35:04 INFO - warning: no files found matching '*.txt' 16:35:04 INFO - no previously-included directories found matching 'build' 16:35:04 INFO - no previously-included directories found matching 'dist' 16:35:04 INFO - no previously-included directories found matching '.git*' 16:35:04 INFO - Installing collected packages: jsonschema, functools32 16:35:04 INFO - Running setup.py install for functools32 16:35:04 INFO - warning: no files found matching '*.txt' 16:35:04 INFO - no previously-included directories found matching 'build' 16:35:04 INFO - no previously-included directories found matching 'dist' 16:35:04 INFO - no previously-included directories found matching '.git*' 16:35:04 INFO - Successfully installed jsonschema functools32 16:35:04 INFO - Cleaning up... 16:35:04 INFO - Return code: 0 16:35:04 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10642eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106431768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530592e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620cd30>, '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 0x106133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106201be8>, '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.tV1nNWvDi2/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.53gFnKQx1q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:35:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:35:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:35:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tV1nNWvDi2/Render', 16:35:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:04 INFO - 'HOME': '/Users/cltbld', 16:35:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:04 INFO - 'LOGNAME': 'cltbld', 16:35:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:04 INFO - 'MOZ_NO_REMOTE': '1', 16:35:04 INFO - 'NO_EM_RESTART': '1', 16:35:04 INFO - 'PAGER': '/bin/cat', 16:35:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:04 INFO - 'PWD': '/builds/slave/test', 16:35:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:04 INFO - 'SHELL': '/bin/bash', 16:35:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53gFnKQx1q/Listeners', 16:35:04 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:35:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:04 INFO - 'USER': 'cltbld', 16:35:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:04 INFO - 'XPC_FLAGS': '0x0', 16:35:04 INFO - 'XPC_SERVICE_NAME': '0', 16:35:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:04 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:35:04 INFO - Cleaning up... 16:35:04 INFO - Return code: 0 16:35:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10642eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106431768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530592e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620cd30>, '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 0x106133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106201be8>, '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.tV1nNWvDi2/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.53gFnKQx1q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:35:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:35:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:35:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tV1nNWvDi2/Render', 16:35:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:04 INFO - 'HOME': '/Users/cltbld', 16:35:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:04 INFO - 'LOGNAME': 'cltbld', 16:35:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:04 INFO - 'MOZ_NO_REMOTE': '1', 16:35:04 INFO - 'NO_EM_RESTART': '1', 16:35:04 INFO - 'PAGER': '/bin/cat', 16:35:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:04 INFO - 'PWD': '/builds/slave/test', 16:35:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:04 INFO - 'SHELL': '/bin/bash', 16:35:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53gFnKQx1q/Listeners', 16:35:04 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:35:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:04 INFO - 'USER': 'cltbld', 16:35:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:04 INFO - 'XPC_FLAGS': '0x0', 16:35:04 INFO - 'XPC_SERVICE_NAME': '0', 16:35:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:04 INFO - Downloading/unpacking blobuploader==1.2.4 16:35:04 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 16:35:04 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 16:35:04 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 16:35:04 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 16:35:04 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 16:35:04 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 16:35:07 INFO - Downloading blobuploader-1.2.4.tar.gz 16:35:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:35:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:35:07 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 16:35:07 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 16:35:07 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 16:35:07 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 16:35:07 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 16:35:07 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 16:35:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:35:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:35:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:35:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:35:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:35:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:35:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:35:08 INFO - Downloading docopt-0.6.1.tar.gz 16:35:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:35:08 INFO - Installing collected packages: blobuploader, requests, docopt 16:35:08 INFO - Running setup.py install for blobuploader 16:35:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:35:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:35:09 INFO - Running setup.py install for requests 16:35:09 INFO - Running setup.py install for docopt 16:35:09 INFO - Successfully installed blobuploader requests docopt 16:35:09 INFO - Cleaning up... 16:35:09 INFO - Return code: 0 16:35:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:35:09 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')]} 16:35:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:09 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')]} 16:35:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:09 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 0x10642eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106431768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530592e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620cd30>, '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 0x106133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106201be8>, '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.tV1nNWvDi2/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.53gFnKQx1q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:35:09 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 16:35:09 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 16:35:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tV1nNWvDi2/Render', 16:35:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:09 INFO - 'HOME': '/Users/cltbld', 16:35:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:09 INFO - 'LOGNAME': 'cltbld', 16:35:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:09 INFO - 'MOZ_NO_REMOTE': '1', 16:35:09 INFO - 'NO_EM_RESTART': '1', 16:35:09 INFO - 'PAGER': '/bin/cat', 16:35:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:09 INFO - 'PWD': '/builds/slave/test', 16:35:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:09 INFO - 'SHELL': '/bin/bash', 16:35:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53gFnKQx1q/Listeners', 16:35:09 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:35:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:09 INFO - 'USER': 'cltbld', 16:35:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:09 INFO - 'XPC_FLAGS': '0x0', 16:35:09 INFO - 'XPC_SERVICE_NAME': '0', 16:35:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:35:10 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-1R5dIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:35:10 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-XN9S2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:35:10 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-UqMM7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:35:10 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-FJ4A3x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:35:10 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-iuUnx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:35:10 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-XOMMd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:35:10 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-bh8OTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:35:10 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-4zaBoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:35:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:35:10 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-B9DYty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:35:11 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-gP7X4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:35:11 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-fUZacY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:35:11 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-qrJEbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:35:11 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-PVA2re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:35:11 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Wum9Oj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:35:11 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-PBlUTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:35:11 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-8oBmJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:35:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:35:11 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-jGWVqk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:35:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:35:11 INFO - Running setup.py install for manifestparser 16:35:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Running setup.py install for mozcrash 16:35:12 INFO - Running setup.py install for mozdebug 16:35:12 INFO - Running setup.py install for mozdevice 16:35:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Running setup.py install for mozfile 16:35:12 INFO - Running setup.py install for mozhttpd 16:35:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Running setup.py install for mozinfo 16:35:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:35:12 INFO - Running setup.py install for mozInstall 16:35:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:35:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:35:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:35:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:35:13 INFO - Running setup.py install for mozleak 16:35:13 INFO - Running setup.py install for mozlog 16:35:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:35:13 INFO - Running setup.py install for moznetwork 16:35:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:35:13 INFO - Running setup.py install for mozprocess 16:35:13 INFO - Running setup.py install for mozprofile 16:35:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:35:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:35:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:35:13 INFO - Running setup.py install for mozrunner 16:35:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:35:14 INFO - Running setup.py install for mozscreenshot 16:35:14 INFO - Running setup.py install for moztest 16:35:14 INFO - Running setup.py install for mozversion 16:35:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:35:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:35:14 INFO - Cleaning up... 16:35:14 INFO - Return code: 0 16:35:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:35:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:14 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 0x10642eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106431768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530592e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620cd30>, '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 0x106133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106201be8>, '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.tV1nNWvDi2/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.53gFnKQx1q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:35:14 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 16:35:14 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 16:35:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tV1nNWvDi2/Render', 16:35:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:14 INFO - 'HOME': '/Users/cltbld', 16:35:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:14 INFO - 'LOGNAME': 'cltbld', 16:35:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:14 INFO - 'MOZ_NO_REMOTE': '1', 16:35:14 INFO - 'NO_EM_RESTART': '1', 16:35:14 INFO - 'PAGER': '/bin/cat', 16:35:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:14 INFO - 'PWD': '/builds/slave/test', 16:35:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:14 INFO - 'SHELL': '/bin/bash', 16:35:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53gFnKQx1q/Listeners', 16:35:14 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:35:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:14 INFO - 'USER': 'cltbld', 16:35:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:14 INFO - 'XPC_FLAGS': '0x0', 16:35:14 INFO - 'XPC_SERVICE_NAME': '0', 16:35:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:35:14 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-tks6ir-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:35:14 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)) 16:35:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:35:14 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-YwBNAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:35:15 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)) 16:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:35:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-dsxKJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:35:15 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)) 16:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:35:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Bzcngt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:35:15 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)) 16:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:35:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-um4YRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:35:15 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)) 16:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:35:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-4VxiFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:35:15 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)) 16:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:35:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-UbIpRH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:35:15 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)) 16:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:35:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-L8LqlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:35:15 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)) 16:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:35:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-mO1Nev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:35:15 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)) 16:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:35:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-UoXmxM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:35:15 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)) 16:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:35:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-gZBiaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:35:15 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)) 16:35:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:35:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-cuyF1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:35:16 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)) 16:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:35:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-vwGIvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:35:16 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)) 16:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:35:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-0xuC8_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:35:16 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)) 16:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:35:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-g3Lws3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:35:16 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)) 16:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:35:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-mrFaH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:35:16 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)) 16:35:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:35:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-AfKK4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:35:16 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)) 16:35:16 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)) 16:35:16 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)) 16:35:16 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)) 16:35:16 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)) 16:35:16 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)) 16:35:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:35:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:35:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:35:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:35:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:35:19 INFO - Downloading blessings-1.6.tar.gz 16:35:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:35:19 INFO - Installing collected packages: blessings 16:35:19 INFO - Running setup.py install for blessings 16:35:19 INFO - Successfully installed blessings 16:35:19 INFO - Cleaning up... 16:35:19 INFO - Return code: 0 16:35:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:35:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:35:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:35:20 INFO - Reading from file tmpfile_stdout 16:35:20 INFO - Current package versions: 16:35:20 INFO - blessings == 1.6 16:35:20 INFO - blobuploader == 1.2.4 16:35:20 INFO - docopt == 0.6.1 16:35:20 INFO - functools32 == 3.2.3-2 16:35:20 INFO - jsonschema == 2.5.1 16:35:20 INFO - manifestparser == 1.1 16:35:20 INFO - mozInstall == 1.12 16:35:20 INFO - mozcrash == 1.0 16:35:20 INFO - mozdebug == 0.1 16:35:20 INFO - mozdevice == 0.49 16:35:20 INFO - mozfile == 1.2 16:35:20 INFO - mozhttpd == 0.7 16:35:20 INFO - mozinfo == 0.9 16:35:20 INFO - mozleak == 0.1 16:35:20 INFO - mozlog == 3.4 16:35:20 INFO - moznetwork == 0.27 16:35:20 INFO - mozprocess == 0.24 16:35:20 INFO - mozprofile == 0.28 16:35:20 INFO - mozrunner == 6.13 16:35:20 INFO - mozscreenshot == 0.1 16:35:20 INFO - mozsystemmonitor == 0.3 16:35:20 INFO - moztest == 0.8 16:35:20 INFO - mozversion == 1.4 16:35:20 INFO - psutil == 3.1.1 16:35:20 INFO - requests == 1.2.3 16:35:20 INFO - wsgiref == 0.1.2 16:35:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:35: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')]} 16:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35: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')]} 16:35:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35:20 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 0x10642eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106431768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530592e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620cd30>, '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 0x106133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106201be8>, '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.tV1nNWvDi2/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.53gFnKQx1q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:35:20 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 16:35:20 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 16:35:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tV1nNWvDi2/Render', 16:35:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:20 INFO - 'HOME': '/Users/cltbld', 16:35:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:20 INFO - 'LOGNAME': 'cltbld', 16:35:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:20 INFO - 'MOZ_NO_REMOTE': '1', 16:35:20 INFO - 'NO_EM_RESTART': '1', 16:35:20 INFO - 'PAGER': '/bin/cat', 16:35:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:20 INFO - 'PWD': '/builds/slave/test', 16:35:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:20 INFO - 'SHELL': '/bin/bash', 16:35:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53gFnKQx1q/Listeners', 16:35:20 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:35:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:20 INFO - 'USER': 'cltbld', 16:35:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:20 INFO - 'XPC_FLAGS': '0x0', 16:35:20 INFO - 'XPC_SERVICE_NAME': '0', 16:35:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:20 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)) 16:35:20 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)) 16:35:20 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)) 16:35:20 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)) 16:35:20 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)) 16:35:20 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)) 16:35:20 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)) 16:35:20 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)) 16:35:20 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)) 16:35:20 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)) 16:35:20 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)) 16:35:20 INFO - Cleaning up... 16:35:20 INFO - Return code: 0 16:35:20 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:35: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')]} 16:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:35:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35: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')]} 16:35:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:35:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:35: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10642eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106431768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa530592e50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10620cd30>, '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 0x106133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106201be8>, '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.tV1nNWvDi2/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.53gFnKQx1q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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 16:35: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'] in /builds/slave/test/build 16:35: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 16:35:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tV1nNWvDi2/Render', 16:35:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:20 INFO - 'HOME': '/Users/cltbld', 16:35:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:20 INFO - 'LOGNAME': 'cltbld', 16:35:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:20 INFO - 'MOZ_NO_REMOTE': '1', 16:35:20 INFO - 'NO_EM_RESTART': '1', 16:35:20 INFO - 'PAGER': '/bin/cat', 16:35:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:20 INFO - 'PWD': '/builds/slave/test', 16:35:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:20 INFO - 'SHELL': '/bin/bash', 16:35:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53gFnKQx1q/Listeners', 16:35:20 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:35:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:20 INFO - 'USER': 'cltbld', 16:35:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:20 INFO - 'XPC_FLAGS': '0x0', 16:35:20 INFO - 'XPC_SERVICE_NAME': '0', 16:35:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:35:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:35:21 INFO - Cleaning up... 16:35:21 INFO - Return code: 0 16:35:21 INFO - Running post-action listener: _resource_record_post_action 16:35:21 INFO - Running post-action listener: _start_resource_monitoring 16:35:21 INFO - Starting resource monitoring. 16:35:21 INFO - [mozharness: 2017-01-13 00:35:21.276414Z] Finished create-virtualenv step (success) 16:35:21 INFO - [mozharness: 2017-01-13 00:35:21.277401Z] Running install step. 16:35:21 INFO - Running pre-action listener: _resource_record_pre_action 16:35:21 INFO - Running main action method: install 16:35:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:35:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:35:21 INFO - Reading from file tmpfile_stdout 16:35:21 INFO - Detecting whether we're running mozinstall >=1.0... 16:35:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:35:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:35:21 INFO - Reading from file tmpfile_stdout 16:35:21 INFO - Output received: 16:35:21 INFO - Usage: mozinstall [options] installer 16:35:21 INFO - Options: 16:35:21 INFO - -h, --help show this help message and exit 16:35:21 INFO - -d DEST, --destination=DEST 16:35:21 INFO - Directory to install application into. [default: 16:35:21 INFO - "/builds/slave/test"] 16:35:21 INFO - --app=APP Application being installed. [default: firefox] 16:35:21 INFO - mkdir: /builds/slave/test/build/application 16:35:21 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'] 16:35:21 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 16:35:44 INFO - Reading from file tmpfile_stdout 16:35:44 INFO - Output received: 16:35:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:35:44 INFO - Running post-action listener: _resource_record_post_action 16:35:44 INFO - [mozharness: 2017-01-13 00:35:44.217790Z] Finished install step (success) 16:35:44 INFO - [mozharness: 2017-01-13 00:35:44.217961Z] Running run-tests step. 16:35:44 INFO - Running pre-action listener: _resource_record_pre_action 16:35:44 INFO - Running main action method: run_tests 16:35:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:35:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:35:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:35:44 INFO - 2017-01-12 16:35:44.232 screenresolution[6869:121048] starting screenresolution argv=screenresolution get 16:35:44 INFO - 2017-01-12 16:35:44.235 screenresolution[6869:121048] Display 0: 1600x1200x32@60 16:35:44 INFO - 2017-01-12 16:35:44.241 screenresolution[6870:121051] starting screenresolution argv=screenresolution list 16:35:44 INFO - Available Modes on Display 0 16:35:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:35:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:35:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:35:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:35:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:35:44 INFO - Intel Iris: 16:35:44 INFO - Chipset Model: Intel Iris 16:35:44 INFO - Type: GPU 16:35:44 INFO - Bus: Built-In 16:35:44 INFO - VRAM (Dynamic, Max): 1536 MB 16:35:44 INFO - Vendor: Intel (0x8086) 16:35:44 INFO - Device ID: 0x0a2e 16:35:44 INFO - Revision ID: 0x0009 16:35:44 INFO - Displays: 16:35:44 INFO - Display: 16:35:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:35:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:35:44 INFO - Display Serial Number: 1600x1200 60 16:35:44 INFO - Main Display: Yes 16:35:44 INFO - Mirror: Off 16:35:44 INFO - Online: Yes 16:35:44 INFO - Rotation: Supported 16:35:44 INFO - Return code: 0 16:35:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:35:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:35:44 INFO - Python 2.7.3 16:35:44 INFO - Return code: 0 16:35:44 INFO - grabbing minidump binary from tooltool 16:35:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:35:44 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 0x10620cd30>, '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 0x106133db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106201be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:35:44 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 16:35:44 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 16:35:44 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 16:35:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:35:44 INFO - Return code: 0 16:35:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:35:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:35:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:35:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:35:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:35:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:35:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0176 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:35:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tV1nNWvDi2/Render', 16:35:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:35:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:35:44 INFO - 'HOME': '/Users/cltbld', 16:35:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:35:44 INFO - 'LOGNAME': 'cltbld', 16:35:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:35:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:35:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:35:44 INFO - 'MOZ_NO_REMOTE': '1', 16:35:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:35:44 INFO - 'NO_EM_RESTART': '1', 16:35:44 INFO - 'PAGER': '/bin/cat', 16:35:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:35:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:35:44 INFO - 'PWD': '/builds/slave/test', 16:35:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:35:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:35:44 INFO - 'SHELL': '/bin/bash', 16:35:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.53gFnKQx1q/Listeners', 16:35:44 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 16:35:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:35:44 INFO - 'USER': 'cltbld', 16:35:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:35:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:35:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:35:44 INFO - 'XPC_FLAGS': '0x0', 16:35:44 INFO - 'XPC_SERVICE_NAME': '0', 16:35:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:35:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:35:44 INFO - mozversion application_buildid: 20170112154018 16:35:44 INFO - mozversion application_changeset: 721c28b39396010388fcab52459e98435ee8b33e 16:35:44 INFO - mozversion application_display_name: Nightly 16:35:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:35:44 INFO - mozversion application_name: Firefox 16:35:44 INFO - mozversion application_remotingname: firefox 16:35:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:35:44 INFO - mozversion application_vendor: Mozilla 16:35:44 INFO - mozversion application_version: 53.0a1 16:35:44 INFO - mozversion platform_buildid: 20170112154018 16:35:44 INFO - mozversion platform_changeset: 721c28b39396010388fcab52459e98435ee8b33e 16:35:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:35:44 INFO - mozversion platform_version: 53.0a1 16:35:44 INFO - using testdate: 1484267744 16:35:44 INFO - actual date: 1484267744 16:35:44 INFO - starting webserver on 'localhost:58582' 16:35:44 INFO - SUITE-START | Running 2 tests 16:35:44 INFO - TEST-START | dromaeo_css 16:35:44 INFO - operating with platform_type : mac_ 16:35:44 INFO - Initialising browser for dromaeo_css test... 16:35:44 INFO - TEST-INFO | started process 6880 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpy1wNLE/profile http://localhost:58582/getInfo.html) 16:35:47 INFO - PROCESS | 6880 | 2017-01-12 16:35:47.796 plugin-container[6882:121207] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 16:35:47 INFO - PROCESS | 6880 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:47 INFO - PROCESS | 6880 | 2017-01-12 16:35:47.800 plugin-container[6882:121207] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 16:35:47 INFO - PROCESS | 6880 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:47 INFO - PROCESS | 6880 | 2017-01-12 16:35:47.800 plugin-container[6882:121207] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:35:47 INFO - PROCESS | 6880 | __metrics Screen width/height:1600/1200 16:35:47 INFO - PROCESS | 6880 | colorDepth:24 16:35:47 INFO - PROCESS | 6880 | Browser inner width/height: 1024/689 16:35:47 INFO - PROCESS | 6880 | __metrics 16:35:47 ERROR - PROCESS | 6880 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3242: TypeError: this.tabs is undefined 16:35:48 INFO - TEST-INFO | 6880: exit 0 16:35:48 INFO - Browser initialized. 16:35:48 INFO - Running cycle 1/1 for dromaeo_css test... 16:35:48 INFO - TEST-INFO | started process 6883 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpy1wNLE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:35:51 INFO - PROCESS | 6883 | 2017-01-12 16:35:51.226 plugin-container[6884:121325] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 16:35:51 INFO - PROCESS | 6883 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:51 INFO - PROCESS | 6883 | 2017-01-12 16:35:51.226 plugin-container[6884:121325] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 16:35:51 INFO - PROCESS | 6883 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:35:51 INFO - PROCESS | 6883 | 2017-01-12 16:35:51.226 plugin-container[6884:121325] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:36:00 INFO - PROCESS | 6883 | RSS: Main: 233443328 16:36:00 INFO - PROCESS | 6883 | 16:39:04 INFO - PROCESS | 6883 | Cycle 1(1): loaded http://localhost:58582/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58582/tests/dromaeo/cssquery-ext.html) 16:39:05 INFO - PROCESS | 6883 | RSS: Main: 251969536 16:39:05 INFO - PROCESS | 6883 | 16:40:49 INFO - PROCESS | 6883 | ************************* 16:40:49 INFO - PROCESS | 6883 | A coding exception was thrown and uncaught in a Task. 16:40:49 INFO - PROCESS | 6883 | 16:40:49 INFO - PROCESS | 6883 | Full message: ReferenceError: fetch is not defined 16:40:49 INFO - PROCESS | 6883 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 16:40:49 INFO - PROCESS | 6883 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 16:40:49 INFO - PROCESS | 6883 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 16:40:49 INFO - PROCESS | 6883 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 16:40:49 INFO - PROCESS | 6883 | TaskImpl@resource://gre/modules/Task.jsm:277:3 16:40:49 INFO - PROCESS | 6883 | asyncFunction@resource://gre/modules/Task.jsm:252:14 16:40:49 INFO - PROCESS | 6883 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 16:40:49 INFO - PROCESS | 6883 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 16:40:49 INFO - PROCESS | 6883 | TaskImpl@resource://gre/modules/Task.jsm:277:3 16:40:49 INFO - PROCESS | 6883 | asyncFunction@resource://gre/modules/Task.jsm:252:14 16:40:49 INFO - PROCESS | 6883 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 16:40:49 INFO - PROCESS | 6883 | 16:40:49 INFO - PROCESS | 6883 | ************************* 16:42:07 INFO - PROCESS | 6883 | Cycle 1(1): loaded http://localhost:58582/tests/dromaeo/cssquery-ext.html (next: http://localhost:58582/tests/dromaeo/cssquery-jquery.html) 16:42:08 INFO - PROCESS | 6883 | RSS: Main: 263299072 16:42:08 INFO - PROCESS | 6883 | 16:45:10 INFO - PROCESS | 6883 | Cycle 1(1): loaded http://localhost:58582/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58582/tests/dromaeo/cssquery-mootools.html) 16:45:10 INFO - PROCESS | 6883 | RSS: Main: 271478784 16:45:10 INFO - PROCESS | 6883 | 16:48:12 INFO - PROCESS | 6883 | Cycle 1(1): loaded http://localhost:58582/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58582/tests/dromaeo/cssquery-prototype.html) 16:48:13 INFO - PROCESS | 6883 | RSS: Main: 267694080 16:48:13 INFO - PROCESS | 6883 | 16:51:16 INFO - PROCESS | 6883 | Cycle 1(1): loaded http://localhost:58582/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58582/tests/dromaeo/cssquery-yui.html) 16:51:16 INFO - PROCESS | 6883 | RSS: Main: 272285696 16:51:16 INFO - PROCESS | 6883 | 16:54:19 INFO - PROCESS | 6883 | Cycle 1(1): loaded http://localhost:58582/tests/dromaeo/cssquery-yui.html (next: http://localhost:58582/tests/dromaeo/cssquery-dojo.html) 16:54:19 INFO - PROCESS | 6883 | RSS: Main: 272887808 16:54:19 INFO - PROCESS | 6883 | 16:54:19 INFO - PROCESS | 6883 | __start_tp_report 16:54:19 INFO - PROCESS | 6883 | _x_x_mozilla_page_load 16:54:19 INFO - PROCESS | 6883 | _x_x_mozilla_page_load_details 16:54:19 INFO - PROCESS | 6883 | |i|pagename|runs| 16:54:19 INFO - PROCESS | 6883 | |0;dojo.html;3330.184104894303;3358.0933148050026;3361.2941282330708;3387.5765529308837;3388.593368795744;5533.529649979747;5620;5679.4604512571295;5709.257796486461;5729.255196824417;5596.524295434893;5629.831105066854;5682.801101961432;5722.971385143079;5743.798967036155;4126.587341265879;5161.890193608373;5192.1432963560965;5222.3472065991755;5284.524392804647;5142.717150556718;5225.817096401626;5255.395629502618;5265.999460102587;5266.8419947401635;5098.133317336024;5267.26258323835;5268.473152684739;5301.284326615915;5320.015797077553;5118.07874582575;5134.64057515974;5179.015986962503;5180.171172612364;5183.714895680736;5185.248139019859;5193.013327467806;5207.947920520822;5232.136697444917;5247.580193584504;4644.349791029259;5499.1476321170285;5663.122216056518;5703.54371650267;5711.7715291388295;6100.7022855299665;6264.6241225526155;6265.404786545271;6271.059341098826;6317.557770956035;6150.169727086831;6206.069089636546;6255.40573645503;6259.499240060784;6300.9603415436395;5777.364489906107;5830.591858569859;5851.76592936282;5859.677717725566;5872.559558033122;6214.40963108504;6266.561340706106;6275.403836635513;6284;6292.685365731695;5986.102084687244;6017.789377371771;6061.515078793686;6083;6138.631682099089;6188.44304012641;6219.720112594944;6266.49868010558;6276.968615156895;6291.433770960635;6122.142899994004;6125.357545073132;6167.96916015426;6201.720922558496;6204.069389591507;5119.3088932994315;6207.596506227081;6273.18448601688;6313.810585682437;6314.021326694278;4115.938260926088;4913.582345500601;4930.309756634074;4956.801727930842;4969.776360063805;4348.956510434855;4369.956300437018;4443.400140980991;4449.1546606145375;4449.243628583149;5862.560307976919;5914.852128696817;5935.910961335497;5942.405759424023;5961.135635332853;6241.282252541016;6252.562320637512;6267.2165979252595;6268.655223962726;6272.717727702265;5689.687067211343;5700.173474846124;5731.828045158652;5920.31916329619;5942.019566771521;5413.242146099549;5425.077736784756;5444.91832622511;5465.754041068161;5533.861653458695;6298.842528936813;6321.841953951188;6357.682115894279;6371.681415929185;6377.202849643794;6238.875222495615;6242.687865606792;6249.375062493714;6264.21697287839;6278.246610406781;6174.197354343906;6190.628562286277;6190.783322583688;6231.1276421300145;6238.594491358047;4972.577330926912;5017.222330538772;5366.7656439018465;6203.286622038343;6221.9377806221355;5032.924506132485;6216.813495595139;6255.593386429868;6276.8430789228805;6285.5600107992;6025.367336429787;6035.215421995113;6087.147799308189;6091.695415229309;6123.374132580074;6101.877962440815;6123.4488895999575;6127.617148554337;6174.228221472316;6226.128342032029;5509.889802203853;5530.695811730394;5728.083506638918;5904.704764761831;5906.173135760911;6063.181470501602;6116.0010150213275;6132.294786099477;6190.071489276645;6196.349383314868;5863.794767183128;6046.758129674764;6078.848028799315;6083.92652695498;6084.695765211633 16:54:19 INFO - PROCESS | 6883 | |1;ext.html;443.72267332916925;451.8554062699935;499.59782375187973;499.71323841109154;512.9204973229149;40090.799546002265;41996;42009.789951050276;42026.78986605066;42064.57935420645;42707.572924270746;43105.568944310544;43272.13455730883;43356;43404.56595434045;9267.536623168848;9464.337496375256;9496.240300775922;9511.191548718367;10761.13910887131;10780.676579702582;11035.227534072617;11045.3372797632;11048.668539943814;11050.889491105067;10994.835077473845;11003.61487347943;11013.28413653114;11018.834717479203;11035.44822758863;10906.945465272662;11017.724556886142;11020.724481887977;11024.44877756117;11049.392283424408;10719.78560428787;10822.891771082268;10826.783464330749;10828.296160749525;10834.67495975122;36991.630083699085;37196.81401592975;37280.25439491195;37330.066748331235;37440;3698.6671199592165;3725.832337544817;3934.665553427962;3991.6607088397523;4002.3590327965394;28382.014121144555;28765.99319023824;28904.710952890622;29078.41843163146;29158.41683166346;2729.3858881751644;3156.35294764575;3206.5350524173864;3217.7104060634656;3345.3142105868237;388685;388705;389530;393172;394029;55632;55691.16463253054;55738.72130639321;55821;55919.48362323705;58578;58782.41217587855;59047.40952590419;59066;59080.704596477604;15108.017978831342;15595.376184952702;15621.921890390475;15622.29699663518;15625.3749850007;10813.945930270456;11025.062869656169;11850.940745296391;11941.701457463632;12117.212381195193;18045.917244965345;18084.276628934695;18111.547211319823;18125.456236312933;18127.909360453377;6796.029151545997;7804.726834560764;7833.569153696488;8030.277275045274;8040.557769322743;728.144430294402;879.5162660536807;901.9413738107002;903.2457897655531;916.9128932751378;30093.09720708382;30610.54084188739;30672.079837604906;30979.225519361746;31198;1070.1224995503767;1076.2251179150999;1096.0847692176962;1114.1977776001265;1212.6483319837325;15610.219489025503;15857.603559910864;15952.680946381008;16235;16278.86047976654;537480;541802;542348;543415;543424;59087.40912590819;59976.70011650001;60047.69976150091;60166.796664066474;60197;1396.6438558167602;1454.8763355114722;1457.7026446462658;1459.729949959261;1471.8086648735596;25200.243992680247;28156.296092597848;29352.706472935;30085.430846923653;30117.548236776904;50200.748996254784;50652.74673626756;50721;50842.491575083775;50885;473054;474993;477712;478306;478366;23536.52926941486;23954.760452395254;25689.614655779806;25740;26307.342316442242;46930.76534617305;47025.76487117542;47092.82267943329;47794.80512987203;48176.75911620419;39015.80492097521;39043;39140;40448.39327410032;40560.98597535085;37922;38256.66101686427;38427;39429.2114157721;39474.40788388234;37714;37946.0513487165;38194.809025954695;38232.617673822904;38253.043673908374;32134.517982230755;33894.15264618404;34456.65543344535;34685.65314346925;34811.30377392387;24252.51494970126;25063.87468062648;25579.87210063938;25802.870985645703;25906.35234119162 16:54:19 INFO - PROCESS | 6883 | |2;jquery.html;383.30621574949345;384.82875120571356;390.64646494922107;395.4108378516012;421.7532743345143;16024.118673472954;17592.824071759296;19889.900550497245;21275.57448851022;21333.67999480009;20947.266845660404;21176.788232117673;21441.46396340092;21469.355919322406;21708.56582868342;4826.889815342473;4878.317035615017;5642.57680673949;5809.506191973666;5857.6778277194735;5094.261332106842;5351.357837059559;5901.173835662989;5994.610350327237;6002.699865006754;5198.428172900978;5948.256467941507;6092.268927728679;6097.6951152442425;6108.3280839107665;6019.699015049274;6058.636481811083;6062.878742425149;6550.737970481175;6612.471002319803;5875.823725288248;5878.50032747217;5881.882362352771;5900.321463031721;5907.852303692399;11268;13396.598102056856;13401.597952061455;13548.119372240872;17476.60187185022;2394.904203831845;2465.186488458822;2498.675172227617;2581.6127580862835;2639.2214296782495;10171.54228059739;10450.007249311304;12638.115331926769;14768;15667.843321566752;1769.8053214146435;1803.8184988832336;1803.899621231048;1835.2108593304874;1841.7605711257586;138025;141615;141840;143955.56044439526;149151;9958.253131015203;11713.941430292964;11716.88283117175;12122.818157727641;12237.449314780857;14076.577702668732;14667.19330436815;15517.767233491506;15531.844681553266;15549.766753498707;8275.172482751677;8516.233538981522;8611.07223543998;8611.870821937677;8710.782230444163;6463.515236357292;8262.256396924306;8412.915870841336;8520.19058189471;8587;4609.907801843944;4660.46404663468;4703.7883295251795;4742.881427964259;4802.543758342952;1996.6705493593395;2049.894022469241;2055.506678397174;2055.650539408274;2055.6710926251726;789.2462698123278;813.2274339377615;818.2635627934907;822.461287856455;851.7955690634327;21540.892295538422;22089.889550552467;22239;22252;22307.776922230896;843.6920524008692;857.5197889181953;869.7695110795644;875.4309698695771;883.7746374674417;10323.432211228304;10789.489471474;11310.264832786008;11497.212346877162;11512.251703639236;696405;705624;706759.4662026657;723255;728829;121334;138837.30581347027;150249;156362.21818890833;159059;1066.5307264803462;1068.7808999155154;1091.8416829559828;1094.8084085285202;1095.5727266366123;18875.90562047181;19614.313499027467;19621;19667.70498442496;19680.60638787245;47044.7647761759;52295.69260768511;57554.42445575491;57629;59878.40729556241;74483.25516744763;84691.57654211689;85805;85969.57015214884;86270;8161.020677518736;8173.773933909961;8229.547374894439;8613.397062205586;8675.089115642766;12236.388180590757;12786.61640150797;12998.545050923172;13324;13326.33368331599;5487.98472282629;7586.696532138873;9183.632654693738;11265.098792096776;11562.132840037199;11029.393383363991;11234;11287.887121129012;11378.601748938747;11668.008219301455;11558.43637017381;11595.130365222474;11634.767304653691;11744.412779361168;11855.288682679067;8363.494570977282;8422.494650321001;8464.703735369232;8498.53258070812;8508.021577518664;7743.612819359122;10713;10713.142948564253;11537;11653.76692466163 16:54:19 INFO - PROCESS | 6883 | |3;mootools.html;1348.379745317154;1427.2221639206625;1444.6821699226166;1445.8409574946754;1455.1196526101708;7495.138059123203;7759.301662850342;8003.799905002371;8457.78855528612;8461.746147615573;7954.920450795505;8174.26431621154;8223.506589604627;8326.542040187787;8344.791380215498;2256.2892688803017;2299.8965046572944;2304.9193278235266;2307.0310469602723;2317.1079134533243;2042.9693554596693;2129.244118337991;2296.276672848056;2335.6730057791924;2341.0752752662775;2204.206485665159;2302.251768175341;2304.873231972241;2315.8420789605198;2319.3042087373806;1995.8025184889095;2295.931122066341;2296.184854376689;2312.1329501436962;2324.8140148788057;2208.4147700859285;2289.0843662534953;2293.9885300573555;2336.5326934613145;2339.5905716499547;7192.928070719278;8367;8399.244068033906;8416.957915210445;8424.578771061422;5075.492450754932;5235.293235413209;5238.345206849144;5242.842714718565;5247.895042099175;3251.430999575065;3438.2435864109857;3448.2241495663525;3490.1972546314237;3523.594786599548;1169.9005584525326;1189.1794661683412;1201.633501781956;1211.0977321895245;1223.9082068844784;9289.814203715956;10444.164466842634;10695.251332406735;10775.622853200179;10821.999999999922;4373.343998400202;4626.699264547794;4678.426865224181;4694.460137084279;4706.670533062721;9379.296552758571;9474.95262523683;9522.714318570454;9545.61817527305;9550.949395566482;5238.711870847063;5248.711320877312;5252.343457067866;5252.816151434681;5259.8422047338645;4334.566543345641;4635.629149668019;4744.478107408182;4824.445188803333;4832.106060378841;3185.8566364513654;3788.981055094762;3890.3386424307937;3915.1190982029384;3959.48526691534;1164.8427462292652;1198.748262864808;1224.381687247934;1257.7484503099233;1258.8929940955122;911.1708345405649;922.077922077922;943.2973482863497;944.8488241881404;969.2536746705139;5457.208704737791;5579.69311687861;5678.943210567842;5746.942530574641;5810.622309549865;974.7368210583103;992.7071513903416;1010.8685870836891;1020.6478764826123;1059.1473863539925;3677.5770786359376;3824.0248736572;3904.72666913319;3926.1362500249897;3941.152652179795;9810.509474526245;10031.347962382422;10153.898461015444;10154;10155.847662285072;9882.209423246122;9931.900680993242;10046.04562566555;10085.394876307446;10101.797964040825;7812.179721129313;7813.80465488356;7916.485428447132;7951.920480795234;7980.281774640311;5481.780728770805;5581.302337207849;5631.943680563306;5644.068728659725;5691.861627674399;9225.769355766159;9311.022342654194;9335.953320233355;9339.20616747584;9340.019297973615;9005.189532942066;9360.859587106335;9396.295277854051;9407.294452915921;9517.762055948657;7092.822679432849;7254.6735396908325;7264.019357386685;7264.89102663471;7270.163931147986;7804.8829267559895;7858.44990850635;7863.292303692695;7917.7624671259955;7949.1255961843;6606.174228221473;7250;7407.444441667004;7484.139323977813;7612.923870761221;7286.708531658886;7299.5620262784405;7320.96339518299;7367.852642947219;7453.142888567667;6407.519436042408;6409.935900640934;6419.871602567829;6433.228012638513;6464.288928217987;5027.522385373383;5027.64806463544;5066.03745288394;5074.619403544675;5152.227165924991;5011.4236862761245;5014.7994080236385;5018.322526459027;5120.462351453044;5178.637495375438 16:54:19 INFO - PROCESS | 6883 | |4;prototype.html;309.8249489038693;346.4647120199292;366.4924080148993;368.28001257541473;378.1340729728921;4550.49944506104;4740.597049250813;5390.353157621086;5496.340439147298;5499.670019798815;4113.876583702487;4661.883452913678;5192.350956130484;5327.147656374981;5472.616916815825;3525.682688558036;3911.4575525908103;3914.745541539791;4247.490301163864;4285.742855428669;3583.552055993001;4098.446709694198;4172.3532852407925;4241.533431322552;4257.7445353278745;4193;4217.198732240864;4227.936580951288;4245.532991370962;4299;3339.7662163648674;3663.670269675729;4275.657947364203;4281.272183728774;4322.697411181219;3354.3459025490038;3553.9466907996402;3713.9814300928592;3725.2549490102087;3765.491658626078;4229.894252643678;5265.552428043583;5404.4595540446035;5406.972675191741;5453.481919217668;4702.835400760991;4863.902721945577;4894.5105489451125;4941;4961.5534601886;4784.736839473828;5522.889542209174;5735.942640573624;5791.913121303141;5796.8840623187725;3709.77741335518;4153.816162393733;4163.854265100737;4176.310908700061;4258.339957306623;5778.59549831512;6036.637801731867;6067.7269522871575;6113.868934247179;6129.682118344577;3163.636181839118;3573.4282514797505;3581.8567257309414;3653.214558869856;4206.558311377323;3707.6107008763693;3984.143409167043;3996.980015099906;4019.577944315863;4059.837606495707;3749.9625003750157;4018.799060046986;4364.934525982113;4616.953830461653;4680.859574212779;5016.347874776256;5113.159262665437;5134.127198376285;5493.725313734298;5785.392533783976;1167.8598568171874;1244.5830646733446;1270.7522033203436;1272.4210484229654;1291.4124073546514;726.6947881889619;734.1190571314444;747.9551226926402;756.5838788666211;882.2765332427348;4280.72175308604;5051.570616497639;5076.695398276115;5115.18157094863;5266;5818.8254352369495;5917.763289468459;5962.720503722935;5993.534816561602;6010.54920880927;4770.284457331359;4949.985253023122;5282.656627319212;5361.222622719762;5437.48343907336;2747.1566928229186;2880.510313246753;2937.296368106493;2937.5446805745146;2948.231061363165;4760.45254795696;5244.947550524522;5783.2529548464145;5826.359100498942;5847.8012007538355;4903.583195428429;5484.588655850827;5612.691301978431;5793.914190891276;5797.420257974253;4714.952850471452;4800.73595952226;5139.794408223629;5331.600129990189;5787.913181302284;4940.728259945738;5549.7780088796;5607.046802043744;5697.544196464356;5708.400617935057;4684.906301873875;4760.881407773508;5301.3373328333955;5398.838034858961;5539.365887063406;5668.37647858727;5948.226730525004;5949.608723028005;6044.546659000504;6338.936610634019;3979.900502487345;3993.221321842213;4009.3785463253244;4022.3966405039478;4024.597540245952;5671.051053452265;5726.627769194989;5803.053923332006;5839.562032847469;5998.25021872266;4883;4995.525425084611;5228.790848366023;5375.865603359791;5476.342838859203;5100.515451032146;5317.415084340797;5755.079407106863;5851.853703657443;5871.324797648325;4639.652026098123;4959.032988567339;5202.439268219595;5386.649867758583;5622.8313150605545;5075.695458272367;5586.229295225788;5661.631993920382;5827.737751801094;5877.853053673522;4955.984023275148;5210.577081656896;5458.890822183614;5618.410066943075;5729.6508431544535 16:54:19 INFO - PROCESS | 6883 | |5;yui.html;650.9153810004699;679.8300424893777;695.7495301691393;709.4182770128496;760.1030783675262;14438.855611443882;15399.999000065001;15419.997700149483;17049.82950170498;17512.387066452677;16604.418845340417;16782.328706851713;16785.160741962914;16799.664006719857;16806.32774689014;624.3756243756244;681.7034589953386;863.9697610583631;886.02979737188;891.2201823404521;782.5774081995744;865.709987154304;875;882.0826340605762;882.5013867165063;898.4339865884483;916.7937214126835;918.0727465260102;918.2837386838277;919.3702313914989;909.5134103254727;909.5679552212679;910.649399981012;911.5806728904712;912.370464379576;679.4055201698513;683.8187880211748;684.090160087083;684.4114061906756;685.2188505104185;12681.93659031699;15253.403801926279;15452.15013174275;15478.690426191413;15600.29798659063;11914.940425297904;13757.243351615758;13949.511767088205;14131.71736565263;14185.858141418557;11599.246049006788;11768.646940591796;11812.468438920183;11917.82123268143;12279.508819647204;3602;3622.7464077514583;3688.0779805048737;3721.6092310307567;3791.2796568652143;85153;107594.46202769014;108794.91205087927;109796.4510177444;111312.99999999919;10599.788004239872;10713.464326783629;10768.22324316479;10890.727731806768;11088.611898583511;22042.779572204072;22271.77728222697;22477.88761056217;22500.662490062663;22574.435639108826;7198.92801071993;7594.354479869163;7614.200508946481;7716.5370077795515;7759.495632783851;4187.560306167869;4210.87367378973;4285.742855428684;4307.870763877088;4371.191329604034;3572.91067723309;3632.509611202454;3659.9817000914827;3660.6888414484533;3668.413053911361;649.256601191633;652.3150691773619;657.9539432239698;665.3512824995687;667.6127845849396;674.6250565085417;702.1749444402807;704.2816327346078;719.6078137415121;739.3789217057697;10073.345232560006;10192.592296308212;10256.461500796908;10362;10403.84394234087;686.5160062156168;693.6497068980133;715.3454589051064;718.2996578336185;718.8957601147804;4658.534146585314;4934.605231581465;4937.728424936591;4958.57852082577;4963.181075122564;91775;101050;103528.48235758924;108208;110023;66216.66891665607;90156;90499.73750656155;90724;91894;71236.86289411326;73142.63428682928;75776.24223757912;76547;79703.60148199221;10758.784824303626;10902.44340998544;11087.22389432732;11149.442527873736;11154.442277886235;47199.76400117978;48255.75872120617;50514.747426262635;50725.49274507208;51618.741906290226;67732.661336693;68604.6569767148;69590;69906;72205;12924.612261632166;12935.288559129338;12982.740345193231;13079.280639564535;13142.211467311992;14504.709905802036;14779.039362441406;14995.850041499445;15154.772678410052;15280.61798455003;13606.591802245948;13794.793078103636;13858.584242472742;13925.373358198703;14290.785638215642;11998.460069297078;12030.63908082759;12037.458314375699;12445.502179913065;12600.495980160691;13268;13394.93302533481;13542.458301667823;13589.184648921097;13683.726325473634;10868.61959831402;11061.668149955514;11078.335299882034;11264.267822591506;11524.193306468802;8772.81566988474;9032.915540591002;9997.850247221371;10835.76820670321;11401.714957126138 16:54:19 INFO - PROCESS | 6883 | __end_tp_report 16:54:19 INFO - PROCESS | 6883 | __start_cc_report 16:54:19 INFO - PROCESS | 6883 | _x_x_mozilla_cycle_collect,377 16:54:19 INFO - PROCESS | 6883 | __end_cc_report 16:54:19 INFO - PROCESS | 6883 | __startTimestamp1484268859893__endTimestamp 16:54:19 INFO - PROCESS | 6883 | 16:54:19 INFO - PROCESS | 6883 | ------- Summary: start ------- 16:54:19 INFO - PROCESS | 6883 | Number of tests: 6 16:54:19 INFO - PROCESS | 6883 | 16:54:19 INFO - PROCESS | 6883 | [#0] dojo.html Cycles:165 Average:5703.10 Median:5935.91 stddev:668.55 (11.3%) stddev-sans-first:644.17 16:54:19 INFO - PROCESS | 6883 | Values: 3330.2 3358.1 3361.3 3387.6 3388.6 5533.5 5620.0 5679.5 5709.3 5729.3 5596.5 5629.8 5682.8 5723.0 5743.8 4126.6 5161.9 5192.1 5222.3 5284.5 5142.7 5225.8 5255.4 5266.0 5266.8 5098.1 5267.3 5268.5 5301.3 5320.0 5118.1 5134.6 5179.0 5180.2 5183.7 5185.2 5193.0 5207.9 5232.1 5247.6 4644.3 5499.1 5663.1 5703.5 5711.8 6100.7 6264.6 6265.4 6271.1 6317.6 6150.2 6206.1 6255.4 6259.5 6301.0 5777.4 5830.6 5851.8 5859.7 5872.6 6214.4 6266.6 6275.4 6284.0 6292.7 5986.1 6017.8 6061.5 6083.0 6138.6 6188.4 6219.7 6266.5 6277.0 6291.4 6122.1 6125.4 6168.0 6201.7 6204.1 5119.3 6207.6 6273.2 6313.8 6314.0 4115.9 4913.6 4930.3 4956.8 4969.8 4349.0 4370.0 4443.4 4449.2 4449.2 5862.6 5914.9 5935.9 5942.4 5961.1 6241.3 6252.6 6267.2 6268.7 6272.7 5689.7 5700.2 5731.8 5920.3 5942.0 5413.2 5425.1 5444.9 5465.8 5533.9 6298.8 6321.8 6357.7 6371.7 6377.2 6238.9 6242.7 6249.4 6264.2 6278.2 6174.2 6190.6 6190.8 6231.1 6238.6 4972.6 5017.2 5366.8 6203.3 6221.9 5032.9 6216.8 6255.6 6276.8 6285.6 6025.4 6035.2 6087.1 6091.7 6123.4 6101.9 6123.4 6127.6 6174.2 6226.1 5509.9 5530.7 5728.1 5904.7 5906.2 6063.2 6116.0 6132.3 6190.1 6196.3 5863.8 6046.8 6078.8 6083.9 6084.7 16:54:19 INFO - PROCESS | 6883 | 16:54:19 INFO - PROCESS | 6883 | [#1] ext.html Cycles:180 Average:61830.38 Median:37305.16 stddev:125819.27 (337.3%) stddev-sans-first:126087.81 16:54:19 INFO - PROCESS | 6883 | Values: 443.7 451.9 499.6 499.7 512.9 40090.8 41996.0 42009.8 42026.8 42064.6 42707.6 43105.6 43272.1 43356.0 43404.6 9267.5 9464.3 9496.2 9511.2 10761.1 10780.7 11035.2 11045.3 11048.7 11050.9 10994.8 11003.6 11013.3 11018.8 11035.4 10906.9 11017.7 11020.7 11024.4 11049.4 10719.8 10822.9 10826.8 10828.3 10834.7 36991.6 37196.8 37280.3 37330.1 37440.0 3698.7 3725.8 3934.7 3991.7 4002.4 28382.0 28766.0 28904.7 29078.4 29158.4 2729.4 3156.4 3206.5 3217.7 3345.3 388685.0 388705.0 389530.0 393172.0 394029.0 55632.0 55691.2 55738.7 55821.0 55919.5 58578.0 58782.4 59047.4 59066.0 59080.7 15108.0 15595.4 15621.9 15622.3 15625.4 10813.9 11025.1 11850.9 11941.7 12117.2 18045.9 18084.3 18111.5 18125.5 18127.9 6796.0 7804.7 7833.6 8030.3 8040.6 728.1 879.5 901.9 903.2 916.9 30093.1 30610.5 30672.1 30979.2 31198.0 1070.1 1076.2 1096.1 1114.2 1212.6 15610.2 15857.6 15952.7 16235.0 16278.9 537480.0 541802.0 542348.0 543415.0 543424.0 59087.4 59976.7 60047.7 60166.8 60197.0 1396.6 1454.9 1457.7 1459.7 1471.8 25200.2 28156.3 29352.7 30085.4 30117.5 50200.7 50652.7 50721.0 50842.5 50885.0 473054.0 474993.0 477712.0 478306.0 478366.0 23536.5 23954.8 25689.6 25740.0 26307.3 46930.8 47025.8 47092.8 47794.8 48176.8 39015.8 39043.0 39140.0 40448.4 40561.0 37922.0 38256.7 38427.0 39429.2 39474.4 37714.0 37946.1 38194.8 38232.6 38253.0 32134.5 33894.2 34456.7 34685.7 34811.3 24252.5 25063.9 25579.9 25802.9 25906.4 16:54:19 INFO - PROCESS | 6883 | 16:54:19 INFO - PROCESS | 6883 | [#2] jquery.html Cycles:180 Average:39366.14 Median:2430.05 stddev:118996.18 (4896.9%) stddev-sans-first:119293.99 16:54:19 INFO - PROCESS | 6883 | Values: 383.3 384.8 390.6 395.4 421.8 16024.1 17592.8 19889.9 21275.6 21333.7 20947.3 21176.8 21441.5 21469.4 21708.6 4826.9 4878.3 5642.6 5809.5 5857.7 5094.3 5351.4 5901.2 5994.6 6002.7 5198.4 5948.3 6092.3 6097.7 6108.3 6019.7 6058.6 6062.9 6550.7 6612.5 5875.8 5878.5 5881.9 5900.3 5907.9 11268.0 13396.6 13401.6 13548.1 17476.6 2394.9 2465.2 2498.7 2581.6 2639.2 10171.5 10450.0 12638.1 14768.0 15667.8 1769.8 1803.8 1803.9 1835.2 1841.8 138025.0 141615.0 141840.0 143955.6 149151.0 9958.3 11713.9 11716.9 12122.8 12237.4 14076.6 14667.2 15517.8 15531.8 15549.8 8275.2 8516.2 8611.1 8611.9 8710.8 6463.5 8262.3 8412.9 8520.2 8587.0 4609.9 4660.5 4703.8 4742.9 4802.5 1996.7 2049.9 2055.5 2055.7 2055.7 789.2 813.2 818.3 822.5 851.8 21540.9 22089.9 22239.0 22252.0 22307.8 843.7 857.5 869.8 875.4 883.8 10323.4 10789.5 11310.3 11497.2 11512.3 696405.0 705624.0 706759.5 723255.0 728829.0 121334.0 138837.3 150249.0 156362.2 159059.0 1066.5 1068.8 1091.8 1094.8 1095.6 18875.9 19614.3 19621.0 19667.7 19680.6 47044.8 52295.7 57554.4 57629.0 59878.4 74483.3 84691.6 85805.0 85969.6 86270.0 8161.0 8173.8 8229.5 8613.4 8675.1 12236.4 12786.6 12998.5 13324.0 13326.3 5488.0 7586.7 9183.6 11265.1 11562.1 11029.4 11234.0 11287.9 11378.6 11668.0 11558.4 11595.1 11634.8 11744.4 11855.3 8363.5 8422.5 8464.7 8498.5 8508.0 7743.6 10713.0 10713.1 11537.0 11653.8 16:54:19 INFO - PROCESS | 6883 | 16:54:19 INFO - PROCESS | 6883 | [#3] mootools.html Cycles:180 Average:5455.43 Median:5236.82 stddev:2955.41 (56.4%) stddev-sans-first:2947.58 16:54:19 INFO - PROCESS | 6883 | Values: 1348.4 1427.2 1444.7 1445.8 1455.1 7495.1 7759.3 8003.8 8457.8 8461.7 7954.9 8174.3 8223.5 8326.5 8344.8 2256.3 2299.9 2304.9 2307.0 2317.1 2043.0 2129.2 2296.3 2335.7 2341.1 2204.2 2302.3 2304.9 2315.8 2319.3 1995.8 2295.9 2296.2 2312.1 2324.8 2208.4 2289.1 2294.0 2336.5 2339.6 7192.9 8367.0 8399.2 8417.0 8424.6 5075.5 5235.3 5238.3 5242.8 5247.9 3251.4 3438.2 3448.2 3490.2 3523.6 1169.9 1189.2 1201.6 1211.1 1223.9 9289.8 10444.2 10695.3 10775.6 10822.0 4373.3 4626.7 4678.4 4694.5 4706.7 9379.3 9475.0 9522.7 9545.6 9550.9 5238.7 5248.7 5252.3 5252.8 5259.8 4334.6 4635.6 4744.5 4824.4 4832.1 3185.9 3789.0 3890.3 3915.1 3959.5 1164.8 1198.7 1224.4 1257.7 1258.9 911.2 922.1 943.3 944.8 969.3 5457.2 5579.7 5678.9 5746.9 5810.6 974.7 992.7 1010.9 1020.6 1059.1 3677.6 3824.0 3904.7 3926.1 3941.2 9810.5 10031.3 10153.9 10154.0 10155.8 9882.2 9931.9 10046.0 10085.4 10101.8 7812.2 7813.8 7916.5 7951.9 7980.3 5481.8 5581.3 5631.9 5644.1 5691.9 9225.8 9311.0 9336.0 9339.2 9340.0 9005.2 9360.9 9396.3 9407.3 9517.8 7092.8 7254.7 7264.0 7264.9 7270.2 7804.9 7858.4 7863.3 7917.8 7949.1 6606.2 7250.0 7407.4 7484.1 7612.9 7286.7 7299.6 7321.0 7367.9 7453.1 6407.5 6409.9 6419.9 6433.2 6464.3 5027.5 5027.6 5066.0 5074.6 5152.2 5011.4 5014.8 5018.3 5120.5 5178.6 16:54:19 INFO - PROCESS | 6883 | 16:54:19 INFO - PROCESS | 6883 | [#4] prototype.html Cycles:180 Average:4546.91 Median:5005.94 stddev:1397.47 (27.9%) stddev-sans-first:1364.72 16:54:19 INFO - PROCESS | 6883 | Values: 309.8 346.5 366.5 368.3 378.1 4550.5 4740.6 5390.4 5496.3 5499.7 4113.9 4661.9 5192.4 5327.1 5472.6 3525.7 3911.5 3914.7 4247.5 4285.7 3583.6 4098.4 4172.4 4241.5 4257.7 4193.0 4217.2 4227.9 4245.5 4299.0 3339.8 3663.7 4275.7 4281.3 4322.7 3354.3 3553.9 3714.0 3725.3 3765.5 4229.9 5265.6 5404.5 5407.0 5453.5 4702.8 4863.9 4894.5 4941.0 4961.6 4784.7 5522.9 5735.9 5791.9 5796.9 3709.8 4153.8 4163.9 4176.3 4258.3 5778.6 6036.6 6067.7 6113.9 6129.7 3163.6 3573.4 3581.9 3653.2 4206.6 3707.6 3984.1 3997.0 4019.6 4059.8 3750.0 4018.8 4364.9 4617.0 4680.9 5016.3 5113.2 5134.1 5493.7 5785.4 1167.9 1244.6 1270.8 1272.4 1291.4 726.7 734.1 748.0 756.6 882.3 4280.7 5051.6 5076.7 5115.2 5266.0 5818.8 5917.8 5962.7 5993.5 6010.5 4770.3 4950.0 5282.7 5361.2 5437.5 2747.2 2880.5 2937.3 2937.5 2948.2 4760.5 5244.9 5783.3 5826.4 5847.8 4903.6 5484.6 5612.7 5793.9 5797.4 4715.0 4800.7 5139.8 5331.6 5787.9 4940.7 5549.8 5607.0 5697.5 5708.4 4684.9 4760.9 5301.3 5398.8 5539.4 5668.4 5948.2 5949.6 6044.5 6338.9 3979.9 3993.2 4009.4 4022.4 4024.6 5671.1 5726.6 5803.1 5839.6 5998.3 4883.0 4995.5 5228.8 5375.9 5476.3 5100.5 5317.4 5755.1 5851.9 5871.3 4639.7 4959.0 5202.4 5386.6 5622.8 5075.7 5586.2 5661.6 5827.7 5877.9 4956.0 5210.6 5458.9 5618.4 5729.7 16:54:19 INFO - PROCESS | 6883 | 16:54:19 INFO - PROCESS | 6883 | [#5] yui.html Cycles:180 Average:20421.00 Median:3664.55 stddev:29100.15 (794.1%) stddev-sans-first:29143.92 16:54:19 INFO - PROCESS | 6883 | Values: 650.9 679.8 695.7 709.4 760.1 14438.9 15400.0 15420.0 17049.8 17512.4 16604.4 16782.3 16785.2 16799.7 16806.3 624.4 681.7 864.0 886.0 891.2 782.6 865.7 875.0 882.1 882.5 898.4 916.8 918.1 918.3 919.4 909.5 909.6 910.6 911.6 912.4 679.4 683.8 684.1 684.4 685.2 12681.9 15253.4 15452.2 15478.7 15600.3 11914.9 13757.2 13949.5 14131.7 14185.9 11599.2 11768.6 11812.5 11917.8 12279.5 3602.0 3622.7 3688.1 3721.6 3791.3 85153.0 107594.5 108794.9 109796.5 111313.0 10599.8 10713.5 10768.2 10890.7 11088.6 22042.8 22271.8 22477.9 22500.7 22574.4 7198.9 7594.4 7614.2 7716.5 7759.5 4187.6 4210.9 4285.7 4307.9 4371.2 3572.9 3632.5 3660.0 3660.7 3668.4 649.3 652.3 658.0 665.4 667.6 674.6 702.2 704.3 719.6 739.4 10073.3 10192.6 10256.5 10362.0 10403.8 686.5 693.6 715.3 718.3 718.9 4658.5 4934.6 4937.7 4958.6 4963.2 91775.0 101050.0 103528.5 108208.0 110023.0 66216.7 90156.0 90499.7 90724.0 91894.0 71236.9 73142.6 75776.2 76547.0 79703.6 10758.8 10902.4 11087.2 11149.4 11154.4 47199.8 48255.8 50514.7 50725.5 51618.7 67732.7 68604.7 69590.0 69906.0 72205.0 12924.6 12935.3 12982.7 13079.3 13142.2 14504.7 14779.0 14995.9 15154.8 15280.6 13606.6 13794.8 13858.6 13925.4 14290.8 11998.5 12030.6 12037.5 12445.5 12600.5 13268.0 13394.9 13542.5 13589.2 13683.7 10868.6 11061.7 11078.3 11264.3 11524.2 8772.8 9032.9 9997.9 10835.8 11401.7 16:54:19 INFO - PROCESS | 6883 | -------- Summary: end -------- 16:54:19 INFO - PROCESS | 6883 | 16:54:20 INFO - TEST-INFO | 6883: exit 0 16:54:20 INFO - TEST-OK | dromaeo_css | took 1115712ms 16:54:20 INFO - TEST-START | kraken 16:54:20 INFO - operating with platform_type : mac_ 16:54:20 INFO - Initialising browser for kraken test... 16:54:20 INFO - TEST-INFO | started process 6887 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpAwlRz1/profile http://localhost:58582/getInfo.html) 16:54:23 INFO - PROCESS | 6887 | 2017-01-12 16:54:23.172 plugin-container[6888:123334] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 16:54:23 INFO - PROCESS | 6887 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:54:23 INFO - PROCESS | 6887 | 2017-01-12 16:54:23.178 plugin-container[6888:123334] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 16:54:23 INFO - PROCESS | 6887 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:54:23 INFO - PROCESS | 6887 | 2017-01-12 16:54:23.178 plugin-container[6888:123334] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:54:23 INFO - PROCESS | 6887 | __metrics Screen width/height:1600/1200 16:54:23 INFO - PROCESS | 6887 | colorDepth:24 16:54:23 INFO - PROCESS | 6887 | Browser inner width/height: 1024/689 16:54:23 INFO - PROCESS | 6887 | __metrics 16:54:23 ERROR - PROCESS | 6887 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3242: TypeError: this.tabs is undefined 16:54:24 INFO - TEST-INFO | 6887: exit 0 16:54:24 INFO - Browser initialized. 16:54:24 INFO - Running cycle 1/1 for kraken test... 16:54:24 INFO - TEST-INFO | started process 6889 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpAwlRz1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:54:26 INFO - PROCESS | 6889 | 2017-01-12 16:54:26.527 plugin-container[6890:123451] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 16:54:26 INFO - PROCESS | 6889 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:54:26 INFO - PROCESS | 6889 | 2017-01-12 16:54:26.528 plugin-container[6890:123451] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 16:54:26 INFO - PROCESS | 6889 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:54:26 INFO - PROCESS | 6889 | 2017-01-12 16:54:26.528 plugin-container[6890:123451] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:54:36 INFO - PROCESS | 6889 | RSS: Main: 233451520 16:54:36 INFO - PROCESS | 6889 | 16:54:39 INFO - PROCESS | 6889 | Cycle 1(1): loaded http://localhost:58582/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58582/tests/kraken/driver.html?testName=audio-beat-detection) 16:54:40 INFO - PROCESS | 6889 | RSS: Main: 246128640 16:54:40 INFO - PROCESS | 6889 | 16:54:42 INFO - PROCESS | 6889 | Cycle 1(1): loaded http://localhost:58582/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58582/tests/kraken/driver.html?testName=audio-dft) 16:54:43 INFO - PROCESS | 6889 | RSS: Main: 254197760 16:54:43 INFO - PROCESS | 6889 | 16:54:46 INFO - PROCESS | 6889 | Cycle 1(1): loaded http://localhost:58582/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58582/tests/kraken/driver.html?testName=audio-fft) 16:54:47 INFO - PROCESS | 6889 | RSS: Main: 256847872 16:54:47 INFO - PROCESS | 6889 | 16:54:49 INFO - PROCESS | 6889 | Cycle 1(1): loaded http://localhost:58582/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58582/tests/kraken/driver.html?testName=audio-oscillator) 16:54:50 INFO - PROCESS | 6889 | RSS: Main: 257990656 16:54:50 INFO - PROCESS | 6889 | 16:54:52 INFO - PROCESS | 6889 | Cycle 1(1): loaded http://localhost:58582/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58582/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:54:52 INFO - PROCESS | 6889 | RSS: Main: 259686400 16:54:52 INFO - PROCESS | 6889 | 16:54:55 INFO - PROCESS | 6889 | Cycle 1(1): loaded http://localhost:58582/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58582/tests/kraken/driver.html?testName=imaging-darkroom) 16:54:56 INFO - PROCESS | 6889 | RSS: Main: 265449472 16:54:56 INFO - PROCESS | 6889 | 16:54:59 INFO - PROCESS | 6889 | Cycle 1(1): loaded http://localhost:58582/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58582/tests/kraken/driver.html?testName=imaging-desaturate) 16:55:00 INFO - PROCESS | 6889 | RSS: Main: 264302592 16:55:00 INFO - PROCESS | 6889 | 16:55:02 INFO - PROCESS | 6889 | Cycle 1(1): loaded http://localhost:58582/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58582/tests/kraken/driver.html?testName=json-parse-financial) 16:55:03 INFO - PROCESS | 6889 | RSS: Main: 264208384 16:55:03 INFO - PROCESS | 6889 | 16:55:05 INFO - PROCESS | 6889 | Cycle 1(1): loaded http://localhost:58582/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58582/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:55:05 INFO - PROCESS | 6889 | RSS: Main: 263569408 16:55:05 INFO - PROCESS | 6889 | 16:55:07 INFO - PROCESS | 6889 | Cycle 1(1): loaded http://localhost:58582/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58582/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:55:08 INFO - PROCESS | 6889 | RSS: Main: 263499776 16:55:08 INFO - PROCESS | 6889 | 16:55:10 INFO - PROCESS | 6889 | Cycle 1(1): loaded http://localhost:58582/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58582/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:55:11 INFO - PROCESS | 6889 | RSS: Main: 263376896 16:55:11 INFO - PROCESS | 6889 | 16:55:14 INFO - PROCESS | 6889 | Cycle 1(1): loaded http://localhost:58582/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58582/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:55:14 INFO - PROCESS | 6889 | RSS: Main: 263606272 16:55:14 INFO - PROCESS | 6889 | 16:55:18 INFO - PROCESS | 6889 | Cycle 1(1): loaded http://localhost:58582/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58582/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:55:18 INFO - PROCESS | 6889 | RSS: Main: 263684096 16:55:18 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | Cycle 1(1): loaded http://localhost:58582/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58582/tests/kraken/driver.html?testName=ai-astar) 16:55:20 INFO - PROCESS | 6889 | RSS: Main: 265531392 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | __start_tp_report 16:55:20 INFO - PROCESS | 6889 | _x_x_mozilla_page_load 16:55:20 INFO - PROCESS | 6889 | _x_x_mozilla_page_load_details 16:55:20 INFO - PROCESS | 6889 | |i|pagename|runs| 16:55:20 INFO - PROCESS | 6889 | |0;ai-astar;106;116;116;116;114;117;115;117;116;118 16:55:20 INFO - PROCESS | 6889 | |1;audio-beat-detection;179;143;148;132;129;146;145;129;128;114 16:55:20 INFO - PROCESS | 6889 | |2;audio-dft;202;211;212;202;203;263;271;196;194;194 16:55:20 INFO - PROCESS | 6889 | |3;audio-fft;82;59;61;85;60;59;59;59;64;54 16:55:20 INFO - PROCESS | 6889 | |4;audio-oscillator;89;71;87;100;102;99;87;71;72;82 16:55:20 INFO - PROCESS | 6889 | |5;imaging-gaussian-blur;120;132;90;91;91;86;90;87;89;86 16:55:20 INFO - PROCESS | 6889 | |6;imaging-darkroom;97;151;149;98;96;125;152;95;96;95 16:55:20 INFO - PROCESS | 6889 | |7;imaging-desaturate;95;93;92;91;97;92;91;97;92;94 16:55:20 INFO - PROCESS | 6889 | |8;json-parse-financial;60;60;61;63;62;61;61;63;62;63 16:55:20 INFO - PROCESS | 6889 | |9;json-stringify-tinderbox;69;79;70;87;80;79;82;71;65;100 16:55:20 INFO - PROCESS | 6889 | |10;stanford-crypto-aes;91;94;116;88;86;93;108;115;83;92 16:55:20 INFO - PROCESS | 6889 | |11;stanford-crypto-ccm;143;142;140;143;154;136;159;158;189;159 16:55:20 INFO - PROCESS | 6889 | |12;stanford-crypto-pbkdf2;169;176;173;222;246;219;240;192;211;174 16:55:20 INFO - PROCESS | 6889 | |13;stanford-crypto-sha256-iterative;70;71;80;73;60;63;59;60;85;59 16:55:20 INFO - PROCESS | 6889 | __end_tp_report 16:55:20 INFO - PROCESS | 6889 | __start_cc_report 16:55:20 INFO - PROCESS | 6889 | _x_x_mozilla_cycle_collect,928 16:55:20 INFO - PROCESS | 6889 | __end_cc_report 16:55:20 INFO - PROCESS | 6889 | __startTimestamp1484268920771__endTimestamp 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | ------- Summary: start ------- 16:55:20 INFO - PROCESS | 6889 | Number of tests: 14 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | [#0] ai-astar Cycles:10 Average:115.10 Median:116.00 stddev:3.38 (2.9%) stddev-sans-first:1.17 16:55:20 INFO - PROCESS | 6889 | Values: 106.0 116.0 116.0 116.0 114.0 117.0 115.0 117.0 116.0 118.0 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | [#1] audio-beat-detection Cycles:10 Average:139.30 Median:144.00 stddev:17.56 (12.2%) stddev-sans-first:11.32 16:55:20 INFO - PROCESS | 6889 | Values: 179.0 143.0 148.0 132.0 129.0 146.0 145.0 129.0 128.0 114.0 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | [#2] audio-dft Cycles:10 Average:214.80 Median:207.00 stddev:28.26 (13.7%) stddev-sans-first:29.60 16:55:20 INFO - PROCESS | 6889 | Values: 202.0 211.0 212.0 202.0 203.0 263.0 271.0 196.0 194.0 194.0 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | [#3] audio-fft Cycles:10 Average:64.20 Median:60.50 stddev:10.49 (17.3%) stddev-sans-first:8.93 16:55:20 INFO - PROCESS | 6889 | Values: 82.0 59.0 61.0 85.0 60.0 59.0 59.0 59.0 64.0 54.0 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | [#4] audio-oscillator Cycles:10 Average:86.00 Median:84.50 stddev:11.99 (14.2%) stddev-sans-first:12.67 16:55:20 INFO - PROCESS | 6889 | Values: 89.0 71.0 87.0 100.0 102.0 99.0 87.0 71.0 72.0 82.0 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | [#5] imaging-gaussian-blur Cycles:10 Average:96.20 Median:89.50 stddev:16.07 (18.0%) stddev-sans-first:14.55 16:55:20 INFO - PROCESS | 6889 | Values: 120.0 132.0 90.0 91.0 91.0 86.0 90.0 87.0 89.0 86.0 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | [#6] imaging-darkroom Cycles:10 Average:115.40 Median:95.50 stddev:25.94 (27.2%) stddev-sans-first:26.64 16:55:20 INFO - PROCESS | 6889 | Values: 97.0 151.0 149.0 98.0 96.0 125.0 152.0 95.0 96.0 95.0 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | [#7] imaging-desaturate Cycles:10 Average:93.40 Median:93.50 stddev:2.27 (2.4%) stddev-sans-first:2.33 16:55:20 INFO - PROCESS | 6889 | Values: 95.0 93.0 92.0 91.0 97.0 92.0 91.0 97.0 92.0 94.0 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | [#8] json-parse-financial Cycles:10 Average:61.60 Median:62.00 stddev:1.17 (1.9%) stddev-sans-first:1.09 16:55:20 INFO - PROCESS | 6889 | Values: 60.0 60.0 61.0 63.0 62.0 61.0 61.0 63.0 62.0 63.0 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | [#9] json-stringify-tinderbox Cycles:10 Average:78.20 Median:79.00 stddev:10.27 (13.0%) stddev-sans-first:10.34 16:55:20 INFO - PROCESS | 6889 | Values: 69.0 79.0 70.0 87.0 80.0 79.0 82.0 71.0 65.0 100.0 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | [#10] stanford-crypto-aes Cycles:10 Average:96.60 Median:89.50 stddev:11.96 (13.4%) stddev-sans-first:12.52 16:55:20 INFO - PROCESS | 6889 | Values: 91.0 94.0 116.0 88.0 86.0 93.0 108.0 115.0 83.0 92.0 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | [#11] stanford-crypto-ccm Cycles:10 Average:152.30 Median:156.00 stddev:15.52 (9.9%) stddev-sans-first:16.09 16:55:20 INFO - PROCESS | 6889 | Values: 143.0 142.0 140.0 143.0 154.0 136.0 159.0 158.0 189.0 159.0 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.20 Median:215.00 stddev:29.13 (13.6%) stddev-sans-first:28.32 16:55:20 INFO - PROCESS | 6889 | Values: 169.0 176.0 173.0 222.0 246.0 219.0 240.0 192.0 211.0 174.0 16:55:20 INFO - PROCESS | 6889 | 16:55:20 INFO - PROCESS | 6889 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:70.50 stddev:9.35 (13.3%) stddev-sans-first:9.88 16:55:20 INFO - PROCESS | 6889 | Values: 70.0 71.0 80.0 73.0 60.0 63.0 59.0 60.0 85.0 59.0 16:55:20 INFO - PROCESS | 6889 | -------- Summary: end -------- 16:55:20 INFO - PROCESS | 6889 | 16:55:21 INFO - TEST-INFO | 6889: exit 0 16:55:21 INFO - TEST-OK | kraken | took 61134ms 16:55:21 INFO - SUITE-END | took 1176s 16:55:21 INFO - Completed test suite (00:19:37) 16:55:21 INFO - javascript benchmark 16:55:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7279.155717475328, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.184104894303, 3358.0933148050026, 3361.2941282330708, 3387.5765529308837, 3388.593368795744, 5533.529649979747, 5620.0, 5679.4604512571295, 5709.257796486461, 5729.255196824417, 5596.524295434893, 5629.831105066854, 5682.801101961432, 5722.971385143079, 5743.798967036155, 4126.587341265879, 5161.890193608373, 5192.1432963560965, 5222.3472065991755, 5284.524392804647, 5142.717150556718, 5225.817096401626, 5255.395629502618, 5265.999460102587, 5266.8419947401635, 5098.133317336024, 5267.26258323835, 5268.473152684739, 5301.284326615915, 5320.015797077553, 5118.07874582575, 5134.64057515974, 5179.015986962503, 5180.171172612364, 5183.714895680736, 5185.248139019859, 5193.013327467806, 5207.947920520822, 5232.136697444917, 5247.580193584504, 4644.349791029259, 5499.1476321170285, 5663.122216056518, 5703.54371650267, 5711.7715291388295, 6100.7022855299665, 6264.6241225526155, 6265.404786545271, 6271.059341098826, 6317.557770956035, 6150.169727086831, 6206.069089636546, 6255.40573645503, 6259.499240060784, 6300.9603415436395, 5777.364489906107, 5830.591858569859, 5851.76592936282, 5859.677717725566, 5872.559558033122, 6214.40963108504, 6266.561340706106, 6275.403836635513, 6284.0, 6292.685365731695, 5986.102084687244, 6017.789377371771, 6061.515078793686, 6083.0, 6138.631682099089, 6188.44304012641, 6219.720112594944, 6266.49868010558, 6276.968615156895, 6291.433770960635, 6122.142899994004, 6125.357545073132, 6167.96916015426, 6201.720922558496, 6204.069389591507, 5119.3088932994315, 6207.596506227081, 6273.18448601688, 6313.810585682437, 6314.021326694278, 4115.938260926088, 4913.582345500601, 4930.309756634074, 4956.801727930842, 4969.776360063805, 4348.956510434855, 4369.956300437018, 4443.400140980991, 4449.1546606145375, 4449.243628583149, 5862.560307976919, 5914.852128696817, 5935.910961335497, 5942.405759424023, 5961.135635332853, 6241.282252541016, 6252.562320637512, 6267.2165979252595, 6268.655223962726, 6272.717727702265, 5689.687067211343, 5700.173474846124, 5731.828045158652, 5920.31916329619, 5942.019566771521, 5413.242146099549, 5425.077736784756, 5444.91832622511, 5465.754041068161, 5533.861653458695, 6298.842528936813, 6321.841953951188, 6357.682115894279, 6371.681415929185, 6377.202849643794, 6238.875222495615, 6242.687865606792, 6249.375062493714, 6264.21697287839, 6278.246610406781, 6174.197354343906, 6190.628562286277, 6190.783322583688, 6231.1276421300145, 6238.594491358047, 4972.577330926912, 5017.222330538772, 5366.7656439018465, 6203.286622038343, 6221.9377806221355, 5032.924506132485, 6216.813495595139, 6255.593386429868, 6276.8430789228805, 6285.5600107992, 6025.367336429787, 6035.215421995113, 6087.147799308189, 6091.695415229309, 6123.374132580074, 6101.877962440815, 6123.4488895999575, 6127.617148554337, 6174.228221472316, 6226.128342032029, 5509.889802203853, 5530.695811730394, 5728.083506638918, 5904.704764761831, 5906.173135760911, 6063.181470501602, 6116.0010150213275, 6132.294786099477, 6190.071489276645, 6196.349383314868, 5863.794767183128, 6046.758129674764, 6078.848028799315, 6083.92652695498, 6084.695765211633], "value": 5661.2045921379195, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [443.72267332916925, 451.8554062699935, 499.59782375187973, 499.71323841109154, 512.9204973229149, 40090.799546002265, 41996.0, 42009.789951050276, 42026.78986605066, 42064.57935420645, 42707.572924270746, 43105.568944310544, 43272.13455730883, 43356.0, 43404.56595434045, 9267.536623168848, 9464.337496375256, 9496.240300775922, 9511.191548718367, 10761.13910887131, 10780.676579702582, 11035.227534072617, 11045.3372797632, 11048.668539943814, 11050.889491105067, 10994.835077473845, 11003.61487347943, 11013.28413653114, 11018.834717479203, 11035.44822758863, 10906.945465272662, 11017.724556886142, 11020.724481887977, 11024.44877756117, 11049.392283424408, 10719.78560428787, 10822.891771082268, 10826.783464330749, 10828.296160749525, 10834.67495975122, 36991.630083699085, 37196.81401592975, 37280.25439491195, 37330.066748331235, 37440.0, 3698.6671199592165, 3725.832337544817, 3934.665553427962, 3991.6607088397523, 4002.3590327965394, 28382.014121144555, 28765.99319023824, 28904.710952890622, 29078.41843163146, 29158.41683166346, 2729.3858881751644, 3156.35294764575, 3206.5350524173864, 3217.7104060634656, 3345.3142105868237, 388685.0, 388705.0, 389530.0, 393172.0, 394029.0, 55632.0, 55691.16463253054, 55738.72130639321, 55821.0, 55919.48362323705, 58578.0, 58782.41217587855, 59047.40952590419, 59066.0, 59080.704596477604, 15108.017978831342, 15595.376184952702, 15621.921890390475, 15622.29699663518, 15625.3749850007, 10813.945930270456, 11025.062869656169, 11850.940745296391, 11941.701457463632, 12117.212381195193, 18045.917244965345, 18084.276628934695, 18111.547211319823, 18125.456236312933, 18127.909360453377, 6796.029151545997, 7804.726834560764, 7833.569153696488, 8030.277275045274, 8040.557769322743, 728.144430294402, 879.5162660536807, 901.9413738107002, 903.2457897655531, 916.9128932751378, 30093.09720708382, 30610.54084188739, 30672.079837604906, 30979.225519361746, 31198.0, 1070.1224995503767, 1076.2251179150999, 1096.0847692176962, 1114.1977776001265, 1212.6483319837325, 15610.219489025503, 15857.603559910864, 15952.680946381008, 16235.0, 16278.86047976654, 537480.0, 541802.0, 542348.0, 543415.0, 543424.0, 59087.40912590819, 59976.70011650001, 60047.69976150091, 60166.796664066474, 60197.0, 1396.6438558167602, 1454.8763355114722, 1457.7026446462658, 1459.729949959261, 1471.8086648735596, 25200.243992680247, 28156.296092597848, 29352.706472935, 30085.430846923653, 30117.548236776904, 50200.748996254784, 50652.74673626756, 50721.0, 50842.491575083775, 50885.0, 473054.0, 474993.0, 477712.0, 478306.0, 478366.0, 23536.52926941486, 23954.760452395254, 25689.614655779806, 25740.0, 26307.342316442242, 46930.76534617305, 47025.76487117542, 47092.82267943329, 47794.80512987203, 48176.75911620419, 39015.80492097521, 39043.0, 39140.0, 40448.39327410032, 40560.98597535085, 37922.0, 38256.66101686427, 38427.0, 39429.2114157721, 39474.40788388234, 37714.0, 37946.0513487165, 38194.809025954695, 38232.617673822904, 38253.043673908374, 32134.517982230755, 33894.15264618404, 34456.65543344535, 34685.65314346925, 34811.30377392387, 24252.51494970126, 25063.87468062648, 25579.87210063938, 25802.870985645703, 25906.35234119162], "value": 19784.071820704725, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.30621574949345, 384.82875120571356, 390.64646494922107, 395.4108378516012, 421.7532743345143, 16024.118673472954, 17592.824071759296, 19889.900550497245, 21275.57448851022, 21333.67999480009, 20947.266845660404, 21176.788232117673, 21441.46396340092, 21469.355919322406, 21708.56582868342, 4826.889815342473, 4878.317035615017, 5642.57680673949, 5809.506191973666, 5857.6778277194735, 5094.261332106842, 5351.357837059559, 5901.173835662989, 5994.610350327237, 6002.699865006754, 5198.428172900978, 5948.256467941507, 6092.268927728679, 6097.6951152442425, 6108.3280839107665, 6019.699015049274, 6058.636481811083, 6062.878742425149, 6550.737970481175, 6612.471002319803, 5875.823725288248, 5878.50032747217, 5881.882362352771, 5900.321463031721, 5907.852303692399, 11268.0, 13396.598102056856, 13401.597952061455, 13548.119372240872, 17476.60187185022, 2394.904203831845, 2465.186488458822, 2498.675172227617, 2581.6127580862835, 2639.2214296782495, 10171.54228059739, 10450.007249311304, 12638.115331926769, 14768.0, 15667.843321566752, 1769.8053214146435, 1803.8184988832336, 1803.899621231048, 1835.2108593304874, 1841.7605711257586, 138025.0, 141615.0, 141840.0, 143955.56044439526, 149151.0, 9958.253131015203, 11713.941430292964, 11716.88283117175, 12122.818157727641, 12237.449314780857, 14076.577702668732, 14667.19330436815, 15517.767233491506, 15531.844681553266, 15549.766753498707, 8275.172482751677, 8516.233538981522, 8611.07223543998, 8611.870821937677, 8710.782230444163, 6463.515236357292, 8262.256396924306, 8412.915870841336, 8520.19058189471, 8587.0, 4609.907801843944, 4660.46404663468, 4703.7883295251795, 4742.881427964259, 4802.543758342952, 1996.6705493593395, 2049.894022469241, 2055.506678397174, 2055.650539408274, 2055.6710926251726, 789.2462698123278, 813.2274339377615, 818.2635627934907, 822.461287856455, 851.7955690634327, 21540.892295538422, 22089.889550552467, 22239.0, 22252.0, 22307.776922230896, 843.6920524008692, 857.5197889181953, 869.7695110795644, 875.4309698695771, 883.7746374674417, 10323.432211228304, 10789.489471474, 11310.264832786008, 11497.212346877162, 11512.251703639236, 696405.0, 705624.0, 706759.4662026657, 723255.0, 728829.0, 121334.0, 138837.30581347027, 150249.0, 156362.21818890833, 159059.0, 1066.5307264803462, 1068.7808999155154, 1091.8416829559828, 1094.8084085285202, 1095.5727266366123, 18875.90562047181, 19614.313499027467, 19621.0, 19667.70498442496, 19680.60638787245, 47044.7647761759, 52295.69260768511, 57554.42445575491, 57629.0, 59878.40729556241, 74483.25516744763, 84691.57654211689, 85805.0, 85969.57015214884, 86270.0, 8161.020677518736, 8173.773933909961, 8229.547374894439, 8613.397062205586, 8675.089115642766, 12236.388180590757, 12786.61640150797, 12998.545050923172, 13324.0, 13326.33368331599, 5487.98472282629, 7586.696532138873, 9183.632654693738, 11265.098792096776, 11562.132840037199, 11029.393383363991, 11234.0, 11287.887121129012, 11378.601748938747, 11668.008219301455, 11558.43637017381, 11595.130365222474, 11634.767304653691, 11744.412779361168, 11855.288682679067, 8363.494570977282, 8422.494650321001, 8464.703735369232, 8498.53258070812, 8508.021577518664, 7743.612819359122, 10713.0, 10713.142948564253, 11537.0, 11653.76692466163], "value": 9656.164643109285, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1348.379745317154, 1427.2221639206625, 1444.6821699226166, 1445.8409574946754, 1455.1196526101708, 7495.138059123203, 7759.301662850342, 8003.799905002371, 8457.78855528612, 8461.746147615573, 7954.920450795505, 8174.26431621154, 8223.506589604627, 8326.542040187787, 8344.791380215498, 2256.2892688803017, 2299.8965046572944, 2304.9193278235266, 2307.0310469602723, 2317.1079134533243, 2042.9693554596693, 2129.244118337991, 2296.276672848056, 2335.6730057791924, 2341.0752752662775, 2204.206485665159, 2302.251768175341, 2304.873231972241, 2315.8420789605198, 2319.3042087373806, 1995.8025184889095, 2295.931122066341, 2296.184854376689, 2312.1329501436962, 2324.8140148788057, 2208.4147700859285, 2289.0843662534953, 2293.9885300573555, 2336.5326934613145, 2339.5905716499547, 7192.928070719278, 8367.0, 8399.244068033906, 8416.957915210445, 8424.578771061422, 5075.492450754932, 5235.293235413209, 5238.345206849144, 5242.842714718565, 5247.895042099175, 3251.430999575065, 3438.2435864109857, 3448.2241495663525, 3490.1972546314237, 3523.594786599548, 1169.9005584525326, 1189.1794661683412, 1201.633501781956, 1211.0977321895245, 1223.9082068844784, 9289.814203715956, 10444.164466842634, 10695.251332406735, 10775.622853200179, 10821.999999999922, 4373.343998400202, 4626.699264547794, 4678.426865224181, 4694.460137084279, 4706.670533062721, 9379.296552758571, 9474.95262523683, 9522.714318570454, 9545.61817527305, 9550.949395566482, 5238.711870847063, 5248.711320877312, 5252.343457067866, 5252.816151434681, 5259.8422047338645, 4334.566543345641, 4635.629149668019, 4744.478107408182, 4824.445188803333, 4832.106060378841, 3185.8566364513654, 3788.981055094762, 3890.3386424307937, 3915.1190982029384, 3959.48526691534, 1164.8427462292652, 1198.748262864808, 1224.381687247934, 1257.7484503099233, 1258.8929940955122, 911.1708345405649, 922.077922077922, 943.2973482863497, 944.8488241881404, 969.2536746705139, 5457.208704737791, 5579.69311687861, 5678.943210567842, 5746.942530574641, 5810.622309549865, 974.7368210583103, 992.7071513903416, 1010.8685870836891, 1020.6478764826123, 1059.1473863539925, 3677.5770786359376, 3824.0248736572, 3904.72666913319, 3926.1362500249897, 3941.152652179795, 9810.509474526245, 10031.347962382422, 10153.898461015444, 10154.0, 10155.847662285072, 9882.209423246122, 9931.900680993242, 10046.04562566555, 10085.394876307446, 10101.797964040825, 7812.179721129313, 7813.80465488356, 7916.485428447132, 7951.920480795234, 7980.281774640311, 5481.780728770805, 5581.302337207849, 5631.943680563306, 5644.068728659725, 5691.861627674399, 9225.769355766159, 9311.022342654194, 9335.953320233355, 9339.20616747584, 9340.019297973615, 9005.189532942066, 9360.859587106335, 9396.295277854051, 9407.294452915921, 9517.762055948657, 7092.822679432849, 7254.6735396908325, 7264.019357386685, 7264.89102663471, 7270.163931147986, 7804.8829267559895, 7858.44990850635, 7863.292303692695, 7917.7624671259955, 7949.1255961843, 6606.174228221473, 7250.0, 7407.444441667004, 7484.139323977813, 7612.923870761221, 7286.708531658886, 7299.5620262784405, 7320.96339518299, 7367.852642947219, 7453.142888567667, 6407.519436042408, 6409.935900640934, 6419.871602567829, 6433.228012638513, 6464.288928217987, 5027.522385373383, 5027.64806463544, 5066.03745288394, 5074.619403544675, 5152.227165924991, 5011.4236862761245, 5014.7994080236385, 5018.322526459027, 5120.462351453044, 5178.637495375438], "value": 4441.647587636485, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.8249489038693, 346.4647120199292, 366.4924080148993, 368.28001257541473, 378.1340729728921, 4550.49944506104, 4740.597049250813, 5390.353157621086, 5496.340439147298, 5499.670019798815, 4113.876583702487, 4661.883452913678, 5192.350956130484, 5327.147656374981, 5472.616916815825, 3525.682688558036, 3911.4575525908103, 3914.745541539791, 4247.490301163864, 4285.742855428669, 3583.552055993001, 4098.446709694198, 4172.3532852407925, 4241.533431322552, 4257.7445353278745, 4193.0, 4217.198732240864, 4227.936580951288, 4245.532991370962, 4299.0, 3339.7662163648674, 3663.670269675729, 4275.657947364203, 4281.272183728774, 4322.697411181219, 3354.3459025490038, 3553.9466907996402, 3713.9814300928592, 3725.2549490102087, 3765.491658626078, 4229.894252643678, 5265.552428043583, 5404.4595540446035, 5406.972675191741, 5453.481919217668, 4702.835400760991, 4863.902721945577, 4894.5105489451125, 4941.0, 4961.5534601886, 4784.736839473828, 5522.889542209174, 5735.942640573624, 5791.913121303141, 5796.8840623187725, 3709.77741335518, 4153.816162393733, 4163.854265100737, 4176.310908700061, 4258.339957306623, 5778.59549831512, 6036.637801731867, 6067.7269522871575, 6113.868934247179, 6129.682118344577, 3163.636181839118, 3573.4282514797505, 3581.8567257309414, 3653.214558869856, 4206.558311377323, 3707.6107008763693, 3984.143409167043, 3996.980015099906, 4019.577944315863, 4059.837606495707, 3749.9625003750157, 4018.799060046986, 4364.934525982113, 4616.953830461653, 4680.859574212779, 5016.347874776256, 5113.159262665437, 5134.127198376285, 5493.725313734298, 5785.392533783976, 1167.8598568171874, 1244.5830646733446, 1270.7522033203436, 1272.4210484229654, 1291.4124073546514, 726.6947881889619, 734.1190571314444, 747.9551226926402, 756.5838788666211, 882.2765332427348, 4280.72175308604, 5051.570616497639, 5076.695398276115, 5115.18157094863, 5266.0, 5818.8254352369495, 5917.763289468459, 5962.720503722935, 5993.534816561602, 6010.54920880927, 4770.284457331359, 4949.985253023122, 5282.656627319212, 5361.222622719762, 5437.48343907336, 2747.1566928229186, 2880.510313246753, 2937.296368106493, 2937.5446805745146, 2948.231061363165, 4760.45254795696, 5244.947550524522, 5783.2529548464145, 5826.359100498942, 5847.8012007538355, 4903.583195428429, 5484.588655850827, 5612.691301978431, 5793.914190891276, 5797.420257974253, 4714.952850471452, 4800.73595952226, 5139.794408223629, 5331.600129990189, 5787.913181302284, 4940.728259945738, 5549.7780088796, 5607.046802043744, 5697.544196464356, 5708.400617935057, 4684.906301873875, 4760.881407773508, 5301.3373328333955, 5398.838034858961, 5539.365887063406, 5668.37647858727, 5948.226730525004, 5949.608723028005, 6044.546659000504, 6338.936610634019, 3979.900502487345, 3993.221321842213, 4009.3785463253244, 4022.3966405039478, 4024.597540245952, 5671.051053452265, 5726.627769194989, 5803.053923332006, 5839.562032847469, 5998.25021872266, 4883.0, 4995.525425084611, 5228.790848366023, 5375.865603359791, 5476.342838859203, 5100.515451032146, 5317.415084340797, 5755.079407106863, 5851.853703657443, 5871.324797648325, 4639.652026098123, 4959.032988567339, 5202.439268219595, 5386.649867758583, 5622.8313150605545, 5075.695458272367, 5586.229295225788, 5661.631993920382, 5827.737751801094, 5877.853053673522, 4955.984023275148, 5210.577081656896, 5458.890822183614, 5618.410066943075, 5729.6508431544535], "value": 4102.389605129252, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.9153810004699, 679.8300424893777, 695.7495301691393, 709.4182770128496, 760.1030783675262, 14438.855611443882, 15399.999000065001, 15419.997700149483, 17049.82950170498, 17512.387066452677, 16604.418845340417, 16782.328706851713, 16785.160741962914, 16799.664006719857, 16806.32774689014, 624.3756243756244, 681.7034589953386, 863.9697610583631, 886.02979737188, 891.2201823404521, 782.5774081995744, 865.709987154304, 875.0, 882.0826340605762, 882.5013867165063, 898.4339865884483, 916.7937214126835, 918.0727465260102, 918.2837386838277, 919.3702313914989, 909.5134103254727, 909.5679552212679, 910.649399981012, 911.5806728904712, 912.370464379576, 679.4055201698513, 683.8187880211748, 684.090160087083, 684.4114061906756, 685.2188505104185, 12681.93659031699, 15253.403801926279, 15452.15013174275, 15478.690426191413, 15600.29798659063, 11914.940425297904, 13757.243351615758, 13949.511767088205, 14131.71736565263, 14185.858141418557, 11599.246049006788, 11768.646940591796, 11812.468438920183, 11917.82123268143, 12279.508819647204, 3602.0, 3622.7464077514583, 3688.0779805048737, 3721.6092310307567, 3791.2796568652143, 85153.0, 107594.46202769014, 108794.91205087927, 109796.4510177444, 111312.99999999919, 10599.788004239872, 10713.464326783629, 10768.22324316479, 10890.727731806768, 11088.611898583511, 22042.779572204072, 22271.77728222697, 22477.88761056217, 22500.662490062663, 22574.435639108826, 7198.92801071993, 7594.354479869163, 7614.200508946481, 7716.5370077795515, 7759.495632783851, 4187.560306167869, 4210.87367378973, 4285.742855428684, 4307.870763877088, 4371.191329604034, 3572.91067723309, 3632.509611202454, 3659.9817000914827, 3660.6888414484533, 3668.413053911361, 649.256601191633, 652.3150691773619, 657.9539432239698, 665.3512824995687, 667.6127845849396, 674.6250565085417, 702.1749444402807, 704.2816327346078, 719.6078137415121, 739.3789217057697, 10073.345232560006, 10192.592296308212, 10256.461500796908, 10362.0, 10403.84394234087, 686.5160062156168, 693.6497068980133, 715.3454589051064, 718.2996578336185, 718.8957601147804, 4658.534146585314, 4934.605231581465, 4937.728424936591, 4958.57852082577, 4963.181075122564, 91775.0, 101050.0, 103528.48235758924, 108208.0, 110023.0, 66216.66891665607, 90156.0, 90499.73750656155, 90724.0, 91894.0, 71236.86289411326, 73142.63428682928, 75776.24223757912, 76547.0, 79703.60148199221, 10758.784824303626, 10902.44340998544, 11087.22389432732, 11149.442527873736, 11154.442277886235, 47199.76400117978, 48255.75872120617, 50514.747426262635, 50725.49274507208, 51618.741906290226, 67732.661336693, 68604.6569767148, 69590.0, 69906.0, 72205.0, 12924.612261632166, 12935.288559129338, 12982.740345193231, 13079.280639564535, 13142.211467311992, 14504.709905802036, 14779.039362441406, 14995.850041499445, 15154.772678410052, 15280.61798455003, 13606.591802245948, 13794.793078103636, 13858.584242472742, 13925.373358198703, 14290.785638215642, 11998.460069297078, 12030.63908082759, 12037.458314375699, 12445.502179913065, 12600.495980160691, 13268.0, 13394.93302533481, 13542.458301667823, 13589.184648921097, 13683.726325473634, 10868.61959831402, 11061.668149955514, 11078.335299882034, 11264.267822591506, 11524.193306468802, 8772.81566988474, 9032.915540591002, 9997.850247221371, 10835.76820670321, 11401.714957126138], "value": 7547.950356629146, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1583.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 116.0, 116.0, 116.0, 114.0, 117.0, 115.0, 117.0, 116.0, 118.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 143.0, 148.0, 132.0, 129.0, 146.0, 145.0, 129.0, 128.0, 114.0], "value": 139.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 211.0, 212.0, 202.0, 203.0, 263.0, 271.0, 196.0, 194.0, 194.0], "value": 214.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 61.0, 85.0, 60.0, 59.0, 59.0, 59.0, 64.0, 54.0], "value": 64.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 87.0, 100.0, 102.0, 99.0, 87.0, 71.0, 72.0, 82.0], "value": 86.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 132.0, 90.0, 91.0, 91.0, 86.0, 90.0, 87.0, 89.0, 86.0], "value": 96.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 151.0, 149.0, 98.0, 96.0, 125.0, 152.0, 95.0, 96.0, 95.0], "value": 115.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 92.0, 91.0, 97.0, 92.0, 91.0, 97.0, 92.0, 94.0], "value": 93.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 61.0, 63.0, 62.0, 61.0, 61.0, 63.0, 62.0, 63.0], "value": 61.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 70.0, 87.0, 80.0, 79.0, 82.0, 71.0, 65.0, 100.0], "value": 78.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 116.0, 88.0, 86.0, 93.0, 108.0, 115.0, 83.0, 92.0], "value": 96.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 142.0, 140.0, 143.0, 154.0, 136.0, 159.0, 158.0, 189.0, 159.0], "value": 152.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 176.0, 173.0, 222.0, 246.0, 219.0, 240.0, 192.0, 211.0, 174.0], "value": 202.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 80.0, 73.0, 60.0, 63.0, 59.0, 60.0, 85.0, 59.0], "value": 68.0, "unit": "score"}]}]} 16:55:22 INFO - Return code: 0 16:55:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:55:22 ERROR - # TBPL SUCCESS # 16:55:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:55:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:55:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:55:22 INFO - 2017-01-12 16:55:22.122 screenresolution[6894:123819] starting screenresolution argv=screenresolution get 16:55:22 INFO - 2017-01-12 16:55:22.125 screenresolution[6894:123819] Display 0: 1600x1200x32@60 16:55:22 INFO - 2017-01-12 16:55:22.131 screenresolution[6895:123822] starting screenresolution argv=screenresolution list 16:55:22 INFO - Available Modes on Display 0 16:55:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:55:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:55:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:55:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:55:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:55:22 INFO - Intel Iris: 16:55:22 INFO - Chipset Model: Intel Iris 16:55:22 INFO - Type: GPU 16:55:22 INFO - Bus: Built-In 16:55:22 INFO - VRAM (Dynamic, Max): 1536 MB 16:55:22 INFO - Vendor: Intel (0x8086) 16:55:22 INFO - Device ID: 0x0a2e 16:55:22 INFO - Revision ID: 0x0009 16:55:22 INFO - Displays: 16:55:22 INFO - Display: 16:55:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:55:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:55:22 INFO - Display Serial Number: 1600x1200 60 16:55:22 INFO - Main Display: Yes 16:55:22 INFO - Mirror: Off 16:55:22 INFO - Online: Yes 16:55:22 INFO - Rotation: Supported 16:55:22 INFO - Return code: 0 16:55:22 INFO - Running post-action listener: _resource_record_post_action 16:55:22 INFO - [mozharness: 2017-01-13 00:55:22.367156Z] Finished run-tests step (success) 16:55:22 INFO - Running post-run listener: _resource_record_post_run 16:55:22 INFO - Total resource usage - Wall time: 1200s; CPU: 54.0%; Read bytes: 180736; Write bytes: 554160128; Read time: 2; Write time: 1984 16:55:22 INFO - TinderboxPrint: CPU usage
54.5% 16:55:22 INFO - TinderboxPrint: I/O read bytes / time
180,736 / 2 16:55:22 INFO - TinderboxPrint: I/O write bytes / time
554,160,128 / 1,984 16:55:22 INFO - TinderboxPrint: CPU idle
2,186.5 (45.5%) 16:55:22 INFO - TinderboxPrint: CPU system
971.0 (20.2%) 16:55:22 INFO - TinderboxPrint: CPU user
1,644.2 (34.2%) 16:55:22 INFO - TinderboxPrint: Swap in / out
250,736,640 / 0 16:55:22 INFO - install - Wall time: 23s; CPU: 55.0%; Read bytes: 230496768; Write bytes: 226076672; Read time: 16881; Write time: 460 16:55:22 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 180224; Write bytes: 299042816; Read time: 1; Write time: 1471 16:55:22 INFO - Running post-run listener: _upload_blobber_files 16:55:22 INFO - Blob upload gear active. 16:55:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:55:22 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 16:55:22 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'] 16:55:22 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 16:55:23 INFO - (blobuploader) - INFO - Open directory for files ... 16:55:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:55:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:55:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:55:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:55:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:55:23 INFO - (blobuploader) - INFO - Done attempting. 16:55:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:55:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:55:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:55:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:55:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:55:24 INFO - (blobuploader) - INFO - Done attempting. 16:55:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:55:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:55:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:55:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:55:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:55:25 INFO - (blobuploader) - INFO - Done attempting. 16:55:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:55:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:55:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:55:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:55:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:55:28 INFO - (blobuploader) - INFO - Done attempting. 16:55:28 INFO - (blobuploader) - INFO - Iteration through files over. 16:55:28 INFO - Return code: 0 16:55:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:55:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:55:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/243e2086aacc851a1aab1211a3e9bdb12afbcac4b4aa14648d7f9b348275f68b333fc78b2aa6880cc438efbccc15956bb883bc5018eca14f611fc5ca845e335e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cfb723b419fae0b319fb614243724a51b30a511b09e09b9b69a5a33bfd805965e643d902498f6ca454a763b09058dedbaf665f53a0d7e3d0167c0b547dc0e51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3231704f79ed071feef3facd55bf8b4adf1da3d6243ed7f3f2e2fd4577fed8483793979363d9404cc4dbf926a4812728d468cbc311b8eb5ed4deb4d3ec88ebb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abbb828d6975ba8f6b502c2d41c25c1ac90e36aee43f02519d39a38577477f0fae71509509cd5922b4e3e67b001deee62ebd424145ba2654f3e7bc64258d4317"} 16:55:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:55:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:55:28 INFO - Contents: 16:55:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/243e2086aacc851a1aab1211a3e9bdb12afbcac4b4aa14648d7f9b348275f68b333fc78b2aa6880cc438efbccc15956bb883bc5018eca14f611fc5ca845e335e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cfb723b419fae0b319fb614243724a51b30a511b09e09b9b69a5a33bfd805965e643d902498f6ca454a763b09058dedbaf665f53a0d7e3d0167c0b547dc0e51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3231704f79ed071feef3facd55bf8b4adf1da3d6243ed7f3f2e2fd4577fed8483793979363d9404cc4dbf926a4812728d468cbc311b8eb5ed4deb4d3ec88ebb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abbb828d6975ba8f6b502c2d41c25c1ac90e36aee43f02519d39a38577477f0fae71509509cd5922b4e3e67b001deee62ebd424145ba2654f3e7bc64258d4317"} 16:55:28 INFO - Running post-run listener: copy_logs_to_upload_dir 16:55:28 INFO - Copying logs to upload dir... 16:55:28 INFO - mkdir: /builds/slave/test/build/upload/logs 16:55:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.555698 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-01-12 16:55:28.322709) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 16:55:28.326287) ========= 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.tV1nNWvDi2/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.53gFnKQx1q/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/243e2086aacc851a1aab1211a3e9bdb12afbcac4b4aa14648d7f9b348275f68b333fc78b2aa6880cc438efbccc15956bb883bc5018eca14f611fc5ca845e335e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cfb723b419fae0b319fb614243724a51b30a511b09e09b9b69a5a33bfd805965e643d902498f6ca454a763b09058dedbaf665f53a0d7e3d0167c0b547dc0e51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3231704f79ed071feef3facd55bf8b4adf1da3d6243ed7f3f2e2fd4577fed8483793979363d9404cc4dbf926a4812728d468cbc311b8eb5ed4deb4d3ec88ebb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abbb828d6975ba8f6b502c2d41c25c1ac90e36aee43f02519d39a38577477f0fae71509509cd5922b4e3e67b001deee62ebd424145ba2654f3e7bc64258d4317"} build_url:https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011350 build_url: 'https://queue.taskcluster.net/v1/task/IKq_leMyQ3WA-3p6w09RHg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/243e2086aacc851a1aab1211a3e9bdb12afbcac4b4aa14648d7f9b348275f68b333fc78b2aa6880cc438efbccc15956bb883bc5018eca14f611fc5ca845e335e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9cfb723b419fae0b319fb614243724a51b30a511b09e09b9b69a5a33bfd805965e643d902498f6ca454a763b09058dedbaf665f53a0d7e3d0167c0b547dc0e51", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3231704f79ed071feef3facd55bf8b4adf1da3d6243ed7f3f2e2fd4577fed8483793979363d9404cc4dbf926a4812728d468cbc311b8eb5ed4deb4d3ec88ebb3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/abbb828d6975ba8f6b502c2d41c25c1ac90e36aee43f02519d39a38577477f0fae71509509cd5922b4e3e67b001deee62ebd424145ba2654f3e7bc64258d4317"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-12 16:55:28.360890) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 16:55:28.361217) ========= 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.tV1nNWvDi2/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.53gFnKQx1q/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.004693 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-12 16:55:28.410777) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 16:55:28.411258) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-12 16:55:28.411657) ========= ========= Total master_lag: 0.80 =========