builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0319 starttime: 1483448013.86 results: success (0) buildid: 20170103033820 builduid: 9bd5333fec204ce39ed7fb2f110b96e3 revision: 232424f9511d61179912ccaf5b169fb45f05599a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 04:53:33.861723) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-03 04:53:33.862201) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 04:53:33.862481) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-03 04:53:33.905259) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 04:53:33.905689) ========= 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.5yQwM5LAWf/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.md5pPs2FOo/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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-03 04:53:33-- 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% 19.9M=0.001s 2017-01-03 04:53:34 (19.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134285 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 04:53:34.066959) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 04:53:34.067242) ========= 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.5yQwM5LAWf/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.md5pPs2FOo/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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.027104 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 04:53:34.135219) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-03 04:53:34.135537) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 232424f9511d61179912ccaf5b169fb45f05599a --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 232424f9511d61179912ccaf5b169fb45f05599a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5yQwM5LAWf/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.md5pPs2FOo/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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-03 04:53:34,241 truncating revision to first 12 chars 2017-01-03 04:53:34,241 Setting DEBUG logging. 2017-01-03 04:53:34,241 attempt 1/10 2017-01-03 04:53:34,241 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/232424f9511d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-03 04:53:35,243 unpacking tar archive at: mozilla-central-232424f9511d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.267369 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-03 04:53:35.433661) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 04:53:35.434308) ========= script_repo_revision: 232424f9511d61179912ccaf5b169fb45f05599a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-03 04:53:35.434815) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 04:53:35.435170) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-03 04:53:35.455818) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-03 04:53:35.456476) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5yQwM5LAWf/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.md5pPs2FOo/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:53:35 INFO - MultiFileLogger online at 20170103 04:53:35 in /builds/slave/test 04:53:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 04:53:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:53:35 INFO - {'append_to_log': False, 04:53:35 INFO - 'base_work_dir': '/builds/slave/test', 04:53:35 INFO - 'blob_upload_branch': 'Firefox', 04:53:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:53:35 INFO - 'branch': 'Firefox', 04:53:35 INFO - 'buildbot_json_path': 'buildprops.json', 04:53:35 INFO - 'config_files': ('talos/mac_config.py',), 04:53:35 INFO - 'default_actions': ('clobber', 04:53:35 INFO - 'read-buildbot-config', 04:53:35 INFO - 'download-and-extract', 04:53:35 INFO - 'populate-webroot', 04:53:35 INFO - 'create-virtualenv', 04:53:35 INFO - 'install', 04:53:35 INFO - 'run-tests'), 04:53:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:53:35 INFO - 'download_minidump_stackwalk': True, 04:53:35 INFO - 'download_symbols': 'ondemand', 04:53:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:53:35 INFO - 'tooltool.py': '/tools/tooltool.py', 04:53:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:53:35 INFO - '/tools/misc-python/virtualenv.py')}, 04:53:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:53:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:53:35 INFO - 'installer_path': 'installer.exe', 04:53:35 INFO - 'log_level': 'info', 04:53:35 INFO - 'log_name': 'talos', 04:53:35 INFO - 'log_to_console': True, 04:53:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:53:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:53:35 INFO - 'opt_config_files': (), 04:53:35 INFO - 'pip_index': False, 04:53:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:53:35 INFO - 'cmd': ('bash', 04:53:35 INFO - '-c', 04:53:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:53:35 INFO - 'enabled': True, 04:53:35 INFO - 'halt_on_failure': False, 04:53:35 INFO - 'name': 'check_screen_resolution'},), 04:53:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:53:35 INFO - 'cmd': ('bash', 04:53:35 INFO - '-c', 04:53:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:53:35 INFO - 'enabled': True, 04:53:35 INFO - 'halt_on_failure': False, 04:53:35 INFO - 'name': 'check_screen_resolution'},), 04:53:35 INFO - 'run_cmd_checks_enabled': True, 04:53:35 INFO - 'sps_profile': False, 04:53:35 INFO - 'sps_profile_interval': 0, 04:53:35 INFO - 'suite': 'dromaeojs', 04:53:35 INFO - 'system_bits': '32', 04:53:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:53:35 INFO - 'title': 't-yosemite-r7-0319', 04:53:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:53:35 INFO - 'use_talos_json': True, 04:53:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:53:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:53:35 INFO - 'work_dir': 'build'} 04:53:35 INFO - [mozharness: 2017-01-03 12:53:35.667810Z] Running clobber step. 04:53:35 INFO - Running pre-action listener: _resource_record_pre_action 04:53:35 INFO - Running main action method: clobber 04:53:35 INFO - rmtree: /builds/slave/test/build 04:53:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:53:37 INFO - Running post-action listener: _resource_record_post_action 04:53:37 INFO - [mozharness: 2017-01-03 12:53:37.992480Z] Finished clobber step (success) 04:53:37 INFO - [mozharness: 2017-01-03 12:53:37.992748Z] Running read-buildbot-config step. 04:53:37 INFO - Running pre-action listener: _resource_record_pre_action 04:53:37 INFO - Running main action method: read_buildbot_config 04:53:37 INFO - Using buildbot properties: 04:53:37 INFO - { 04:53:37 INFO - "project": "", 04:53:37 INFO - "product": "firefox", 04:53:37 INFO - "script_repo_revision": "production", 04:53:37 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:53:37 INFO - "repository": "", 04:53:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:53:37 INFO - "buildid": "20170103033820", 04:53:37 INFO - "pgo_build": "False", 04:53:37 INFO - "basedir": "/builds/slave/test", 04:53:37 INFO - "buildnumber": 66, 04:53:37 INFO - "slavename": "t-yosemite-r7-0319", 04:53:37 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 04:53:37 INFO - "platform": "yosemite_r7", 04:53:37 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:53:37 INFO - "branch": "mozilla-central", 04:53:37 INFO - "repo_path": "mozilla-central", 04:53:37 INFO - "revision": "232424f9511d61179912ccaf5b169fb45f05599a", 04:53:37 INFO - "stage_platform": "macosx64", 04:53:37 INFO - "builduid": "9bd5333fec204ce39ed7fb2f110b96e3", 04:53:37 INFO - "slavebuilddir": "test" 04:53:37 INFO - } 04:53:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 04:53:37 INFO - Running post-action listener: _resource_record_post_action 04:53:37 INFO - [mozharness: 2017-01-03 12:53:37.998625Z] Finished read-buildbot-config step (success) 04:53:37 INFO - [mozharness: 2017-01-03 12:53:37.998832Z] Running download-and-extract step. 04:53:37 INFO - Running pre-action listener: _resource_record_pre_action 04:53:37 INFO - Running main action method: download_and_extract 04:53:37 INFO - mkdir: /builds/slave/test/build/tests 04:53:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:38 INFO - trying https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 04:53:38 INFO - Downloading https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:53:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 04:53:39 INFO - Downloaded 1199 bytes. 04:53:39 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 04:53:39 INFO - Using the following test package requirements: 04:53:39 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:53:39 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 04:53:39 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 04:53:39 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'jsshell-mac.zip'], 04:53:39 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 04:53:39 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 04:53:39 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 04:53:39 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 04:53:39 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 04:53:39 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 04:53:39 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 04:53:39 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:53:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:53:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:53:39 INFO - Fetch https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:53:40 INFO - Expected file size: 16300475 04:53:40 INFO - Obtained file size: 16300475 04:53:46 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 04:53:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 04:53:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:53:46 INFO - Fetch https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 04:53:47 INFO - Expected file size: 16300475 04:53:47 INFO - Obtained file size: 16300475 04:53:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 04:53:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:53:53 INFO - Fetch https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 04:53:54 INFO - Expected file size: 14409776 04:53:54 INFO - Obtained file size: 14409776 04:53:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:53:55 INFO - trying https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:53:55 INFO - Downloading https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 04:53:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 04:53:57 INFO - Downloaded 69758737 bytes. 04:53:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:53:57 INFO - mkdir: /builds/slave/test/properties 04:53:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:53:57 INFO - Writing to file /builds/slave/test/properties/build_url 04:53:57 INFO - Contents: 04:53:57 INFO - build_url:https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 04:53:57 INFO - Running post-action listener: _resource_record_post_action 04:53:57 INFO - Running post-action listener: set_extra_try_arguments 04:53:57 INFO - [mozharness: 2017-01-03 12:53:57.791049Z] Finished download-and-extract step (success) 04:53:57 INFO - [mozharness: 2017-01-03 12:53:57.791210Z] Running populate-webroot step. 04:53:57 INFO - Running pre-action listener: _resource_record_pre_action 04:53:57 INFO - Running main action method: populate_webroot 04:53:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:53:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:53:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:53:57 INFO - '--tppagecycles', 04:53:57 INFO - '7'], 04:53:57 INFO - 'tests': ['tsvgm']}}, 04:53:57 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:53:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:53:57 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:53:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:53:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:53:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:53:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:53:57 INFO - 'talos_options': ['--disable-e10s'], 04:53:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:53:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:53:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:53:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:53:57 INFO - 'talos_options': ['--disable-e10s'], 04:53:57 INFO - 'tests': ['damp', 'tps']}, 04:53:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:53:57 INFO - 'tests': ['damp', 'tps']}, 04:53:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:53:57 INFO - 'tests': ['dromaeo_dom']}, 04:53:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:53:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:53:57 INFO - 'tests': ['basic_compositor_video']}, 04:53:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:53:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:53:57 INFO - 'tests': ['a11yr', 04:53:57 INFO - 'ts_paint', 04:53:57 INFO - 'tpaint', 04:53:57 INFO - 'sessionrestore', 04:53:57 INFO - 'sessionrestore_no_auto_restore', 04:53:57 INFO - 'tabpaint']}, 04:53:57 INFO - 'other-e10s': {'tests': ['a11yr', 04:53:57 INFO - 'ts_paint', 04:53:57 INFO - 'tpaint', 04:53:57 INFO - 'sessionrestore', 04:53:57 INFO - 'sessionrestore_no_auto_restore', 04:53:57 INFO - 'tabpaint']}, 04:53:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:53:57 INFO - 'ts_paint', 04:53:57 INFO - 'tpaint', 04:53:57 INFO - 'sessionrestore', 04:53:57 INFO - 'sessionrestore_no_auto_restore', 04:53:57 INFO - 'tabpaint']}, 04:53:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:53:57 INFO - 'ts_paint', 04:53:57 INFO - 'tpaint', 04:53:57 INFO - 'sessionrestore', 04:53:57 INFO - 'sessionrestore_no_auto_restore', 04:53:57 INFO - 'tabpaint']}, 04:53:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:53:57 INFO - 'tests': ['a11yr', 04:53:57 INFO - 'ts_paint', 04:53:57 INFO - 'tpaint', 04:53:57 INFO - 'sessionrestore', 04:53:57 INFO - 'sessionrestore_no_auto_restore', 04:53:57 INFO - 'tabpaint']}, 04:53:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:53:57 INFO - 'tests': ['a11yr', 04:53:57 INFO - 'ts_paint', 04:53:57 INFO - 'tpaint', 04:53:57 INFO - 'sessionrestore', 04:53:57 INFO - 'sessionrestore_no_auto_restore', 04:53:57 INFO - 'tabpaint']}, 04:53:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:53:57 INFO - 'tests': ['tsvgx', 04:53:57 INFO - 'tsvgr_opacity', 04:53:57 INFO - 'tart', 04:53:57 INFO - 'tscrollx', 04:53:57 INFO - 'cart', 04:53:57 INFO - 'tsvg_static']}, 04:53:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:53:57 INFO - 'tsvgr_opacity', 04:53:57 INFO - 'tart', 04:53:57 INFO - 'tscrollx', 04:53:57 INFO - 'cart', 04:53:57 INFO - 'tsvg_static']}, 04:53:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:53:57 INFO - 'talos_options': ['--disable-e10s'], 04:53:57 INFO - 'tests': ['tp5o']}, 04:53:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:53:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:53:57 INFO - 'talos_options': ['--disable-e10s', 04:53:57 INFO - '--xperf_path', 04:53:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:53:57 INFO - 'tests': ['tp5n']}, 04:53:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:53:57 INFO - 'talos_options': ['--xperf_path', 04:53:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:53:57 INFO - 'tests': ['tp5n']}}, 04:53:57 INFO - 'talos.zip': {'path': '', 04:53:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:53:57 INFO - Running post-action listener: _resource_record_post_action 04:53:57 INFO - [mozharness: 2017-01-03 12:53:57.809565Z] Finished populate-webroot step (success) 04:53:57 INFO - [mozharness: 2017-01-03 12:53:57.809662Z] Running create-virtualenv step. 04:53:57 INFO - Running pre-action listener: _resource_record_pre_action 04:53:57 INFO - Running main action method: create_virtualenv 04:53:57 INFO - Creating virtualenv /builds/slave/test/build/venv 04:53:57 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:53:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:53:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:53:57 INFO - Using real prefix '/tools/python27' 04:53:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:53:58 INFO - Installing distribute.............................................................................................................................................................................................done. 04:54:01 INFO - Installing pip.................done. 04:54:01 INFO - Return code: 0 04:54:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:54:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:54:01 INFO - Reading from file tmpfile_stdout 04:54:01 INFO - Output received: 04:54:01 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:54:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:01 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 0x108929b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10892c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a494b6d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108707d30>, '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 0x10862edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086fcbe8>, '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.5yQwM5LAWf/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.md5pPs2FOo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:54:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:54:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5yQwM5LAWf/Render', 04:54:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:01 INFO - 'HOME': '/Users/cltbld', 04:54:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:01 INFO - 'LOGNAME': 'cltbld', 04:54:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:01 INFO - 'MOZ_NO_REMOTE': '1', 04:54:01 INFO - 'NO_EM_RESTART': '1', 04:54:01 INFO - 'PAGER': '/bin/cat', 04:54:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:01 INFO - 'PWD': '/builds/slave/test', 04:54:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:01 INFO - 'SHELL': '/bin/bash', 04:54:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.md5pPs2FOo/Listeners', 04:54:01 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 04:54:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:01 INFO - 'USER': 'cltbld', 04:54:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:01 INFO - 'XPC_FLAGS': '0x0', 04:54:01 INFO - 'XPC_SERVICE_NAME': '0', 04:54:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:54:02 INFO - Cleaning up... 04:54:02 INFO - Return code: 0 04:54:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:54:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:02 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 0x108929b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10892c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a494b6d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108707d30>, '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 0x10862edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086fcbe8>, '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.5yQwM5LAWf/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.md5pPs2FOo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 04:54:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 04:54:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5yQwM5LAWf/Render', 04:54:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:02 INFO - 'HOME': '/Users/cltbld', 04:54:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:02 INFO - 'LOGNAME': 'cltbld', 04:54:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:02 INFO - 'MOZ_NO_REMOTE': '1', 04:54:02 INFO - 'NO_EM_RESTART': '1', 04:54:02 INFO - 'PAGER': '/bin/cat', 04:54:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:02 INFO - 'PWD': '/builds/slave/test', 04:54:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:02 INFO - 'SHELL': '/bin/bash', 04:54:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.md5pPs2FOo/Listeners', 04:54:02 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 04:54:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:02 INFO - 'USER': 'cltbld', 04:54:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:02 INFO - 'XPC_FLAGS': '0x0', 04:54:02 INFO - 'XPC_SERVICE_NAME': '0', 04:54:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:02 INFO - Downloading/unpacking psutil>=3.1.1 04:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:54:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:54:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:54:06 INFO - Installing collected packages: psutil 04:54:06 INFO - Running setup.py install for psutil 04:54:06 INFO - building 'psutil._psutil_osx' extension 04:54:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:54:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:54:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:54:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:54:07 INFO - building 'psutil._psutil_posix' extension 04:54:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:54:07 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:54:07 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:54:07 INFO - ^ 04:54:07 INFO - 1 warning generated. 04:54:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:54:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:54:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:54:07 INFO - Successfully installed psutil 04:54:07 INFO - Cleaning up... 04:54:07 INFO - Return code: 0 04:54:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:07 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 0x108929b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10892c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a494b6d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108707d30>, '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 0x10862edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086fcbe8>, '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.5yQwM5LAWf/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.md5pPs2FOo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:54:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:54:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5yQwM5LAWf/Render', 04:54:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:07 INFO - 'HOME': '/Users/cltbld', 04:54:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:07 INFO - 'LOGNAME': 'cltbld', 04:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:07 INFO - 'MOZ_NO_REMOTE': '1', 04:54:07 INFO - 'NO_EM_RESTART': '1', 04:54:07 INFO - 'PAGER': '/bin/cat', 04:54:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:07 INFO - 'PWD': '/builds/slave/test', 04:54:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:07 INFO - 'SHELL': '/bin/bash', 04:54:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.md5pPs2FOo/Listeners', 04:54:07 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 04:54:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:07 INFO - 'USER': 'cltbld', 04:54:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:07 INFO - 'XPC_FLAGS': '0x0', 04:54:07 INFO - 'XPC_SERVICE_NAME': '0', 04:54:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:54: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 04:54: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 04:54: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 04:54: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 04:54: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 04:54: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 04:54:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:54:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:54:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:54:10 INFO - Installing collected packages: mozsystemmonitor 04:54:10 INFO - Running setup.py install for mozsystemmonitor 04:54:10 INFO - Successfully installed mozsystemmonitor 04:54:10 INFO - Cleaning up... 04:54:10 INFO - Return code: 0 04:54:10 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:54:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108929b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10892c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a494b6d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108707d30>, '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 0x10862edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086fcbe8>, '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.5yQwM5LAWf/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.md5pPs2FOo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:54:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:54:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5yQwM5LAWf/Render', 04:54:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:10 INFO - 'HOME': '/Users/cltbld', 04:54:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:10 INFO - 'LOGNAME': 'cltbld', 04:54:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:10 INFO - 'MOZ_NO_REMOTE': '1', 04:54:10 INFO - 'NO_EM_RESTART': '1', 04:54:10 INFO - 'PAGER': '/bin/cat', 04:54:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:10 INFO - 'PWD': '/builds/slave/test', 04:54:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:10 INFO - 'SHELL': '/bin/bash', 04:54:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.md5pPs2FOo/Listeners', 04:54:10 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 04:54:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:10 INFO - 'USER': 'cltbld', 04:54:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:10 INFO - 'XPC_FLAGS': '0x0', 04:54:10 INFO - 'XPC_SERVICE_NAME': '0', 04:54:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:10 INFO - Downloading/unpacking jsonschema==2.5.1 04:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:13 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:54:13 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:13 INFO - Downloading functools32-3.2.3-2.tar.gz 04:54:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:54:14 INFO - warning: no files found matching '*.txt' 04:54:14 INFO - no previously-included directories found matching 'build' 04:54:14 INFO - no previously-included directories found matching 'dist' 04:54:14 INFO - no previously-included directories found matching '.git*' 04:54:14 INFO - Installing collected packages: jsonschema, functools32 04:54:14 INFO - Running setup.py install for functools32 04:54:14 INFO - warning: no files found matching '*.txt' 04:54:14 INFO - no previously-included directories found matching 'build' 04:54:14 INFO - no previously-included directories found matching 'dist' 04:54:14 INFO - no previously-included directories found matching '.git*' 04:54:14 INFO - Successfully installed jsonschema functools32 04:54:14 INFO - Cleaning up... 04:54:14 INFO - Return code: 0 04:54:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:54: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')]} 04:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54: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')]} 04:54:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:14 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 0x108929b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10892c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a494b6d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108707d30>, '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 0x10862edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086fcbe8>, '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.5yQwM5LAWf/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.md5pPs2FOo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:54:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:54:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5yQwM5LAWf/Render', 04:54:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:14 INFO - 'HOME': '/Users/cltbld', 04:54:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:14 INFO - 'LOGNAME': 'cltbld', 04:54:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:14 INFO - 'MOZ_NO_REMOTE': '1', 04:54:14 INFO - 'NO_EM_RESTART': '1', 04:54:14 INFO - 'PAGER': '/bin/cat', 04:54:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:14 INFO - 'PWD': '/builds/slave/test', 04:54:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:14 INFO - 'SHELL': '/bin/bash', 04:54:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.md5pPs2FOo/Listeners', 04:54:14 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 04:54:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:14 INFO - 'USER': 'cltbld', 04:54:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:14 INFO - 'XPC_FLAGS': '0x0', 04:54:14 INFO - 'XPC_SERVICE_NAME': '0', 04:54:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:14 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:54:14 INFO - Cleaning up... 04:54:14 INFO - Return code: 0 04:54:14 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:54: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')]} 04:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54: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')]} 04:54:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:14 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 0x108929b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10892c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a494b6d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108707d30>, '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 0x10862edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086fcbe8>, '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.5yQwM5LAWf/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.md5pPs2FOo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:54:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:54:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5yQwM5LAWf/Render', 04:54:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:14 INFO - 'HOME': '/Users/cltbld', 04:54:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:14 INFO - 'LOGNAME': 'cltbld', 04:54:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:14 INFO - 'MOZ_NO_REMOTE': '1', 04:54:14 INFO - 'NO_EM_RESTART': '1', 04:54:14 INFO - 'PAGER': '/bin/cat', 04:54:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:14 INFO - 'PWD': '/builds/slave/test', 04:54:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:14 INFO - 'SHELL': '/bin/bash', 04:54:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.md5pPs2FOo/Listeners', 04:54:14 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 04:54:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:14 INFO - 'USER': 'cltbld', 04:54:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:14 INFO - 'XPC_FLAGS': '0x0', 04:54:14 INFO - 'XPC_SERVICE_NAME': '0', 04:54:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:14 INFO - Downloading/unpacking blobuploader==1.2.4 04:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:17 INFO - Downloading blobuploader-1.2.4.tar.gz 04:54:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:54:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:54:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:18 INFO - Downloading docopt-0.6.1.tar.gz 04:54:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:54:18 INFO - Installing collected packages: blobuploader, requests, docopt 04:54:18 INFO - Running setup.py install for blobuploader 04:54:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:54:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:54:18 INFO - Running setup.py install for requests 04:54:19 INFO - Running setup.py install for docopt 04:54:19 INFO - Successfully installed blobuploader requests docopt 04:54:19 INFO - Cleaning up... 04:54:19 INFO - Return code: 0 04:54:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:19 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 0x108929b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10892c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a494b6d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108707d30>, '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 0x10862edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086fcbe8>, '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.5yQwM5LAWf/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.md5pPs2FOo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:54:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:54:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5yQwM5LAWf/Render', 04:54:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:19 INFO - 'HOME': '/Users/cltbld', 04:54:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:19 INFO - 'LOGNAME': 'cltbld', 04:54:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:19 INFO - 'MOZ_NO_REMOTE': '1', 04:54:19 INFO - 'NO_EM_RESTART': '1', 04:54:19 INFO - 'PAGER': '/bin/cat', 04:54:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:19 INFO - 'PWD': '/builds/slave/test', 04:54:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:19 INFO - 'SHELL': '/bin/bash', 04:54:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.md5pPs2FOo/Listeners', 04:54:19 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 04:54:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:19 INFO - 'USER': 'cltbld', 04:54:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:19 INFO - 'XPC_FLAGS': '0x0', 04:54:19 INFO - 'XPC_SERVICE_NAME': '0', 04:54:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:54:19 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-CKWzX0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:54:20 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-OwS_ZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:54:20 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-b7HL3M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:54:20 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-nL2mG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:54:20 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-QK3hl2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:54:20 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-9NjdFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:54:20 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-JpCUE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:54:20 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-59wArQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:54:20 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-Qlvgl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:54:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:54:20 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-Ul8IOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:54:21 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-x6xK4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:54:21 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-K6yDzD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:54:21 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-OVj_yE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:54:21 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-5L5fS1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:21 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-KrmoYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:54:21 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-G9Kkk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:54:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:54:21 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-1LNZ5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:54:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:54:21 INFO - Running setup.py install for manifestparser 04:54:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:54:21 INFO - Running setup.py install for mozcrash 04:54:22 INFO - Running setup.py install for mozdebug 04:54:22 INFO - Running setup.py install for mozdevice 04:54:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:54:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:54:22 INFO - Running setup.py install for mozfile 04:54:22 INFO - Running setup.py install for mozhttpd 04:54:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:54:22 INFO - Running setup.py install for mozinfo 04:54:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:54:22 INFO - Running setup.py install for mozInstall 04:54:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:54:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:54:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:54:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:54:22 INFO - Running setup.py install for mozleak 04:54:23 INFO - Running setup.py install for mozlog 04:54:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:54:23 INFO - Running setup.py install for moznetwork 04:54:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:54:23 INFO - Running setup.py install for mozprocess 04:54:23 INFO - Running setup.py install for mozprofile 04:54:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:54:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:54:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:54:23 INFO - Running setup.py install for mozrunner 04:54:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:54:23 INFO - Running setup.py install for mozscreenshot 04:54:24 INFO - Running setup.py install for moztest 04:54:24 INFO - Running setup.py install for mozversion 04:54:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:54:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:54:24 INFO - Cleaning up... 04:54:24 INFO - Return code: 0 04:54:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:24 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 0x108929b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10892c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a494b6d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108707d30>, '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 0x10862edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086fcbe8>, '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.5yQwM5LAWf/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.md5pPs2FOo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:54:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:54:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5yQwM5LAWf/Render', 04:54:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:24 INFO - 'HOME': '/Users/cltbld', 04:54:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:24 INFO - 'LOGNAME': 'cltbld', 04:54:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:24 INFO - 'MOZ_NO_REMOTE': '1', 04:54:24 INFO - 'NO_EM_RESTART': '1', 04:54:24 INFO - 'PAGER': '/bin/cat', 04:54:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:24 INFO - 'PWD': '/builds/slave/test', 04:54:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:24 INFO - 'SHELL': '/bin/bash', 04:54:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.md5pPs2FOo/Listeners', 04:54:24 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 04:54:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:24 INFO - 'USER': 'cltbld', 04:54:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:24 INFO - 'XPC_FLAGS': '0x0', 04:54:24 INFO - 'XPC_SERVICE_NAME': '0', 04:54:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:54:24 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-xPwWep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:54:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:54:24 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-1oCJq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:54:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:54:24 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-FIh3eY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:54:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:54:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:54:24 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-obLPhz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:54:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-yrjfG3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:54:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-Auktrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:54:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-VOUrRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:54:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-c3lxkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:54:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-99a1Mv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:54:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-7oTpda-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:54:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-Mxid2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:54:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-bt7qbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:54:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:54:25 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-i12QaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:54:26 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-XW80iq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:26 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-jyNkPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:54:26 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-oYbdj9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:54:26 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-53WRZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:54:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:54:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:54:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:54:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:54:29 INFO - Downloading blessings-1.6.tar.gz 04:54:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:54:29 INFO - Installing collected packages: blessings 04:54:29 INFO - Running setup.py install for blessings 04:54:29 INFO - Successfully installed blessings 04:54:29 INFO - Cleaning up... 04:54:29 INFO - Return code: 0 04:54:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:54:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:54:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:54:29 INFO - Reading from file tmpfile_stdout 04:54:29 INFO - Current package versions: 04:54:29 INFO - blessings == 1.6 04:54:29 INFO - blobuploader == 1.2.4 04:54:29 INFO - docopt == 0.6.1 04:54:29 INFO - functools32 == 3.2.3-2 04:54:29 INFO - jsonschema == 2.5.1 04:54:29 INFO - manifestparser == 1.1 04:54:29 INFO - mozInstall == 1.12 04:54:29 INFO - mozcrash == 1.0 04:54:29 INFO - mozdebug == 0.1 04:54:29 INFO - mozdevice == 0.49 04:54:29 INFO - mozfile == 1.2 04:54:29 INFO - mozhttpd == 0.7 04:54:29 INFO - mozinfo == 0.9 04:54:29 INFO - mozleak == 0.1 04:54:29 INFO - mozlog == 3.4 04:54:29 INFO - moznetwork == 0.27 04:54:29 INFO - mozprocess == 0.23 04:54:29 INFO - mozprofile == 0.28 04:54:29 INFO - mozrunner == 6.13 04:54:29 INFO - mozscreenshot == 0.1 04:54:29 INFO - mozsystemmonitor == 0.3 04:54:29 INFO - moztest == 0.8 04:54:29 INFO - mozversion == 1.4 04:54:29 INFO - psutil == 3.1.1 04:54:29 INFO - requests == 1.2.3 04:54:29 INFO - wsgiref == 0.1.2 04:54:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:29 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 0x108929b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10892c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a494b6d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108707d30>, '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 0x10862edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086fcbe8>, '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.5yQwM5LAWf/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.md5pPs2FOo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:54:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:54:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5yQwM5LAWf/Render', 04:54:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:29 INFO - 'HOME': '/Users/cltbld', 04:54:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:29 INFO - 'LOGNAME': 'cltbld', 04:54:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:29 INFO - 'MOZ_NO_REMOTE': '1', 04:54:29 INFO - 'NO_EM_RESTART': '1', 04:54:29 INFO - 'PAGER': '/bin/cat', 04:54:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:29 INFO - 'PWD': '/builds/slave/test', 04:54:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:29 INFO - 'SHELL': '/bin/bash', 04:54:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.md5pPs2FOo/Listeners', 04:54:29 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 04:54:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:29 INFO - 'USER': 'cltbld', 04:54:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:29 INFO - 'XPC_FLAGS': '0x0', 04:54:29 INFO - 'XPC_SERVICE_NAME': '0', 04:54:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:54:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:54:29 INFO - Cleaning up... 04:54:29 INFO - Return code: 0 04:54:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:54:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:54:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:54:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:54:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108929b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10892c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9a494b6d50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108707d30>, '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 0x10862edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086fcbe8>, '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.5yQwM5LAWf/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.md5pPs2FOo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:54:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:54:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:54:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5yQwM5LAWf/Render', 04:54:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:29 INFO - 'HOME': '/Users/cltbld', 04:54:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:29 INFO - 'LOGNAME': 'cltbld', 04:54:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:29 INFO - 'MOZ_NO_REMOTE': '1', 04:54:29 INFO - 'NO_EM_RESTART': '1', 04:54:29 INFO - 'PAGER': '/bin/cat', 04:54:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:29 INFO - 'PWD': '/builds/slave/test', 04:54:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:29 INFO - 'SHELL': '/bin/bash', 04:54:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.md5pPs2FOo/Listeners', 04:54:29 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 04:54:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:29 INFO - 'USER': 'cltbld', 04:54:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:29 INFO - 'XPC_FLAGS': '0x0', 04:54:29 INFO - 'XPC_SERVICE_NAME': '0', 04:54:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:54:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:54:30 INFO - Cleaning up... 04:54:30 INFO - Return code: 0 04:54:30 INFO - Running post-action listener: _resource_record_post_action 04:54:30 INFO - Running post-action listener: _start_resource_monitoring 04:54:30 INFO - Starting resource monitoring. 04:54:30 INFO - [mozharness: 2017-01-03 12:54:30.248159Z] Finished create-virtualenv step (success) 04:54:30 INFO - [mozharness: 2017-01-03 12:54:30.249108Z] Running install step. 04:54:30 INFO - Running pre-action listener: _resource_record_pre_action 04:54:30 INFO - Running main action method: install 04:54:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:54:30 INFO - Reading from file tmpfile_stdout 04:54:30 INFO - Detecting whether we're running mozinstall >=1.0... 04:54:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:54:30 INFO - Reading from file tmpfile_stdout 04:54:30 INFO - Output received: 04:54:30 INFO - Usage: mozinstall [options] installer 04:54:30 INFO - Options: 04:54:30 INFO - -h, --help show this help message and exit 04:54:30 INFO - -d DEST, --destination=DEST 04:54:30 INFO - Directory to install application into. [default: 04:54:30 INFO - "/builds/slave/test"] 04:54:30 INFO - --app=APP Application being installed. [default: firefox] 04:54:30 INFO - mkdir: /builds/slave/test/build/application 04:54:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:54:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 04:54:52 INFO - Reading from file tmpfile_stdout 04:54:52 INFO - Output received: 04:54:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:54:52 INFO - Running post-action listener: _resource_record_post_action 04:54:52 INFO - [mozharness: 2017-01-03 12:54:52.199549Z] Finished install step (success) 04:54:52 INFO - [mozharness: 2017-01-03 12:54:52.199694Z] Running run-tests step. 04:54:52 INFO - Running pre-action listener: _resource_record_pre_action 04:54:52 INFO - Running main action method: run_tests 04:54:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:54:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:54:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:54:52 INFO - 2017-01-03 04:54:52.214 screenresolution[1629:8207] starting screenresolution argv=screenresolution get 04:54:52 INFO - 2017-01-03 04:54:52.216 screenresolution[1629:8207] Display 0: 1600x1200x32@60 04:54:52 INFO - 2017-01-03 04:54:52.222 screenresolution[1630:8210] starting screenresolution argv=screenresolution list 04:54:52 INFO - Available Modes on Display 0 04:54:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:54:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:54:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:54:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:54:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:54:52 INFO - Intel Iris: 04:54:52 INFO - Chipset Model: Intel Iris 04:54:52 INFO - Type: GPU 04:54:52 INFO - Bus: Built-In 04:54:52 INFO - VRAM (Dynamic, Max): 1536 MB 04:54:52 INFO - Vendor: Intel (0x8086) 04:54:52 INFO - Device ID: 0x0a2e 04:54:52 INFO - Revision ID: 0x0009 04:54:52 INFO - Displays: 04:54:52 INFO - Display: 04:54:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:54:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:54:52 INFO - Display Serial Number: 1600x1200 60 04:54:52 INFO - Main Display: Yes 04:54:52 INFO - Mirror: Off 04:54:52 INFO - Online: Yes 04:54:52 INFO - Rotation: Supported 04:54:52 INFO - Return code: 0 04:54:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:54:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:54:52 INFO - Python 2.7.3 04:54:52 INFO - Return code: 0 04:54:52 INFO - grabbing minidump binary from tooltool 04:54: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')]} 04:54:52 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 0x108707d30>, '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 0x10862edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1086fcbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:54:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:54:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:54:52 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:54:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:54:52 INFO - Return code: 0 04:54:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:54:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:54:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:54:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:54:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:54:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0319', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:54:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0319 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:54:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5yQwM5LAWf/Render', 04:54:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:54:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:54:52 INFO - 'HOME': '/Users/cltbld', 04:54:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:54:52 INFO - 'LOGNAME': 'cltbld', 04:54:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:54:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:54:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:54:52 INFO - 'MOZ_NO_REMOTE': '1', 04:54:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:54:52 INFO - 'NO_EM_RESTART': '1', 04:54:52 INFO - 'PAGER': '/bin/cat', 04:54:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:54:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:54:52 INFO - 'PWD': '/builds/slave/test', 04:54:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:54:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:54:52 INFO - 'SHELL': '/bin/bash', 04:54:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.md5pPs2FOo/Listeners', 04:54:52 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 04:54:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:54:52 INFO - 'USER': 'cltbld', 04:54:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:54:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:54:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:54:52 INFO - 'XPC_FLAGS': '0x0', 04:54:52 INFO - 'XPC_SERVICE_NAME': '0', 04:54:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:54:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0319', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:54:52 INFO - mozversion application_buildid: 20170103033820 04:54:52 INFO - mozversion application_changeset: 232424f9511d61179912ccaf5b169fb45f05599a 04:54:52 INFO - mozversion application_display_name: Nightly 04:54:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:54:52 INFO - mozversion application_name: Firefox 04:54:52 INFO - mozversion application_remotingname: firefox 04:54:52 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:54:52 INFO - mozversion application_vendor: Mozilla 04:54:52 INFO - mozversion application_version: 53.0a1 04:54:52 INFO - mozversion platform_buildid: 20170103033820 04:54:52 INFO - mozversion platform_changeset: 232424f9511d61179912ccaf5b169fb45f05599a 04:54:52 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:54:52 INFO - mozversion platform_version: 53.0a1 04:54:52 INFO - using testdate: 1483448092 04:54:52 INFO - actual date: 1483448092 04:54:52 INFO - starting webserver on 'localhost:49243' 04:54:52 INFO - SUITE-START | Running 2 tests 04:54:52 INFO - TEST-START | dromaeo_css 04:54:52 INFO - operating with platform_type : mac_ 04:54:52 INFO - Initialising browser for dromaeo_css test... 04:54:52 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpxUoX6J/profile http://localhost:49243/getInfo.html) 04:54:53 INFO - PROCESS | 1640 | 1483448093349 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 04:54:55 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 04:54:55 INFO - PROCESS | 1640 | colorDepth:24 04:54:55 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 04:54:55 INFO - PROCESS | 1640 | __metrics 04:54:55 INFO - PROCESS | 1640 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:54:55 INFO - PROCESS | 1640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:54:56 INFO - TEST-INFO | 1640: exit 0 04:54:56 INFO - Browser initialized. 04:54:56 INFO - Running cycle 1/1 for dromaeo_css test... 04:54:56 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpxUoX6J/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:55:08 INFO - PROCESS | 1643 | RSS: Main: 234557440 04:55:08 INFO - PROCESS | 1643 | 04:56:05 INFO - PROCESS | 1643 | 1483448165981 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:58:13 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 04:58:15 INFO - PROCESS | 1643 | RSS: Main: 380792832 04:58:15 INFO - PROCESS | 1643 | 04:59:56 INFO - PROCESS | 1643 | ************************* 04:59:56 INFO - PROCESS | 1643 | A coding exception was thrown and uncaught in a Task. 04:59:56 INFO - PROCESS | 1643 | 04:59:56 INFO - PROCESS | 1643 | Full message: ReferenceError: fetch is not defined 04:59:56 INFO - PROCESS | 1643 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 04:59:56 INFO - PROCESS | 1643 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 04:59:56 INFO - PROCESS | 1643 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 04:59:56 INFO - PROCESS | 1643 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:59:56 INFO - PROCESS | 1643 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:59:56 INFO - PROCESS | 1643 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:59:56 INFO - PROCESS | 1643 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 04:59:56 INFO - PROCESS | 1643 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 04:59:56 INFO - PROCESS | 1643 | TaskImpl@resource://gre/modules/Task.jsm:277:3 04:59:56 INFO - PROCESS | 1643 | asyncFunction@resource://gre/modules/Task.jsm:252:14 04:59:56 INFO - PROCESS | 1643 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 04:59:56 INFO - PROCESS | 1643 | 04:59:56 INFO - PROCESS | 1643 | ************************* 05:01:17 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 05:01:18 INFO - PROCESS | 1643 | RSS: Main: 367071232 05:01:18 INFO - PROCESS | 1643 | 05:04:20 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 05:04:21 INFO - PROCESS | 1643 | RSS: Main: 361922560 05:04:21 INFO - PROCESS | 1643 | 05:07:23 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 05:07:24 INFO - PROCESS | 1643 | RSS: Main: 340824064 05:07:24 INFO - PROCESS | 1643 | 05:10:28 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 05:10:29 INFO - PROCESS | 1643 | RSS: Main: 324419584 05:10:29 INFO - PROCESS | 1643 | 05:13:32 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 05:13:32 INFO - PROCESS | 1643 | RSS: Main: 435609600 05:13:32 INFO - PROCESS | 1643 | 05:13:32 INFO - PROCESS | 1643 | __start_tp_report 05:13:32 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 05:13:32 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 05:13:32 INFO - PROCESS | 1643 | |i|pagename|runs| 05:13:32 INFO - PROCESS | 1643 | |0;dojo.html;3353.8323083845803;3377.746668999825;3384.035549868288;3391.3386889556546;3449.810260435675;5668.489835914766;5730.426957304273;5735.426457354262;5774.942250577493;5804.419558044198;5720.22776925115;5729.914051289234;5752.194692743019;5755.913661295084;5771.365149833515;5177.896442071147;5219.112750832368;5228.73856307185;5249.947500524984;5286.4449232830575;5318.813841515548;5320.095583750772;5320.281761962125;5322.88219473911;5327.760250788724;5312.495312945264;5318.680879147244;5324.387695415019;5331.440198779131;5340.385855626594;5306.416294207634;5322.866928326784;5323.707196104193;5325.3077099977145;5337.786488540453;4420.9336859947125;5206.947930520684;5274.472552744694;5276.81531146408;5280.1815718563685;5756.194132821408;5779.768809247625;5807;5864.237649105632;5881.176635271066;6269.717862696144;6310.5267104967315;6322.968385158045;6324.683765811737;6335.208098987627;6158.445739883432;6191.195144631214;6239.65681887496;6241.968790156066;6273.184486016834;5832.387599302097;5839.416058394211;5853;5860.70696465175;5864.589478736448;6311.305756366796;6321.620702757849;6323.683815809191;6326.304106548276;6328.050792381179;6040.637561746309;6082.878342433127;6086.452219300284;6128.575417880035;6200.844978875474;6172.321044685081;6217.347178546278;6226.346233645492;6232.626042437469;6237.438630523275;6117.775897257223;6124.544022963324;6170.074488826712;6172.660503672341;6266.373362663697;6295.087212354275;6301.338359472281;6312.084747711556;6318.557700960889;6333.081703153017;4959.677620954626;4959.70241785494;4963.429205641325;4973.1297023020825;4989.151844186497;4400.141972315432;4404.163208990281;4417.889552761207;4427.535108813592;4459.331100334975;5882.294124705063;5883.970580147072;5891.617044892154;5897.970510147422;5935.080062590306;6301.306856245809;6349.936500635027;6351.745930162741;6351.745930162834;6352.174217351807;5677.545796336283;5755.510781583528;5772.336181339116;5879.911801322984;5888.735006924699;5390.541803946709;5407.891842163135;5408.242846001563;5408.891822163535;5409.215663728737;5352.518273355417;6278.717457714414;6361.109444677842;6377.202849643794;6388.233411990591;6093.725782339717;6178.567500274938;6297.716602752796;6338.334474880071;6343.175387199634;6256.061590761422;6346.904796427965;6356.68216589178;6378.2983871774995;6384.297727249906;6127.953177891068;6138.051223665453;6155.692215389302;6174.783882564088;6188.597741146811;5210.8957820844125;6272.466840318624;6331.493480521639;6335.809925702236;6341.460975817107;5590.881823635371;5727.570432217517;6055.969720151371;6137.754489820357;6149.200603921461;5901.7639294427745;6017.699115044143;6019.247594050744;6108.645631960618;6119.571629985859;5565.6104072714525;5712.143178523089;6045.516358691206;6050.273967123797;6054.8183554493435;6155.876882462415;6180.289266734383;6187.938120618736;6212.81361559154;6218.253809542884;5798.884022319615;5839.416058394127;5855.619384739978;5952.613080149777;6008.339082700812 05:13:32 INFO - PROCESS | 1643 | |1;ext.html;432.3644242962844;492.50995259716564;498.61108335498324;510.22445882258944;522.0759256116673;24048.79756012201;37614.24771504572;39299;39348;39494.01264968377;39709.8014509927;40100.59899401012;40185.79907100468;40186.39720404189;40893.38659920096;8344.874826877602;9160.175584197405;9369.67206147785;9370.672026479075;9393;8368.790780230513;10973.286736362152;11220.775584488305;11247.212695111304;11438.71403214922;9539.80920381592;11086.556537738481;11228.66314010577;11257.831132533018;11279.887201127965;11021.724456888562;11139.33164010162;11214.551417943354;11218.495167717472;11219.775604487864;11028.062614677761;11096.722581935435;11104.833427498594;11106.61126860556;11113.499892504857;37711.43432848509;37773.81113094444;37787.244255115016;37826.43260351097;37863.62136378628;3968.0675041365257;3972.066564357374;3983.701222408331;4006.759594424315;4011.2579172853116;28268;29029;29124;29246.561301580492;29326.70673293261;3107.813531188136;3153.716165545112;3153.8896138635037;3163.873445062218;3264.05342450689;416253;419791;419854;421351;422317.88841055904;52980.205296919805;54073.459265407626;54601.63495912634;54641.1803822943;54806;57045.714771426705;57099;57228.713856431285;57240;57267.14099288514;14816.259187040605;15312.770308445382;15316.387344506318;15328.540143795704;15343.61640958985;9598.568064436977;10696.62561810333;11046.061084807881;11829.881701182878;11930.22453540517;14211.147331160164;14497.637559061108;14561.053531520418;14718.0433271837;14761.261936903113;6390.904136437957;6423.229212494531;6454.612723236621;6464.22429308486;6470.288268290485;830.5930094253893;833.3208435125354;836.1554829622125;843.8860703870831;857.1471385970999;28140.859295703798;28290.43419131647;28339.57490637642;28751.56872646912;29053.854730726212;1006.0744115413837;1027.1166796554958;1033.7725700345911;1043.3479075577764;1044.7022598559527;13477.865221347856;14158.29208539569;14632.48786292496;14923;14985.700285994219;421863;509180.3622945659;509197.45401272754;509355.4532227389;509475;52341.2148817768;52516;52574.737126314125;52660.73669631627;52720;1333.8266025416633;1379.200063962899;1383.1631862722907;1383.9169649820842;1389.6456403617215;27622.861885690443;27688.58467123035;27842.44315113646;28357;28801.423971520035;44882.55117448784;45340.77329613331;45353.77323113364;45459;45480;440449;441847;442600;444513;444677;31692.207694807814;32207.199350134695;35350;36025.63974360223;36377.81811090928;38020.85937421882;39343.80328098455;39608;40076.198476029735;40117.99705007398;36374.09064773402;36460.90617281486;37073.86152634894;37196.6280337204;38098.80950595229;35588.466173006906;35825;36357.45463818098;36967;37166.814165929;35584;35603.28793424065;36257.64325318846;36294.8185259072;36425.90722278336;29575;30921.76312947457;32167.51748723724;32190;32291.35417291688;23721.88139059294;23762.524749505257;23976;24341.878290609144;24426.75573244245 05:13:32 INFO - PROCESS | 1643 | |2;jquery.html;351.11168743079986;359.2814371257484;366.5015578812814;367.37546171508427;395.6913607386238;14485.927570362146;14658.706825863477;15590.92204538977;19700.408987730356;20196.091175897083;16751.83248167518;20211.59576808463;20360.490987725276;21088.68366974493;21166.470838229052;5149.974250128744;5613.831585052455;5912.379200183983;5931.970340148293;5933.5549833762425;4305.956940430587;4932.950670493303;5680.6591604503865;5923.229980102581;5937.554683398741;5875.178694604665;5889.6171748836405;5895.705214739267;5896.764129434818;5899.734511946951;5569.860753481175;5731.140328950649;5767.653940763546;5835.562332825055;5854.76580936762;5075.670081444731;5137.332146820927;5143.8456846294675;5145.871353216162;6795.082663840368;20671.759694418382;26019.21942341733;27030;27736.583951240747;27891.86054069737;2795.930101747452;2885.6104425902445;2893.594896714462;2952.822830630169;2983.1199796059996;15924.76112858308;15933.760993585105;16422.589435264214;16447.588810279718;16643.41748038813;1997.1412292714126;2000.1399398258889;2018.794298001105;2044.3355909329528;2049.631066408046;122094.38952805179;125204.74795252022;137539.31230343884;137645;140571;18715.71926421105;20314.898425507778;20559.794402056086;20823.709391966873;20834.374968750963;35001.29997400038;35073.473897891556;35121;35121.64878351235;35216.59133634626;9964.950175249222;10168.847467287997;10298.279668676854;10366.170706343475;10514.684559463229;8385.664573416994;8394.664213431395;8429.873551896726;8536.786580335542;8575.399722019523;7794.9220507795335;9320.627174912928;9402.247820174369;9603.663871764595;9627.277954153467;3594.712423006153;3717.8326975285636;3720.2373513429684;3721.3673675475234;3730.3213553900855;805.295366554265;945.2013048973606;977.9462129582942;993.9276608271834;1007.9546420411099;20289.49276268105;20811;20852.895735521226;20860.269890553758;20920;990.9504524773732;997.6607953295769;1014.523174108168;1019.1286450085219;1021.7037059252882;10133.898661013443;10431.634892778717;10755.731106722395;10855.782884342269;10868.673939781662;467768;471875;474897;551863;563917;134901;139142;143390;144235;145047.06723390523;967.8886928003229;1013.8023085498257;1023.1405619279692;1026.4508487958906;1026.5124066068706;14645.414183432544;18698.43904682862;18864.62270754605;18889.71665425047;19501;49996.50003500064;50591.241131383795;51857;52488.73755631197;55414;54640.726796365765;64545.31367932638;71107;73027;73062.63468682622;7712.506861440989;7982.564762989885;8010.759677209693;8411.83176336482;8435.283000944988;8439;8595.570221488775;8890.911090889009;8927.062658420773;9312.301577381588;7796.766097017098;7945;8069.233422824822;8118.269355757774;8172.959135204286;7494.17564067963;7518.210587888193;7996.160403157584;8070.192980702118;8119.30985866208;7936.365090792839;7939.166387529457;7950.125486196629;8105.27052565272;8169.223923727236;11204.887951120385;12011.939940300243;12073.094517911017;12212.938935305267;12311.75376492483;11404.48679809394;11586.42067896585;11853.348065856117;11949.402529873645;11976.46105925218 05:13:32 INFO - PROCESS | 1643 | |3;mootools.html;1292.8836404723584;1390.8331000279966;1408.3943904121231;1429.6282966428732;1436.045030055013;7370.1892791792925;7752.147263800985;8022.598870056496;8295.585220738954;8352.039515455725;8170.6323215455295;8180.059293181286;8189.95905020474;8291.875621865676;8311.25198732114;2011.5976804639058;2262.5474905019023;2319.211468100845;2323.8838058097112;2323.8954247058837;2079.386580958613;2245.236619549353;2324.6861673674093;2335.0776443304885;2341.9461242440884;2252.6395776675736;2275.4766403727153;2277.2257432472957;2281.3498153026308;2286.336962280945;2104.3897269791896;2274.033535747301;2305.7924786769104;2308.0190918859416;2308.4113551044543;2136.583366243583;2290.5189910118893;2334.2880421471446;2337.6026075567024;2339.4619237575453;8252.587370631445;8335.208155225304;8352.16478352166;8355.206255405727;8365.749027529184;4936.654434189608;5082.567981721558;5099.541041306263;5102.872428189288;5110.565601923841;2834.135588645462;3215.2122729931316;3328.7503437242303;3408.596939157888;3410.471376936579;1114.4761961877905;1183.591660876996;1187.9821802672966;1230.655416483386;1232.9075319350995;10565.41890196039;10634.042936135786;10711.571537138505;10713.785724285548;10765.892341076646;3731.552213734315;4377.233984052778;4466.57567531084;4550.47669518003;4555.658325625592;9123.087691230823;9279.907200928039;9290.396124251902;9316.534173291308;9349.252059835197;5124.103281925648;5128;5133.101707201299;5135.229715542624;5135.820246291437;4088.795560221977;4621.758447153128;4655.37152484417;4705.7411842349;4707.576318131385;3705.27747089315;3732.738708290448;3768.491253680718;3828.9617103828605;3842.9615703843165;1080.8000519903842;1233.179935343005;1262.2994238197705;1272.243015405832;1278.341654048166;742.684359147371;758.1698040645484;868.3747701654798;883.8276536075532;927.3230541704594;5433.646812957145;5558.527525160326;5655.406182350794;5709.571782116358;5866.970665146647;865.2818160926416;938.5354249646468;962.8892677342195;978.4227305889559;980.1668581705493;3676.8161591920298;3700.740948133605;3725.5529336479794;3810.904727381838;3852.7303088783515;9603.711888643353;9685.951570242103;9785;9888.802223955481;9891.406515609087;9317.487538185465;9704.514774261259;9828.852567211497;9851.162651174587;9859.75350616226;7494.85010299791;7714.922850771421;7722.420818438527;7738.535687858746;7747.496412733154;5069.670471419346;5079.44126146131;5132.538071573576;5178.404483484297;5279.788808447619;7686.346660533917;8786.692465763668;8831.690890819047;9079.636814527345;9259.444433334022;9063.63745450201;9217.539123043955;9267.351285409957;9275.62897484093;9374.062593740571;6967.7212911482975;7180.676869540987;7192.460565457716;7194.2086370498155;7217.278272172741;7648.273414025659;7707.730229441942;7793.532388056735;7800.765977020698;7814.4529882907655;6988.4059854910865;7034.683439245144;7044.647767611496;7051.224365319913;7082.645867706491;6885.277045910308;6906.792796216121;6958.721651133898;7023.543469674455;7031.156261248683;6211.875762484816;6215.627062376272;6262.373762623702;6312.3056463788025;6377.107204991212;4260.4035435038495;4465.352523884084;4589.655775816894;4648.953510464852;4668.043051174569;4767.404074490689;4776.355192049029;4851.56335929768;4919.778609962489;5027.446980832033 05:13:32 INFO - PROCESS | 1643 | |4;prototype.html;311.45650839285435;326.4401551339452;350.82458770614693;358.3710587918203;360.624950051946;4563.361129441881;4698.412698412699;4698.835540756074;4766.856994290169;4974.427940786811;4992.950070499294;5158.942416804555;5213.530782229599;5253.527182553569;5267.683938963665;3652.7808331500064;4106.733062350952;4118.752874827505;4127.318992366256;4140.358244472097;3449.7930124192503;3622.3117607654463;4140.799168241207;4149.917001659965;4156.937645935298;3609.205974685578;3940.345055076875;3985.043589538519;3998.900027499307;4127.855525056623;3577.1057235690946;4085.44846445729;4095.692823038284;4101.088227358579;4126.154138401621;2782.6243457133232;3531.1525233943926;3616.8553257869653;3644.398674218751;3661.9267614647824;4182.790860456996;4554.954450455486;4899.338589290436;5019.895622962942;5092.79628814847;4720.362751028601;4745.786439610226;4746.310976218548;4748.976255118702;4766.285057241442;4892.437369702494;5219.73901304937;5308.389535203462;5544.944550554484;5636.661800291955;3349.547811045502;4004.8598299059686;4008.178323443687;4008.8596899108684;4014.859479918218;5526;5761.88476230473;5796.652200867961;5810.796622118206;5825.970870145665;3812.1613245086037;4085.22380747657;4114.485689288839;4118.711690181659;4135.338345864648;4000.979995100006;4113.547509773923;4176.979115104466;4180.435641188462;4182.832686692559;4486.080353527519;4537.776489520112;4583.518730533313;4594.425696787902;4710.561290258131;4751.429828420612;5061.936993231389;5503.972480137573;5559.63862348946;5657.538970547825;1274.5915763081662;1335.3990704183157;1352.4590163934308;1368.6715188354922;1381.4267079162237;928.4568527411404;951.7097285327903;953.537534295866;958.8897276813116;980.8626792249091;4227;4687.20317546018;4876.268559716;4918.385201849769;4944.615507657862;4806.668765648657;5314.867128321823;5353.86615334612;5436.53789427903;5443.727813609304;4550.544945505423;4567.497575266718;4922.606191504671;4954.621781544879;5024.316853853927;2488.203774792049;2569.8072644551735;2841.9431611368072;2867.641544806899;2868.164374800088;5002.699838009731;5092.9236061459105;5232.529072383504;5514.283143191359;5548.361938377057;5131.7434128293435;5145.614078944094;5220.929709409562;5390.21841832932;5582.944170558324;4857.489963553776;5153.45888681698;5395.70323632204;5622.831315060473;5631.6902570359025;4662.5546080715085;5395;5407.810281738011;5444.985355418362;5446.930166536691;4667.556582124693;4817.590504807131;5023.874403139805;5678.602497825113;5719.799807006735;5560.694161821139;5910.231669882888;5947.316058653138;5950.16697662319;5979.312379076462;3439.1084903303586;3679.4296883983025;3684.0658654206754;3806.4480650306105;3848.8652897148463;5450.645708028966;5621.831345059655;5707.857303567444;5721.628094173865;5745.1382292656435;4768.880777980578;5346.304980352529;5445.26488924006;5451.318585176853;5662.207290979349;5143.585513983684;5191.585912550682;5548.278723766046;5562.165675148598;5575.8884822304135;4584.862454126382;5417.133258678593;5423.559398270715;5464.617476776588;5486.872482684831;5031.295618613324;5510.622344413897;5521.365043020104;5525.209769066596;5552.639078459887;4766.6901651392545;5260.921086183786;5428.45715428454;5469.343678758574;5499.807506737245 05:13:32 INFO - PROCESS | 1643 | |5;yui.html;697.0206859362592;719.4819729794546;742.2986514907831;795.5425630262598;831.9750407487776;14365.784513232296;15532.068075915453;15549.922250388761;15563.299651515681;15568.14375209363;13675;14011.019228653999;14252;14307.57077287683;14334.924880633942;862.8705694145881;887.7469921072495;889.1375365895071;890.1899271662788;892.6652505310509;684.7124207832697;713.564725517434;873.8470767615642;877.9341549383822;879.6525372477868;755.4560716284294;893.4371346051978;927.184078011349;934.7709811096291;940.111594543157;931.998102040306;935.5182081228173;940.3417607674655;945.6501094595044;946.5740416812394;531.5641174237127;693.972241110355;694.146200173784;696.5681277607915;697.5814511293235;12481.75036499274;14369.209693466855;14901.776473352907;15048.32282547288;15378.692426151527;14188.645283867883;14207.78688319676;14232.92883535586;14267.571972840831;14325.426982920671;11573;11654.951054405059;11741.29552226869;11871.465784039652;12140.3322817245;3603.3874241378767;3650.4889315495852;3805.809709514513;3817.675497582709;3831.5593706723803;105176.47411762968;109731.90268097297;109740.35389469164;110460;110640.4467977663;10518.421486818146;10999.285046472114;11008.669739907815;11038.558457661604;11168.776624467466;22535.323940281818;22826.086956521885;22905;22933.312000640006;23229.18697845601;7579.658915348822;7620.542767433861;7624.695012199561;7677.731279405194;7707.845843083219;3650.288193802236;4189.0574620710095;4257.829686812554;4306.504751953503;4403.5376285489565;3500.492428597839;3579.552555930509;3608.9278214435562;3612.1872578670113;3715.5727091384297;672.9259781424039;675.1358261425383;675.5035049238805;678.4572342126279;686.1663079358603;511.66230288009734;546.778554685353;550.3368441028631;552.5883217003358;555.9694216818033;8289.212524810133;8569.271611913056;8807.647694092293;8884.8667269991;8885.367300054391;643.4641644165248;644.0886146103305;653.6992983227768;666.74330382802;671.9596824190467;4158.500979882433;4404.383386325917;4405.845795397146;4415.183191109655;4451.554844515523;105005;106738;107734;108932;109830.45084774525;91288.54355728312;91470;92694;93061.53469232611;93756.06243937473;79154;79954.60022699965;80807.78788318063;80810.59594701989;81407.592962036;8506.489610623383;8521.531315777444;8526.573671316286;8618.82762344737;8727.476351418682;51117.74441127771;52095.73952130215;52754;52825.20762188493;55232;70280.29719702942;70574.64712676404;71175;71931.64034179796;72580.63709681417;11362.886371136183;11856.347900865536;11934.28394296345;12128.029757619193;12387.01807710762;12459.626211213677;12663.366831658195;12772.680682983;13000.869991300344;13115.868841311465;11674.88325116772;11764.294142351486;11787.233829801036;11984.40077996114;12510.624681259576;11721.413929303671;11807.468663909973;12419.93790031044;12935.029872759393;13711.588652340446;13156.605301840576;13255.469781208643;13449.723249534683;13528.255945922676;14090.22503762303;10986.230963832455;11069.612563560237;11344.546218151181;11400.359510946082;11485.368304743319;10800.335895613829;10813.891861081289;10817.513211905325;10827;10849.349039057683 05:13:32 INFO - PROCESS | 1643 | __end_tp_report 05:13:32 INFO - PROCESS | 1643 | __start_cc_report 05:13:32 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,1485 05:13:32 INFO - PROCESS | 1643 | __end_cc_report 05:13:32 INFO - PROCESS | 1643 | __startTimestamp1483449212710__endTimestamp 05:13:32 INFO - PROCESS | 1643 | 05:13:32 INFO - PROCESS | 1643 | ------- Summary: start ------- 05:13:32 INFO - PROCESS | 1643 | Number of tests: 6 05:13:32 INFO - PROCESS | 1643 | 05:13:32 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5765.79 Median:5897.97 stddev:639.22 (10.8%) stddev-sans-first:612.53 05:13:32 INFO - PROCESS | 1643 | Values: 3353.8 3377.7 3384.0 3391.3 3449.8 5668.5 5730.4 5735.4 5774.9 5804.4 5720.2 5729.9 5752.2 5755.9 5771.4 5177.9 5219.1 5228.7 5249.9 5286.4 5318.8 5320.1 5320.3 5322.9 5327.8 5312.5 5318.7 5324.4 5331.4 5340.4 5306.4 5322.9 5323.7 5325.3 5337.8 4420.9 5206.9 5274.5 5276.8 5280.2 5756.2 5779.8 5807.0 5864.2 5881.2 6269.7 6310.5 6323.0 6324.7 6335.2 6158.4 6191.2 6239.7 6242.0 6273.2 5832.4 5839.4 5853.0 5860.7 5864.6 6311.3 6321.6 6323.7 6326.3 6328.1 6040.6 6082.9 6086.5 6128.6 6200.8 6172.3 6217.3 6226.3 6232.6 6237.4 6117.8 6124.5 6170.1 6172.7 6266.4 6295.1 6301.3 6312.1 6318.6 6333.1 4959.7 4959.7 4963.4 4973.1 4989.2 4400.1 4404.2 4417.9 4427.5 4459.3 5882.3 5884.0 5891.6 5898.0 5935.1 6301.3 6349.9 6351.7 6351.7 6352.2 5677.5 5755.5 5772.3 5879.9 5888.7 5390.5 5407.9 5408.2 5408.9 5409.2 5352.5 6278.7 6361.1 6377.2 6388.2 6093.7 6178.6 6297.7 6338.3 6343.2 6256.1 6346.9 6356.7 6378.3 6384.3 6128.0 6138.1 6155.7 6174.8 6188.6 5210.9 6272.5 6331.5 6335.8 6341.5 5590.9 5727.6 6056.0 6137.8 6149.2 5901.8 6017.7 6019.2 6108.6 6119.6 5565.6 5712.1 6045.5 6050.3 6054.8 6155.9 6180.3 6187.9 6212.8 6218.3 5798.9 5839.4 5855.6 5952.6 6008.3 05:13:32 INFO - PROCESS | 1643 | 05:13:32 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:59097.72 Median:36401.86 stddev:120212.37 (330.2%) stddev-sans-first:120468.90 05:13:32 INFO - PROCESS | 1643 | Values: 432.4 492.5 498.6 510.2 522.1 24048.8 37614.2 39299.0 39348.0 39494.0 39709.8 40100.6 40185.8 40186.4 40893.4 8344.9 9160.2 9369.7 9370.7 9393.0 8368.8 10973.3 11220.8 11247.2 11438.7 9539.8 11086.6 11228.7 11257.8 11279.9 11021.7 11139.3 11214.6 11218.5 11219.8 11028.1 11096.7 11104.8 11106.6 11113.5 37711.4 37773.8 37787.2 37826.4 37863.6 3968.1 3972.1 3983.7 4006.8 4011.3 28268.0 29029.0 29124.0 29246.6 29326.7 3107.8 3153.7 3153.9 3163.9 3264.1 416253.0 419791.0 419854.0 421351.0 422317.9 52980.2 54073.5 54601.6 54641.2 54806.0 57045.7 57099.0 57228.7 57240.0 57267.1 14816.3 15312.8 15316.4 15328.5 15343.6 9598.6 10696.6 11046.1 11829.9 11930.2 14211.1 14497.6 14561.1 14718.0 14761.3 6390.9 6423.2 6454.6 6464.2 6470.3 830.6 833.3 836.2 843.9 857.1 28140.9 28290.4 28339.6 28751.6 29053.9 1006.1 1027.1 1033.8 1043.3 1044.7 13477.9 14158.3 14632.5 14923.0 14985.7 421863.0 509180.4 509197.5 509355.5 509475.0 52341.2 52516.0 52574.7 52660.7 52720.0 1333.8 1379.2 1383.2 1383.9 1389.6 27622.9 27688.6 27842.4 28357.0 28801.4 44882.6 45340.8 45353.8 45459.0 45480.0 440449.0 441847.0 442600.0 444513.0 444677.0 31692.2 32207.2 35350.0 36025.6 36377.8 38020.9 39343.8 39608.0 40076.2 40118.0 36374.1 36460.9 37073.9 37196.6 38098.8 35588.5 35825.0 36357.5 36967.0 37166.8 35584.0 35603.3 36257.6 36294.8 36425.9 29575.0 30921.8 32167.5 32190.0 32291.4 23721.9 23762.5 23976.0 24341.9 24426.8 05:13:32 INFO - PROCESS | 1643 | 05:13:32 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:33770.08 Median:366.94 stddev:86359.87 (23535.2%) stddev-sans-first:86565.68 05:13:32 INFO - PROCESS | 1643 | Values: 351.1 359.3 366.5 367.4 395.7 14485.9 14658.7 15590.9 19700.4 20196.1 16751.8 20211.6 20360.5 21088.7 21166.5 5150.0 5613.8 5912.4 5932.0 5933.6 4306.0 4933.0 5680.7 5923.2 5937.6 5875.2 5889.6 5895.7 5896.8 5899.7 5569.9 5731.1 5767.7 5835.6 5854.8 5075.7 5137.3 5143.8 5145.9 6795.1 20671.8 26019.2 27030.0 27736.6 27891.9 2795.9 2885.6 2893.6 2952.8 2983.1 15924.8 15933.8 16422.6 16447.6 16643.4 1997.1 2000.1 2018.8 2044.3 2049.6 122094.4 125204.7 137539.3 137645.0 140571.0 18715.7 20314.9 20559.8 20823.7 20834.4 35001.3 35073.5 35121.0 35121.6 35216.6 9965.0 10168.8 10298.3 10366.2 10514.7 8385.7 8394.7 8429.9 8536.8 8575.4 7794.9 9320.6 9402.2 9603.7 9627.3 3594.7 3717.8 3720.2 3721.4 3730.3 805.3 945.2 977.9 993.9 1008.0 20289.5 20811.0 20852.9 20860.3 20920.0 991.0 997.7 1014.5 1019.1 1021.7 10133.9 10431.6 10755.7 10855.8 10868.7 467768.0 471875.0 474897.0 551863.0 563917.0 134901.0 139142.0 143390.0 144235.0 145047.1 967.9 1013.8 1023.1 1026.5 1026.5 14645.4 18698.4 18864.6 18889.7 19501.0 49996.5 50591.2 51857.0 52488.7 55414.0 54640.7 64545.3 71107.0 73027.0 73062.6 7712.5 7982.6 8010.8 8411.8 8435.3 8439.0 8595.6 8890.9 8927.1 9312.3 7796.8 7945.0 8069.2 8118.3 8173.0 7494.2 7518.2 7996.2 8070.2 8119.3 7936.4 7939.2 7950.1 8105.3 8169.2 11204.9 12011.9 12073.1 12212.9 12311.8 11404.5 11586.4 11853.3 11949.4 11976.5 05:13:32 INFO - PROCESS | 1643 | 05:13:32 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5326.54 Median:5134.17 stddev:2914.46 (56.8%) stddev-sans-first:2906.87 05:13:32 INFO - PROCESS | 1643 | Values: 1292.9 1390.8 1408.4 1429.6 1436.0 7370.2 7752.1 8022.6 8295.6 8352.0 8170.6 8180.1 8190.0 8291.9 8311.3 2011.6 2262.5 2319.2 2323.9 2323.9 2079.4 2245.2 2324.7 2335.1 2341.9 2252.6 2275.5 2277.2 2281.3 2286.3 2104.4 2274.0 2305.8 2308.0 2308.4 2136.6 2290.5 2334.3 2337.6 2339.5 8252.6 8335.2 8352.2 8355.2 8365.7 4936.7 5082.6 5099.5 5102.9 5110.6 2834.1 3215.2 3328.8 3408.6 3410.5 1114.5 1183.6 1188.0 1230.7 1232.9 10565.4 10634.0 10711.6 10713.8 10765.9 3731.6 4377.2 4466.6 4550.5 4555.7 9123.1 9279.9 9290.4 9316.5 9349.3 5124.1 5128.0 5133.1 5135.2 5135.8 4088.8 4621.8 4655.4 4705.7 4707.6 3705.3 3732.7 3768.5 3829.0 3843.0 1080.8 1233.2 1262.3 1272.2 1278.3 742.7 758.2 868.4 883.8 927.3 5433.6 5558.5 5655.4 5709.6 5867.0 865.3 938.5 962.9 978.4 980.2 3676.8 3700.7 3725.6 3810.9 3852.7 9603.7 9686.0 9785.0 9888.8 9891.4 9317.5 9704.5 9828.9 9851.2 9859.8 7494.9 7714.9 7722.4 7738.5 7747.5 5069.7 5079.4 5132.5 5178.4 5279.8 7686.3 8786.7 8831.7 9079.6 9259.4 9063.6 9217.5 9267.4 9275.6 9374.1 6967.7 7180.7 7192.5 7194.2 7217.3 7648.3 7707.7 7793.5 7800.8 7814.5 6988.4 7034.7 7044.6 7051.2 7082.6 6885.3 6906.8 6958.7 7023.5 7031.2 6211.9 6215.6 6262.4 6312.3 6377.1 4260.4 4465.4 4589.7 4649.0 4668.0 4767.4 4776.4 4851.6 4919.8 5027.4 05:13:32 INFO - PROCESS | 1643 | 05:13:32 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4450.17 Median:4895.89 stddev:1320.01 (27.0%) stddev-sans-first:1286.64 05:13:32 INFO - PROCESS | 1643 | Values: 311.5 326.4 350.8 358.4 360.6 4563.4 4698.4 4698.8 4766.9 4974.4 4993.0 5158.9 5213.5 5253.5 5267.7 3652.8 4106.7 4118.8 4127.3 4140.4 3449.8 3622.3 4140.8 4149.9 4156.9 3609.2 3940.3 3985.0 3998.9 4127.9 3577.1 4085.4 4095.7 4101.1 4126.2 2782.6 3531.2 3616.9 3644.4 3661.9 4182.8 4555.0 4899.3 5019.9 5092.8 4720.4 4745.8 4746.3 4749.0 4766.3 4892.4 5219.7 5308.4 5544.9 5636.7 3349.5 4004.9 4008.2 4008.9 4014.9 5526.0 5761.9 5796.7 5810.8 5826.0 3812.2 4085.2 4114.5 4118.7 4135.3 4001.0 4113.5 4177.0 4180.4 4182.8 4486.1 4537.8 4583.5 4594.4 4710.6 4751.4 5061.9 5504.0 5559.6 5657.5 1274.6 1335.4 1352.5 1368.7 1381.4 928.5 951.7 953.5 958.9 980.9 4227.0 4687.2 4876.3 4918.4 4944.6 4806.7 5314.9 5353.9 5436.5 5443.7 4550.5 4567.5 4922.6 4954.6 5024.3 2488.2 2569.8 2841.9 2867.6 2868.2 5002.7 5092.9 5232.5 5514.3 5548.4 5131.7 5145.6 5220.9 5390.2 5582.9 4857.5 5153.5 5395.7 5622.8 5631.7 4662.6 5395.0 5407.8 5445.0 5446.9 4667.6 4817.6 5023.9 5678.6 5719.8 5560.7 5910.2 5947.3 5950.2 5979.3 3439.1 3679.4 3684.1 3806.4 3848.9 5450.6 5621.8 5707.9 5721.6 5745.1 4768.9 5346.3 5445.3 5451.3 5662.2 5143.6 5191.6 5548.3 5562.2 5575.9 4584.9 5417.1 5423.6 5464.6 5486.9 5031.3 5510.6 5521.4 5525.2 5552.6 4766.7 5260.9 5428.5 5469.3 5499.8 05:13:32 INFO - PROCESS | 1643 | 05:13:32 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20830.80 Median:3995.03 stddev:30690.12 (768.2%) stddev-sans-first:30738.98 05:13:32 INFO - PROCESS | 1643 | Values: 697.0 719.5 742.3 795.5 832.0 14365.8 15532.1 15549.9 15563.3 15568.1 13675.0 14011.0 14252.0 14307.6 14334.9 862.9 887.7 889.1 890.2 892.7 684.7 713.6 873.8 877.9 879.7 755.5 893.4 927.2 934.8 940.1 932.0 935.5 940.3 945.7 946.6 531.6 694.0 694.1 696.6 697.6 12481.8 14369.2 14901.8 15048.3 15378.7 14188.6 14207.8 14232.9 14267.6 14325.4 11573.0 11655.0 11741.3 11871.5 12140.3 3603.4 3650.5 3805.8 3817.7 3831.6 105176.5 109731.9 109740.4 110460.0 110640.4 10518.4 10999.3 11008.7 11038.6 11168.8 22535.3 22826.1 22905.0 22933.3 23229.2 7579.7 7620.5 7624.7 7677.7 7707.8 3650.3 4189.1 4257.8 4306.5 4403.5 3500.5 3579.6 3608.9 3612.2 3715.6 672.9 675.1 675.5 678.5 686.2 511.7 546.8 550.3 552.6 556.0 8289.2 8569.3 8807.6 8884.9 8885.4 643.5 644.1 653.7 666.7 672.0 4158.5 4404.4 4405.8 4415.2 4451.6 105005.0 106738.0 107734.0 108932.0 109830.5 91288.5 91470.0 92694.0 93061.5 93756.1 79154.0 79954.6 80807.8 80810.6 81407.6 8506.5 8521.5 8526.6 8618.8 8727.5 51117.7 52095.7 52754.0 52825.2 55232.0 70280.3 70574.6 71175.0 71931.6 72580.6 11362.9 11856.3 11934.3 12128.0 12387.0 12459.6 12663.4 12772.7 13000.9 13115.9 11674.9 11764.3 11787.2 11984.4 12510.6 11721.4 11807.5 12419.9 12935.0 13711.6 13156.6 13255.5 13449.7 13528.3 14090.2 10986.2 11069.6 11344.5 11400.4 11485.4 10800.3 10813.9 10817.5 10827.0 10849.3 05:13:32 INFO - PROCESS | 1643 | -------- Summary: end -------- 05:13:32 INFO - PROCESS | 1643 | 05:13:33 INFO - TEST-INFO | 1643: exit 0 05:13:33 INFO - TEST-OK | dromaeo_css | took 1120510ms 05:13:33 INFO - TEST-START | kraken 05:13:33 INFO - operating with platform_type : mac_ 05:13:33 INFO - Initialising browser for kraken test... 05:13:33 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpHVi5US/profile http://localhost:49243/getInfo.html) 05:13:33 INFO - PROCESS | 1649 | 1483449213893 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4986 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3827 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2831 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 05:13:35 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 05:13:35 INFO - PROCESS | 1649 | colorDepth:24 05:13:35 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 05:13:35 INFO - PROCESS | 1649 | __metrics 05:13:35 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:13:35 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:13:36 INFO - TEST-INFO | 1649: exit 0 05:13:36 INFO - Browser initialized. 05:13:36 INFO - Running cycle 1/1 for kraken test... 05:13:36 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpHVi5US/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:13:48 INFO - PROCESS | 1650 | RSS: Main: 233250816 05:13:48 INFO - PROCESS | 1650 | 05:13:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 05:13:52 INFO - PROCESS | 1650 | RSS: Main: 286072832 05:13:52 INFO - PROCESS | 1650 | 05:13:54 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 05:13:55 INFO - PROCESS | 1650 | RSS: Main: 285696000 05:13:55 INFO - PROCESS | 1650 | 05:13:58 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 05:13:59 INFO - PROCESS | 1650 | RSS: Main: 378884096 05:13:59 INFO - PROCESS | 1650 | 05:14:01 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 05:14:01 INFO - PROCESS | 1650 | RSS: Main: 281841664 05:14:01 INFO - PROCESS | 1650 | 05:14:03 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:14:04 INFO - PROCESS | 1650 | RSS: Main: 285589504 05:14:04 INFO - PROCESS | 1650 | 05:14:07 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 05:14:07 INFO - PROCESS | 1650 | RSS: Main: 326750208 05:14:07 INFO - PROCESS | 1650 | 05:14:10 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 05:14:11 INFO - PROCESS | 1650 | RSS: Main: 328806400 05:14:11 INFO - PROCESS | 1650 | 05:14:14 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 05:14:14 INFO - PROCESS | 1650 | RSS: Main: 325844992 05:14:14 INFO - PROCESS | 1650 | 05:14:16 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:14:17 INFO - PROCESS | 1650 | RSS: Main: 304136192 05:14:17 INFO - PROCESS | 1650 | 05:14:19 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:14:19 INFO - PROCESS | 1650 | RSS: Main: 306851840 05:14:19 INFO - PROCESS | 1650 | 05:14:21 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:14:22 INFO - PROCESS | 1650 | RSS: Main: 308776960 05:14:22 INFO - PROCESS | 1650 | 05:14:25 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:14:26 INFO - PROCESS | 1650 | RSS: Main: 322703360 05:14:26 INFO - PROCESS | 1650 | 05:14:29 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:14:29 INFO - PROCESS | 1650 | RSS: Main: 316665856 05:14:29 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 05:14:31 INFO - PROCESS | 1650 | RSS: Main: 361037824 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | __start_tp_report 05:14:31 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 05:14:31 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 05:14:31 INFO - PROCESS | 1650 | |i|pagename|runs| 05:14:31 INFO - PROCESS | 1650 | |0;ai-astar;113;113;112;113;110;113;113;113;112;112 05:14:31 INFO - PROCESS | 1650 | |1;audio-beat-detection;145;108;114;182;144;129;109;109;128;119 05:14:31 INFO - PROCESS | 1650 | |2;audio-dft;176;170;182;186;184;184;184;184;183;179 05:14:31 INFO - PROCESS | 1650 | |3;audio-fft;81;59;61;105;86;84;73;61;78;59 05:14:31 INFO - PROCESS | 1650 | |4;audio-oscillator;88;69;85;70;69;68;83;69;70;84 05:14:31 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;87;86;88;84;87;84;90;85;88;84 05:14:31 INFO - PROCESS | 1650 | |6;imaging-darkroom;93;94;95;95;101;142;112;94;93;94 05:14:31 INFO - PROCESS | 1650 | |7;imaging-desaturate;85;85;87;84;85;85;84;85;85;86 05:14:31 INFO - PROCESS | 1650 | |8;json-parse-financial;56;56;55;55;56;54;54;56;55;55 05:14:31 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;68;73;78;107;112;103;70;70;69;69 05:14:31 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;89;86;88;87;90;86;85;82;84;91 05:14:31 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;141;146;152;141;138;140;140;137;184;148 05:14:31 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;177;234;193;176;233;210;170;208;170;172 05:14:31 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;66;67;67;65;66;66;66;66;67;66 05:14:31 INFO - PROCESS | 1650 | __end_tp_report 05:14:31 INFO - PROCESS | 1650 | __start_cc_report 05:14:31 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1960 05:14:31 INFO - PROCESS | 1650 | __end_cc_report 05:14:31 INFO - PROCESS | 1650 | __startTimestamp1483449271918__endTimestamp 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | ------- Summary: start ------- 05:14:31 INFO - PROCESS | 1650 | Number of tests: 14 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.00 stddev:0.97 (0.9%) stddev-sans-first:1.00 05:14:31 INFO - PROCESS | 1650 | Values: 113.0 113.0 112.0 113.0 110.0 113.0 113.0 113.0 112.0 112.0 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:128.70 Median:128.50 stddev:23.23 (18.1%) stddev-sans-first:23.88 05:14:31 INFO - PROCESS | 1650 | Values: 145.0 108.0 114.0 182.0 144.0 129.0 109.0 109.0 128.0 119.0 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:181.20 Median:184.00 stddev:4.89 (2.7%) stddev-sans-first:4.82 05:14:31 INFO - PROCESS | 1650 | Values: 176.0 170.0 182.0 186.0 184.0 184.0 184.0 184.0 183.0 179.0 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:74.70 Median:75.50 stddev:15.11 (20.0%) stddev-sans-first:15.85 05:14:31 INFO - PROCESS | 1650 | Values: 81.0 59.0 61.0 105.0 86.0 84.0 73.0 61.0 78.0 59.0 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:75.50 Median:76.50 stddev:8.29 (10.8%) stddev-sans-first:7.46 05:14:31 INFO - PROCESS | 1650 | Values: 88.0 69.0 85.0 70.0 69.0 68.0 83.0 69.0 70.0 84.0 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:86.30 Median:87.00 stddev:2.06 (2.4%) stddev-sans-first:2.17 05:14:31 INFO - PROCESS | 1650 | Values: 87.0 86.0 88.0 84.0 87.0 84.0 90.0 85.0 88.0 84.0 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:101.30 Median:94.00 stddev:15.45 (16.4%) stddev-sans-first:16.09 05:14:31 INFO - PROCESS | 1650 | Values: 93.0 94.0 95.0 95.0 101.0 142.0 112.0 94.0 93.0 94.0 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.00 stddev:0.88 (1.0%) stddev-sans-first:0.93 05:14:31 INFO - PROCESS | 1650 | Values: 85.0 85.0 87.0 84.0 85.0 85.0 84.0 85.0 85.0 86.0 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.50 stddev:0.79 (1.4%) stddev-sans-first:0.78 05:14:31 INFO - PROCESS | 1650 | Values: 56.0 56.0 55.0 55.0 56.0 54.0 54.0 56.0 55.0 55.0 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:81.90 Median:69.50 stddev:17.90 (25.8%) stddev-sans-first:18.27 05:14:31 INFO - PROCESS | 1650 | Values: 68.0 73.0 78.0 107.0 112.0 103.0 70.0 70.0 69.0 69.0 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:87.50 stddev:2.78 (3.2%) stddev-sans-first:2.83 05:14:31 INFO - PROCESS | 1650 | Values: 89.0 86.0 88.0 87.0 90.0 86.0 85.0 82.0 84.0 91.0 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:143.50 stddev:13.93 (9.7%) stddev-sans-first:14.62 05:14:31 INFO - PROCESS | 1650 | Values: 141.0 146.0 152.0 141.0 138.0 140.0 140.0 137.0 184.0 148.0 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:194.30 Median:200.50 stddev:25.43 (12.7%) stddev-sans-first:26.20 05:14:31 INFO - PROCESS | 1650 | Values: 177.0 234.0 193.0 176.0 233.0 210.0 170.0 208.0 170.0 172.0 05:14:31 INFO - PROCESS | 1650 | 05:14:31 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 05:14:31 INFO - PROCESS | 1650 | Values: 66.0 67.0 67.0 65.0 66.0 66.0 66.0 66.0 67.0 66.0 05:14:31 INFO - PROCESS | 1650 | -------- Summary: end -------- 05:14:31 INFO - PROCESS | 1650 | 05:14:32 INFO - TEST-INFO | 1650: exit 0 05:14:32 INFO - TEST-OK | kraken | took 59525ms 05:14:32 INFO - SUITE-END | took 1180s 05:14:33 INFO - Completed test suite (00:19:40) 05:14:33 INFO - javascript benchmark 05:14:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3353.8323083845803, 3377.746668999825, 3384.035549868288, 3391.3386889556546, 3449.810260435675, 5668.489835914766, 5730.426957304273, 5735.426457354262, 5774.942250577493, 5804.419558044198, 5720.22776925115, 5729.914051289234, 5752.194692743019, 5755.913661295084, 5771.365149833515, 5177.896442071147, 5219.112750832368, 5228.73856307185, 5249.947500524984, 5286.4449232830575, 5318.813841515548, 5320.095583750772, 5320.281761962125, 5322.88219473911, 5327.760250788724, 5312.495312945264, 5318.680879147244, 5324.387695415019, 5331.440198779131, 5340.385855626594, 5306.416294207634, 5322.866928326784, 5323.707196104193, 5325.3077099977145, 5337.786488540453, 4420.9336859947125, 5206.947930520684, 5274.472552744694, 5276.81531146408, 5280.1815718563685, 5756.194132821408, 5779.768809247625, 5807.0, 5864.237649105632, 5881.176635271066, 6269.717862696144, 6310.5267104967315, 6322.968385158045, 6324.683765811737, 6335.208098987627, 6158.445739883432, 6191.195144631214, 6239.65681887496, 6241.968790156066, 6273.184486016834, 5832.387599302097, 5839.416058394211, 5853.0, 5860.70696465175, 5864.589478736448, 6311.305756366796, 6321.620702757849, 6323.683815809191, 6326.304106548276, 6328.050792381179, 6040.637561746309, 6082.878342433127, 6086.452219300284, 6128.575417880035, 6200.844978875474, 6172.321044685081, 6217.347178546278, 6226.346233645492, 6232.626042437469, 6237.438630523275, 6117.775897257223, 6124.544022963324, 6170.074488826712, 6172.660503672341, 6266.373362663697, 6295.087212354275, 6301.338359472281, 6312.084747711556, 6318.557700960889, 6333.081703153017, 4959.677620954626, 4959.70241785494, 4963.429205641325, 4973.1297023020825, 4989.151844186497, 4400.141972315432, 4404.163208990281, 4417.889552761207, 4427.535108813592, 4459.331100334975, 5882.294124705063, 5883.970580147072, 5891.617044892154, 5897.970510147422, 5935.080062590306, 6301.306856245809, 6349.936500635027, 6351.745930162741, 6351.745930162834, 6352.174217351807, 5677.545796336283, 5755.510781583528, 5772.336181339116, 5879.911801322984, 5888.735006924699, 5390.541803946709, 5407.891842163135, 5408.242846001563, 5408.891822163535, 5409.215663728737, 5352.518273355417, 6278.717457714414, 6361.109444677842, 6377.202849643794, 6388.233411990591, 6093.725782339717, 6178.567500274938, 6297.716602752796, 6338.334474880071, 6343.175387199634, 6256.061590761422, 6346.904796427965, 6356.68216589178, 6378.2983871774995, 6384.297727249906, 6127.953177891068, 6138.051223665453, 6155.692215389302, 6174.783882564088, 6188.597741146811, 5210.8957820844125, 6272.466840318624, 6331.493480521639, 6335.809925702236, 6341.460975817107, 5590.881823635371, 5727.570432217517, 6055.969720151371, 6137.754489820357, 6149.200603921461, 5901.7639294427745, 6017.699115044143, 6019.247594050744, 6108.645631960618, 6119.571629985859, 5565.6104072714525, 5712.143178523089, 6045.516358691206, 6050.273967123797, 6054.8183554493435, 6155.876882462415, 6180.289266734383, 6187.938120618736, 6212.81361559154, 6218.253809542884, 5798.884022319615, 5839.416058394127, 5855.619384739978, 5952.613080149777, 6008.339082700812], "value": 5725.506057702543, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.3644242962844, 492.50995259716564, 498.61108335498324, 510.22445882258944, 522.0759256116673, 24048.79756012201, 37614.24771504572, 39299.0, 39348.0, 39494.01264968377, 39709.8014509927, 40100.59899401012, 40185.79907100468, 40186.39720404189, 40893.38659920096, 8344.874826877602, 9160.175584197405, 9369.67206147785, 9370.672026479075, 9393.0, 8368.790780230513, 10973.286736362152, 11220.775584488305, 11247.212695111304, 11438.71403214922, 9539.80920381592, 11086.556537738481, 11228.66314010577, 11257.831132533018, 11279.887201127965, 11021.724456888562, 11139.33164010162, 11214.551417943354, 11218.495167717472, 11219.775604487864, 11028.062614677761, 11096.722581935435, 11104.833427498594, 11106.61126860556, 11113.499892504857, 37711.43432848509, 37773.81113094444, 37787.244255115016, 37826.43260351097, 37863.62136378628, 3968.0675041365257, 3972.066564357374, 3983.701222408331, 4006.759594424315, 4011.2579172853116, 28268.0, 29029.0, 29124.0, 29246.561301580492, 29326.70673293261, 3107.813531188136, 3153.716165545112, 3153.8896138635037, 3163.873445062218, 3264.05342450689, 416253.0, 419791.0, 419854.0, 421351.0, 422317.88841055904, 52980.205296919805, 54073.459265407626, 54601.63495912634, 54641.1803822943, 54806.0, 57045.714771426705, 57099.0, 57228.713856431285, 57240.0, 57267.14099288514, 14816.259187040605, 15312.770308445382, 15316.387344506318, 15328.540143795704, 15343.61640958985, 9598.568064436977, 10696.62561810333, 11046.061084807881, 11829.881701182878, 11930.22453540517, 14211.147331160164, 14497.637559061108, 14561.053531520418, 14718.0433271837, 14761.261936903113, 6390.904136437957, 6423.229212494531, 6454.612723236621, 6464.22429308486, 6470.288268290485, 830.5930094253893, 833.3208435125354, 836.1554829622125, 843.8860703870831, 857.1471385970999, 28140.859295703798, 28290.43419131647, 28339.57490637642, 28751.56872646912, 29053.854730726212, 1006.0744115413837, 1027.1166796554958, 1033.7725700345911, 1043.3479075577764, 1044.7022598559527, 13477.865221347856, 14158.29208539569, 14632.48786292496, 14923.0, 14985.700285994219, 421863.0, 509180.3622945659, 509197.45401272754, 509355.4532227389, 509475.0, 52341.2148817768, 52516.0, 52574.737126314125, 52660.73669631627, 52720.0, 1333.8266025416633, 1379.200063962899, 1383.1631862722907, 1383.9169649820842, 1389.6456403617215, 27622.861885690443, 27688.58467123035, 27842.44315113646, 28357.0, 28801.423971520035, 44882.55117448784, 45340.77329613331, 45353.77323113364, 45459.0, 45480.0, 440449.0, 441847.0, 442600.0, 444513.0, 444677.0, 31692.207694807814, 32207.199350134695, 35350.0, 36025.63974360223, 36377.81811090928, 38020.85937421882, 39343.80328098455, 39608.0, 40076.198476029735, 40117.99705007398, 36374.09064773402, 36460.90617281486, 37073.86152634894, 37196.6280337204, 38098.80950595229, 35588.466173006906, 35825.0, 36357.45463818098, 36967.0, 37166.814165929, 35584.0, 35603.28793424065, 36257.64325318846, 36294.8185259072, 36425.90722278336, 29575.0, 30921.76312947457, 32167.51748723724, 32190.0, 32291.35417291688, 23721.88139059294, 23762.524749505257, 23976.0, 24341.878290609144, 24426.75573244245], "value": 18923.8396841288, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.11168743079986, 359.2814371257484, 366.5015578812814, 367.37546171508427, 395.6913607386238, 14485.927570362146, 14658.706825863477, 15590.92204538977, 19700.408987730356, 20196.091175897083, 16751.83248167518, 20211.59576808463, 20360.490987725276, 21088.68366974493, 21166.470838229052, 5149.974250128744, 5613.831585052455, 5912.379200183983, 5931.970340148293, 5933.5549833762425, 4305.956940430587, 4932.950670493303, 5680.6591604503865, 5923.229980102581, 5937.554683398741, 5875.178694604665, 5889.6171748836405, 5895.705214739267, 5896.764129434818, 5899.734511946951, 5569.860753481175, 5731.140328950649, 5767.653940763546, 5835.562332825055, 5854.76580936762, 5075.670081444731, 5137.332146820927, 5143.8456846294675, 5145.871353216162, 6795.082663840368, 20671.759694418382, 26019.21942341733, 27030.0, 27736.583951240747, 27891.86054069737, 2795.930101747452, 2885.6104425902445, 2893.594896714462, 2952.822830630169, 2983.1199796059996, 15924.76112858308, 15933.760993585105, 16422.589435264214, 16447.588810279718, 16643.41748038813, 1997.1412292714126, 2000.1399398258889, 2018.794298001105, 2044.3355909329528, 2049.631066408046, 122094.38952805179, 125204.74795252022, 137539.31230343884, 137645.0, 140571.0, 18715.71926421105, 20314.898425507778, 20559.794402056086, 20823.709391966873, 20834.374968750963, 35001.29997400038, 35073.473897891556, 35121.0, 35121.64878351235, 35216.59133634626, 9964.950175249222, 10168.847467287997, 10298.279668676854, 10366.170706343475, 10514.684559463229, 8385.664573416994, 8394.664213431395, 8429.873551896726, 8536.786580335542, 8575.399722019523, 7794.9220507795335, 9320.627174912928, 9402.247820174369, 9603.663871764595, 9627.277954153467, 3594.712423006153, 3717.8326975285636, 3720.2373513429684, 3721.3673675475234, 3730.3213553900855, 805.295366554265, 945.2013048973606, 977.9462129582942, 993.9276608271834, 1007.9546420411099, 20289.49276268105, 20811.0, 20852.895735521226, 20860.269890553758, 20920.0, 990.9504524773732, 997.6607953295769, 1014.523174108168, 1019.1286450085219, 1021.7037059252882, 10133.898661013443, 10431.634892778717, 10755.731106722395, 10855.782884342269, 10868.673939781662, 467768.0, 471875.0, 474897.0, 551863.0, 563917.0, 134901.0, 139142.0, 143390.0, 144235.0, 145047.06723390523, 967.8886928003229, 1013.8023085498257, 1023.1405619279692, 1026.4508487958906, 1026.5124066068706, 14645.414183432544, 18698.43904682862, 18864.62270754605, 18889.71665425047, 19501.0, 49996.50003500064, 50591.241131383795, 51857.0, 52488.73755631197, 55414.0, 54640.726796365765, 64545.31367932638, 71107.0, 73027.0, 73062.63468682622, 7712.506861440989, 7982.564762989885, 8010.759677209693, 8411.83176336482, 8435.283000944988, 8439.0, 8595.570221488775, 8890.911090889009, 8927.062658420773, 9312.301577381588, 7796.766097017098, 7945.0, 8069.233422824822, 8118.269355757774, 8172.959135204286, 7494.17564067963, 7518.210587888193, 7996.160403157584, 8070.192980702118, 8119.30985866208, 7936.365090792839, 7939.166387529457, 7950.125486196629, 8105.27052565272, 8169.223923727236, 11204.887951120385, 12011.939940300243, 12073.094517911017, 12212.938935305267, 12311.75376492483, 11404.48679809394, 11586.42067896585, 11853.348065856117, 11949.402529873645, 11976.46105925218], "value": 10242.27497923487, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1292.8836404723584, 1390.8331000279966, 1408.3943904121231, 1429.6282966428732, 1436.045030055013, 7370.1892791792925, 7752.147263800985, 8022.598870056496, 8295.585220738954, 8352.039515455725, 8170.6323215455295, 8180.059293181286, 8189.95905020474, 8291.875621865676, 8311.25198732114, 2011.5976804639058, 2262.5474905019023, 2319.211468100845, 2323.8838058097112, 2323.8954247058837, 2079.386580958613, 2245.236619549353, 2324.6861673674093, 2335.0776443304885, 2341.9461242440884, 2252.6395776675736, 2275.4766403727153, 2277.2257432472957, 2281.3498153026308, 2286.336962280945, 2104.3897269791896, 2274.033535747301, 2305.7924786769104, 2308.0190918859416, 2308.4113551044543, 2136.583366243583, 2290.5189910118893, 2334.2880421471446, 2337.6026075567024, 2339.4619237575453, 8252.587370631445, 8335.208155225304, 8352.16478352166, 8355.206255405727, 8365.749027529184, 4936.654434189608, 5082.567981721558, 5099.541041306263, 5102.872428189288, 5110.565601923841, 2834.135588645462, 3215.2122729931316, 3328.7503437242303, 3408.596939157888, 3410.471376936579, 1114.4761961877905, 1183.591660876996, 1187.9821802672966, 1230.655416483386, 1232.9075319350995, 10565.41890196039, 10634.042936135786, 10711.571537138505, 10713.785724285548, 10765.892341076646, 3731.552213734315, 4377.233984052778, 4466.57567531084, 4550.47669518003, 4555.658325625592, 9123.087691230823, 9279.907200928039, 9290.396124251902, 9316.534173291308, 9349.252059835197, 5124.103281925648, 5128.0, 5133.101707201299, 5135.229715542624, 5135.820246291437, 4088.795560221977, 4621.758447153128, 4655.37152484417, 4705.7411842349, 4707.576318131385, 3705.27747089315, 3732.738708290448, 3768.491253680718, 3828.9617103828605, 3842.9615703843165, 1080.8000519903842, 1233.179935343005, 1262.2994238197705, 1272.243015405832, 1278.341654048166, 742.684359147371, 758.1698040645484, 868.3747701654798, 883.8276536075532, 927.3230541704594, 5433.646812957145, 5558.527525160326, 5655.406182350794, 5709.571782116358, 5866.970665146647, 865.2818160926416, 938.5354249646468, 962.8892677342195, 978.4227305889559, 980.1668581705493, 3676.8161591920298, 3700.740948133605, 3725.5529336479794, 3810.904727381838, 3852.7303088783515, 9603.711888643353, 9685.951570242103, 9785.0, 9888.802223955481, 9891.406515609087, 9317.487538185465, 9704.514774261259, 9828.852567211497, 9851.162651174587, 9859.75350616226, 7494.85010299791, 7714.922850771421, 7722.420818438527, 7738.535687858746, 7747.496412733154, 5069.670471419346, 5079.44126146131, 5132.538071573576, 5178.404483484297, 5279.788808447619, 7686.346660533917, 8786.692465763668, 8831.690890819047, 9079.636814527345, 9259.444433334022, 9063.63745450201, 9217.539123043955, 9267.351285409957, 9275.62897484093, 9374.062593740571, 6967.7212911482975, 7180.676869540987, 7192.460565457716, 7194.2086370498155, 7217.278272172741, 7648.273414025659, 7707.730229441942, 7793.532388056735, 7800.765977020698, 7814.4529882907655, 6988.4059854910865, 7034.683439245144, 7044.647767611496, 7051.224365319913, 7082.645867706491, 6885.277045910308, 6906.792796216121, 6958.721651133898, 7023.543469674455, 7031.156261248683, 6211.875762484816, 6215.627062376272, 6262.373762623702, 6312.3056463788025, 6377.107204991212, 4260.4035435038495, 4465.352523884084, 4589.655775816894, 4648.953510464852, 4668.043051174569, 4767.404074490689, 4776.355192049029, 4851.56335929768, 4919.778609962489, 5027.446980832033], "value": 4325.229226202323, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.45650839285435, 326.4401551339452, 350.82458770614693, 358.3710587918203, 360.624950051946, 4563.361129441881, 4698.412698412699, 4698.835540756074, 4766.856994290169, 4974.427940786811, 4992.950070499294, 5158.942416804555, 5213.530782229599, 5253.527182553569, 5267.683938963665, 3652.7808331500064, 4106.733062350952, 4118.752874827505, 4127.318992366256, 4140.358244472097, 3449.7930124192503, 3622.3117607654463, 4140.799168241207, 4149.917001659965, 4156.937645935298, 3609.205974685578, 3940.345055076875, 3985.043589538519, 3998.900027499307, 4127.855525056623, 3577.1057235690946, 4085.44846445729, 4095.692823038284, 4101.088227358579, 4126.154138401621, 2782.6243457133232, 3531.1525233943926, 3616.8553257869653, 3644.398674218751, 3661.9267614647824, 4182.790860456996, 4554.954450455486, 4899.338589290436, 5019.895622962942, 5092.79628814847, 4720.362751028601, 4745.786439610226, 4746.310976218548, 4748.976255118702, 4766.285057241442, 4892.437369702494, 5219.73901304937, 5308.389535203462, 5544.944550554484, 5636.661800291955, 3349.547811045502, 4004.8598299059686, 4008.178323443687, 4008.8596899108684, 4014.859479918218, 5526.0, 5761.88476230473, 5796.652200867961, 5810.796622118206, 5825.970870145665, 3812.1613245086037, 4085.22380747657, 4114.485689288839, 4118.711690181659, 4135.338345864648, 4000.979995100006, 4113.547509773923, 4176.979115104466, 4180.435641188462, 4182.832686692559, 4486.080353527519, 4537.776489520112, 4583.518730533313, 4594.425696787902, 4710.561290258131, 4751.429828420612, 5061.936993231389, 5503.972480137573, 5559.63862348946, 5657.538970547825, 1274.5915763081662, 1335.3990704183157, 1352.4590163934308, 1368.6715188354922, 1381.4267079162237, 928.4568527411404, 951.7097285327903, 953.537534295866, 958.8897276813116, 980.8626792249091, 4227.0, 4687.20317546018, 4876.268559716, 4918.385201849769, 4944.615507657862, 4806.668765648657, 5314.867128321823, 5353.86615334612, 5436.53789427903, 5443.727813609304, 4550.544945505423, 4567.497575266718, 4922.606191504671, 4954.621781544879, 5024.316853853927, 2488.203774792049, 2569.8072644551735, 2841.9431611368072, 2867.641544806899, 2868.164374800088, 5002.699838009731, 5092.9236061459105, 5232.529072383504, 5514.283143191359, 5548.361938377057, 5131.7434128293435, 5145.614078944094, 5220.929709409562, 5390.21841832932, 5582.944170558324, 4857.489963553776, 5153.45888681698, 5395.70323632204, 5622.831315060473, 5631.6902570359025, 4662.5546080715085, 5395.0, 5407.810281738011, 5444.985355418362, 5446.930166536691, 4667.556582124693, 4817.590504807131, 5023.874403139805, 5678.602497825113, 5719.799807006735, 5560.694161821139, 5910.231669882888, 5947.316058653138, 5950.16697662319, 5979.312379076462, 3439.1084903303586, 3679.4296883983025, 3684.0658654206754, 3806.4480650306105, 3848.8652897148463, 5450.645708028966, 5621.831345059655, 5707.857303567444, 5721.628094173865, 5745.1382292656435, 4768.880777980578, 5346.304980352529, 5445.26488924006, 5451.318585176853, 5662.207290979349, 5143.585513983684, 5191.585912550682, 5548.278723766046, 5562.165675148598, 5575.8884822304135, 4584.862454126382, 5417.133258678593, 5423.559398270715, 5464.617476776588, 5486.872482684831, 5031.295618613324, 5510.622344413897, 5521.365043020104, 5525.209769066596, 5552.639078459887, 4766.6901651392545, 5260.921086183786, 5428.45715428454, 5469.343678758574, 5499.807506737245], "value": 4045.4610573683694, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.0206859362592, 719.4819729794546, 742.2986514907831, 795.5425630262598, 831.9750407487776, 14365.784513232296, 15532.068075915453, 15549.922250388761, 15563.299651515681, 15568.14375209363, 13675.0, 14011.019228653999, 14252.0, 14307.57077287683, 14334.924880633942, 862.8705694145881, 887.7469921072495, 889.1375365895071, 890.1899271662788, 892.6652505310509, 684.7124207832697, 713.564725517434, 873.8470767615642, 877.9341549383822, 879.6525372477868, 755.4560716284294, 893.4371346051978, 927.184078011349, 934.7709811096291, 940.111594543157, 931.998102040306, 935.5182081228173, 940.3417607674655, 945.6501094595044, 946.5740416812394, 531.5641174237127, 693.972241110355, 694.146200173784, 696.5681277607915, 697.5814511293235, 12481.75036499274, 14369.209693466855, 14901.776473352907, 15048.32282547288, 15378.692426151527, 14188.645283867883, 14207.78688319676, 14232.92883535586, 14267.571972840831, 14325.426982920671, 11573.0, 11654.951054405059, 11741.29552226869, 11871.465784039652, 12140.3322817245, 3603.3874241378767, 3650.4889315495852, 3805.809709514513, 3817.675497582709, 3831.5593706723803, 105176.47411762968, 109731.90268097297, 109740.35389469164, 110460.0, 110640.4467977663, 10518.421486818146, 10999.285046472114, 11008.669739907815, 11038.558457661604, 11168.776624467466, 22535.323940281818, 22826.086956521885, 22905.0, 22933.312000640006, 23229.18697845601, 7579.658915348822, 7620.542767433861, 7624.695012199561, 7677.731279405194, 7707.845843083219, 3650.288193802236, 4189.0574620710095, 4257.829686812554, 4306.504751953503, 4403.5376285489565, 3500.492428597839, 3579.552555930509, 3608.9278214435562, 3612.1872578670113, 3715.5727091384297, 672.9259781424039, 675.1358261425383, 675.5035049238805, 678.4572342126279, 686.1663079358603, 511.66230288009734, 546.778554685353, 550.3368441028631, 552.5883217003358, 555.9694216818033, 8289.212524810133, 8569.271611913056, 8807.647694092293, 8884.8667269991, 8885.367300054391, 643.4641644165248, 644.0886146103305, 653.6992983227768, 666.74330382802, 671.9596824190467, 4158.500979882433, 4404.383386325917, 4405.845795397146, 4415.183191109655, 4451.554844515523, 105005.0, 106738.0, 107734.0, 108932.0, 109830.45084774525, 91288.54355728312, 91470.0, 92694.0, 93061.53469232611, 93756.06243937473, 79154.0, 79954.60022699965, 80807.78788318063, 80810.59594701989, 81407.592962036, 8506.489610623383, 8521.531315777444, 8526.573671316286, 8618.82762344737, 8727.476351418682, 51117.74441127771, 52095.73952130215, 52754.0, 52825.20762188493, 55232.0, 70280.29719702942, 70574.64712676404, 71175.0, 71931.64034179796, 72580.63709681417, 11362.886371136183, 11856.347900865536, 11934.28394296345, 12128.029757619193, 12387.01807710762, 12459.626211213677, 12663.366831658195, 12772.680682983, 13000.869991300344, 13115.868841311465, 11674.88325116772, 11764.294142351486, 11787.233829801036, 11984.40077996114, 12510.624681259576, 11721.413929303671, 11807.468663909973, 12419.93790031044, 12935.029872759393, 13711.588652340446, 13156.605301840576, 13255.469781208643, 13449.723249534683, 13528.255945922676, 14090.22503762303, 10986.230963832455, 11069.612563560237, 11344.546218151181, 11400.359510946082, 11485.368304743319, 10800.335895613829, 10813.891861081289, 10817.513211905325, 10827.0, 10849.349039057683], "value": 7365.296544032187, "unit": "score"}], "name": "dromaeo_css", "value": 7231.678198104229, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 112.0, 113.0, 110.0, 113.0, 113.0, 113.0, 112.0, 112.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 108.0, 114.0, 182.0, 144.0, 129.0, 109.0, 109.0, 128.0, 119.0], "value": 128.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 170.0, 182.0, 186.0, 184.0, 184.0, 184.0, 184.0, 183.0, 179.0], "value": 181.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 61.0, 105.0, 86.0, 84.0, 73.0, 61.0, 78.0, 59.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 85.0, 70.0, 69.0, 68.0, 83.0, 69.0, 70.0, 84.0], "value": 75.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 84.0, 87.0, 84.0, 90.0, 85.0, 88.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 95.0, 101.0, 142.0, 112.0, 94.0, 93.0, 94.0], "value": 101.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 84.0, 85.0, 85.0, 84.0, 85.0, 85.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 55.0, 55.0, 56.0, 54.0, 54.0, 56.0, 55.0, 55.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 73.0, 78.0, 107.0, 112.0, 103.0, 70.0, 70.0, 69.0, 69.0], "value": 81.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 87.0, 90.0, 86.0, 85.0, 82.0, 84.0, 91.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 146.0, 152.0, 141.0, 138.0, 140.0, 140.0, 137.0, 184.0, 148.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 234.0, 193.0, 176.0, 233.0, 210.0, 170.0, 208.0, 170.0, 172.0], "value": 194.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 65.0, 66.0, 66.0, 66.0, 66.0, 67.0, 66.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1476.3, "alertThreshold": 2.0}]} 05:14:33 INFO - Return code: 0 05:14:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:14:33 INFO - # TBPL SUCCESS # 05:14:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:14:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:14:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:14:33 INFO - 2017-01-03 05:14:33.258 screenresolution[1654:10613] starting screenresolution argv=screenresolution get 05:14:33 INFO - 2017-01-03 05:14:33.261 screenresolution[1654:10613] Display 0: 1600x1200x32@60 05:14:33 INFO - 2017-01-03 05:14:33.267 screenresolution[1655:10616] starting screenresolution argv=screenresolution list 05:14:33 INFO - Available Modes on Display 0 05:14:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:14:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:14:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:14:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:14:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:14:33 INFO - Intel Iris: 05:14:33 INFO - Chipset Model: Intel Iris 05:14:33 INFO - Type: GPU 05:14:33 INFO - Bus: Built-In 05:14:33 INFO - VRAM (Dynamic, Max): 1536 MB 05:14:33 INFO - Vendor: Intel (0x8086) 05:14:33 INFO - Device ID: 0x0a2e 05:14:33 INFO - Revision ID: 0x0009 05:14:33 INFO - Displays: 05:14:33 INFO - Display: 05:14:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:14:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:14:33 INFO - Display Serial Number: 1600x1200 60 05:14:33 INFO - Main Display: Yes 05:14:33 INFO - Mirror: Off 05:14:33 INFO - Online: Yes 05:14:33 INFO - Rotation: Supported 05:14:33 INFO - Return code: 0 05:14:33 INFO - Running post-action listener: _resource_record_post_action 05:14:33 INFO - [mozharness: 2017-01-03 13:14:33.507098Z] Finished run-tests step (success) 05:14:33 INFO - Running post-run listener: _resource_record_post_run 05:14:33 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32230400; Write bytes: 552599040; Read time: 192; Write time: 1701 05:14:33 INFO - TinderboxPrint: CPU usage
53.7% 05:14:33 INFO - TinderboxPrint: I/O read bytes / time
32,230,400 / 192 05:14:33 INFO - TinderboxPrint: I/O write bytes / time
552,599,040 / 1,701 05:14:33 INFO - TinderboxPrint: CPU idle
2,225.4 (46.3%) 05:14:33 INFO - TinderboxPrint: CPU system
961.2 (20.0%) 05:14:33 INFO - TinderboxPrint: CPU user
1,623.1 (33.7%) 05:14:33 INFO - TinderboxPrint: Swap in / out
277,487,616 / 0 05:14:33 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 228979200; Write bytes: 221253632; Read time: 16572; Write time: 474 05:14:33 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 31308800; Write bytes: 300521472; Read time: 181; Write time: 1147 05:14:33 INFO - Running post-run listener: _upload_blobber_files 05:14:33 INFO - Blob upload gear active. 05:14:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:14:33 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 05:14:33 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'] 05:14:33 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 05:14:34 INFO - (blobuploader) - INFO - Open directory for files ... 05:14:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:14:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:14:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:14:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:14:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:14:35 INFO - (blobuploader) - INFO - Done attempting. 05:14:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:14:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:14:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:14:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:14:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:14:36 INFO - (blobuploader) - INFO - Done attempting. 05:14:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:14:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:14:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:14:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:14:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:14:36 INFO - (blobuploader) - INFO - Done attempting. 05:14:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:14:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:14:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:14:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:14:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:14:39 INFO - (blobuploader) - INFO - Done attempting. 05:14:39 INFO - (blobuploader) - INFO - Iteration through files over. 05:14:39 INFO - Return code: 0 05:14:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:14:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:14:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0ba5b6779e76df94ff70b3904ce2741cb0ec818e717cb298836d26391d0a32fd3fdddeb53925f783e7e0a107dde69f25155bc941d95a164fa43f6997c20eca9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6acbdf7a2c29be77f83a8d90cae0699ae6de8b8b2cc27a9150b8a497a041c6e8467e52ea40dfc6e5cf29dafc3109fec24acb03f2ca6f3e40768d8e7ebf9c7366", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9823098cdc25f029a8c336163051c58fa640bb7a782df44114b52d02ec30ed9b28cc457403ae65c0e94c26d4ecc64e0633f710627bd9c6d5b677bfd13c82c5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0ab95a3d1bda6ee7d3ab464aaca3227e84c3741a80331316db9430488aa1e0161ddb21743208e2a5dfc4c7110a1238553d22edb383aba7851a9cbec872c9e54"} 05:14:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:14:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:14:39 INFO - Contents: 05:14:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0ba5b6779e76df94ff70b3904ce2741cb0ec818e717cb298836d26391d0a32fd3fdddeb53925f783e7e0a107dde69f25155bc941d95a164fa43f6997c20eca9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6acbdf7a2c29be77f83a8d90cae0699ae6de8b8b2cc27a9150b8a497a041c6e8467e52ea40dfc6e5cf29dafc3109fec24acb03f2ca6f3e40768d8e7ebf9c7366", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9823098cdc25f029a8c336163051c58fa640bb7a782df44114b52d02ec30ed9b28cc457403ae65c0e94c26d4ecc64e0633f710627bd9c6d5b677bfd13c82c5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0ab95a3d1bda6ee7d3ab464aaca3227e84c3741a80331316db9430488aa1e0161ddb21743208e2a5dfc4c7110a1238553d22edb383aba7851a9cbec872c9e54"} 05:14:39 INFO - Running post-run listener: copy_logs_to_upload_dir 05:14:39 INFO - Copying logs to upload dir... 05:14:39 INFO - mkdir: /builds/slave/test/build/upload/logs 05:14:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.890880 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-01-03 05:14:39.388688) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-03 05:14:39.392108) ========= 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.5yQwM5LAWf/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.md5pPs2FOo/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0ba5b6779e76df94ff70b3904ce2741cb0ec818e717cb298836d26391d0a32fd3fdddeb53925f783e7e0a107dde69f25155bc941d95a164fa43f6997c20eca9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6acbdf7a2c29be77f83a8d90cae0699ae6de8b8b2cc27a9150b8a497a041c6e8467e52ea40dfc6e5cf29dafc3109fec24acb03f2ca6f3e40768d8e7ebf9c7366", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9823098cdc25f029a8c336163051c58fa640bb7a782df44114b52d02ec30ed9b28cc457403ae65c0e94c26d4ecc64e0633f710627bd9c6d5b677bfd13c82c5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0ab95a3d1bda6ee7d3ab464aaca3227e84c3741a80331316db9430488aa1e0161ddb21743208e2a5dfc4c7110a1238553d22edb383aba7851a9cbec872c9e54"} build_url:https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011144 build_url: 'https://queue.taskcluster.net/v1/task/RBSRK0J6RweU8Bpds6xxvw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0ba5b6779e76df94ff70b3904ce2741cb0ec818e717cb298836d26391d0a32fd3fdddeb53925f783e7e0a107dde69f25155bc941d95a164fa43f6997c20eca9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6acbdf7a2c29be77f83a8d90cae0699ae6de8b8b2cc27a9150b8a497a041c6e8467e52ea40dfc6e5cf29dafc3109fec24acb03f2ca6f3e40768d8e7ebf9c7366", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d9823098cdc25f029a8c336163051c58fa640bb7a782df44114b52d02ec30ed9b28cc457403ae65c0e94c26d4ecc64e0633f710627bd9c6d5b677bfd13c82c5b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a0ab95a3d1bda6ee7d3ab464aaca3227e84c3741a80331316db9430488aa1e0161ddb21743208e2a5dfc4c7110a1238553d22edb383aba7851a9cbec872c9e54"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-03 05:14:39.426819) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 05:14:39.427147) ========= 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.5yQwM5LAWf/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.md5pPs2FOo/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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.005018 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-03 05:14:39.475619) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 05:14:39.475969) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-03 05:14:39.476301) ========= ========= Total master_lag: 0.21 =========