builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0300 starttime: 1483671334.84 results: success (0) buildid: 20170105173113 builduid: 3d84b506e6b74eddb8e07ddb3122cccb revision: a14094edbad78fc1d16e8d4c57902537cf286fd1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 18:55:34.836115) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 18:55:34.836553) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 18:55:34.836826) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 18:55:34.862757) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:55:34.863105) ========= 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.9v8IGTuwlI/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.mmCg8MUUIE/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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-05 18:55:34-- 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% 68.7M=0s 2017-01-05 18:55:35 (68.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.137638 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:55:35.024538) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:55:35.024847) ========= 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.9v8IGTuwlI/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.mmCg8MUUIE/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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.026033 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 18:55:35.073907) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-05 18:55:35.074265) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a14094edbad78fc1d16e8d4c57902537cf286fd1 --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 a14094edbad78fc1d16e8d4c57902537cf286fd1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9v8IGTuwlI/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.mmCg8MUUIE/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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-05 18:55:35,171 truncating revision to first 12 chars 2017-01-05 18:55:35,171 Setting DEBUG logging. 2017-01-05 18:55:35,171 attempt 1/10 2017-01-05 18:55:35,171 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a14094edbad7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-05 18:55:37,086 unpacking tar archive at: mozilla-central-a14094edbad7/testing/mozharness/ program finished with exit code 0 elapsedTime=2.204343 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-05 18:55:37.302135) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 18:55:37.302479) ========= script_repo_revision: a14094edbad78fc1d16e8d4c57902537cf286fd1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 18:55:37.302865) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 18:55:37.303143) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 18:55:37.322873) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-05 18:55:37.323218) ========= /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.9v8IGTuwlI/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.mmCg8MUUIE/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 18:55:37 INFO - MultiFileLogger online at 20170105 18:55:37 in /builds/slave/test 18:55:37 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 18:55:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:55:37 INFO - {'append_to_log': False, 18:55:37 INFO - 'base_work_dir': '/builds/slave/test', 18:55:37 INFO - 'blob_upload_branch': 'Firefox', 18:55:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:55:37 INFO - 'branch': 'Firefox', 18:55:37 INFO - 'buildbot_json_path': 'buildprops.json', 18:55:37 INFO - 'config_files': ('talos/mac_config.py',), 18:55:37 INFO - 'default_actions': ('clobber', 18:55:37 INFO - 'read-buildbot-config', 18:55:37 INFO - 'download-and-extract', 18:55:37 INFO - 'populate-webroot', 18:55:37 INFO - 'create-virtualenv', 18:55:37 INFO - 'install', 18:55:37 INFO - 'run-tests'), 18:55:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:55:37 INFO - 'download_minidump_stackwalk': True, 18:55:37 INFO - 'download_symbols': 'ondemand', 18:55:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:55:37 INFO - 'tooltool.py': '/tools/tooltool.py', 18:55:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:55:37 INFO - '/tools/misc-python/virtualenv.py')}, 18:55:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:55:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:55:37 INFO - 'installer_path': 'installer.exe', 18:55:37 INFO - 'log_level': 'info', 18:55:37 INFO - 'log_name': 'talos', 18:55:37 INFO - 'log_to_console': True, 18:55:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:55:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:55:37 INFO - 'opt_config_files': (), 18:55:37 INFO - 'pip_index': False, 18:55:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:55:37 INFO - 'cmd': ('bash', 18:55:37 INFO - '-c', 18:55:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:55:37 INFO - 'enabled': True, 18:55:37 INFO - 'halt_on_failure': False, 18:55:37 INFO - 'name': 'check_screen_resolution'},), 18:55:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:55:37 INFO - 'cmd': ('bash', 18:55:37 INFO - '-c', 18:55:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:55:37 INFO - 'enabled': True, 18:55:37 INFO - 'halt_on_failure': False, 18:55:37 INFO - 'name': 'check_screen_resolution'},), 18:55:37 INFO - 'run_cmd_checks_enabled': True, 18:55:37 INFO - 'sps_profile': False, 18:55:37 INFO - 'sps_profile_interval': 0, 18:55:37 INFO - 'suite': 'dromaeojs', 18:55:37 INFO - 'system_bits': '32', 18:55:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:55:37 INFO - 'title': 't-yosemite-r7-0300', 18:55:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:55:37 INFO - 'use_talos_json': True, 18:55:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:55:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:55:37 INFO - 'work_dir': 'build'} 18:55:37 INFO - [mozharness: 2017-01-06 02:55:37.523333Z] Running clobber step. 18:55:37 INFO - Running pre-action listener: _resource_record_pre_action 18:55:37 INFO - Running main action method: clobber 18:55:37 INFO - rmtree: /builds/slave/test/build 18:55:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:55:39 INFO - Running post-action listener: _resource_record_post_action 18:55:39 INFO - [mozharness: 2017-01-06 02:55:39.974340Z] Finished clobber step (success) 18:55:39 INFO - [mozharness: 2017-01-06 02:55:39.974581Z] Running read-buildbot-config step. 18:55:39 INFO - Running pre-action listener: _resource_record_pre_action 18:55:39 INFO - Running main action method: read_buildbot_config 18:55:39 INFO - Using buildbot properties: 18:55:39 INFO - { 18:55:39 INFO - "project": "", 18:55:39 INFO - "product": "firefox", 18:55:39 INFO - "script_repo_revision": "production", 18:55:39 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:55:39 INFO - "repository": "", 18:55:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:55:39 INFO - "buildid": "20170105173113", 18:55:39 INFO - "pgo_build": "False", 18:55:39 INFO - "basedir": "/builds/slave/test", 18:55:39 INFO - "buildnumber": 22, 18:55:39 INFO - "slavename": "t-yosemite-r7-0300", 18:55:39 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:55:39 INFO - "platform": "yosemite_r7", 18:55:39 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:55:39 INFO - "branch": "mozilla-central", 18:55:39 INFO - "repo_path": "mozilla-central", 18:55:39 INFO - "revision": "a14094edbad78fc1d16e8d4c57902537cf286fd1", 18:55:39 INFO - "stage_platform": "macosx64", 18:55:39 INFO - "builduid": "3d84b506e6b74eddb8e07ddb3122cccb", 18:55:39 INFO - "slavebuilddir": "test" 18:55:39 INFO - } 18:55:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 18:55:39 INFO - Running post-action listener: _resource_record_post_action 18:55:39 INFO - [mozharness: 2017-01-06 02:55:39.980505Z] Finished read-buildbot-config step (success) 18:55:39 INFO - [mozharness: 2017-01-06 02:55:39.980662Z] Running download-and-extract step. 18:55:39 INFO - Running pre-action listener: _resource_record_pre_action 18:55:39 INFO - Running main action method: download_and_extract 18:55:39 INFO - mkdir: /builds/slave/test/build/tests 18:55:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:39 INFO - trying https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 18:55:39 INFO - Downloading https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/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 18:55:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/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 18:55:41 INFO - Downloaded 1199 bytes. 18:55:41 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 18:55:41 INFO - Using the following test package requirements: 18:55:41 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 18:55:41 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:55:41 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 18:55:41 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:55:41 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 18:55:41 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:55:41 INFO - u'jsshell-mac.zip'], 18:55:41 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:55:41 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 18:55:41 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 18:55:41 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:55:41 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 18:55:41 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:55:41 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 18:55:41 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:55:41 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 18:55:41 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 18:55:41 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 18:55:41 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:55:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 18:55:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:55:41 INFO - Fetch https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 18:55:42 INFO - Expected file size: 16300148 18:55:42 INFO - Obtained file size: 16300148 18:55:48 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 18:55:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 18:55:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:55:48 INFO - Fetch https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 18:55:49 INFO - Expected file size: 16300148 18:55:49 INFO - Obtained file size: 16300148 18:55:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 18:55:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:55:56 INFO - Fetch https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 18:55:57 INFO - Expected file size: 14403730 18:55:57 INFO - Obtained file size: 14403730 18:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:55:57 INFO - trying https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 18:55:57 INFO - Downloading https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 18:55:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/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 18:55:59 INFO - Downloaded 69713567 bytes. 18:55:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 18:55:59 INFO - mkdir: /builds/slave/test/properties 18:55:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:55:59 INFO - Writing to file /builds/slave/test/properties/build_url 18:55:59 INFO - Contents: 18:55:59 INFO - build_url:https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 18:56:00 INFO - Running post-action listener: _resource_record_post_action 18:56:00 INFO - Running post-action listener: set_extra_try_arguments 18:56:00 INFO - [mozharness: 2017-01-06 02:56:00.148267Z] Finished download-and-extract step (success) 18:56:00 INFO - [mozharness: 2017-01-06 02:56:00.148410Z] Running populate-webroot step. 18:56:00 INFO - Running pre-action listener: _resource_record_pre_action 18:56:00 INFO - Running main action method: populate_webroot 18:56:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:56:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:56:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:56:00 INFO - '--tppagecycles', 18:56:00 INFO - '7'], 18:56:00 INFO - 'tests': ['tsvgm']}}, 18:56:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:56:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:56:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:56:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:56:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:56:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:56:00 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:56:00 INFO - 'talos_options': ['--disable-e10s'], 18:56:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:56:00 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:56:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:56:00 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:56:00 INFO - 'talos_options': ['--disable-e10s'], 18:56:00 INFO - 'tests': ['damp', 'tps']}, 18:56:00 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:56:00 INFO - 'tests': ['damp', 'tps']}, 18:56:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:56:00 INFO - 'tests': ['dromaeo_dom']}, 18:56:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:56:00 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:56:00 INFO - 'tests': ['basic_compositor_video']}, 18:56:00 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:56:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:56:00 INFO - 'tests': ['a11yr', 18:56:00 INFO - 'ts_paint', 18:56:00 INFO - 'tpaint', 18:56:00 INFO - 'sessionrestore', 18:56:00 INFO - 'sessionrestore_no_auto_restore', 18:56:00 INFO - 'tabpaint']}, 18:56:00 INFO - 'other-e10s': {'tests': ['a11yr', 18:56:00 INFO - 'ts_paint', 18:56:00 INFO - 'tpaint', 18:56:00 INFO - 'sessionrestore', 18:56:00 INFO - 'sessionrestore_no_auto_restore', 18:56:00 INFO - 'tabpaint']}, 18:56:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:56:00 INFO - 'ts_paint', 18:56:00 INFO - 'tpaint', 18:56:00 INFO - 'sessionrestore', 18:56:00 INFO - 'sessionrestore_no_auto_restore', 18:56:00 INFO - 'tabpaint']}, 18:56:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:56:00 INFO - 'ts_paint', 18:56:00 INFO - 'tpaint', 18:56:00 INFO - 'sessionrestore', 18:56:00 INFO - 'sessionrestore_no_auto_restore', 18:56:00 INFO - 'tabpaint']}, 18:56:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:56:00 INFO - 'tests': ['a11yr', 18:56:00 INFO - 'ts_paint', 18:56:00 INFO - 'tpaint', 18:56:00 INFO - 'sessionrestore', 18:56:00 INFO - 'sessionrestore_no_auto_restore', 18:56:00 INFO - 'tabpaint']}, 18:56:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:56:00 INFO - 'tests': ['a11yr', 18:56:00 INFO - 'ts_paint', 18:56:00 INFO - 'tpaint', 18:56:00 INFO - 'sessionrestore', 18:56:00 INFO - 'sessionrestore_no_auto_restore', 18:56:00 INFO - 'tabpaint']}, 18:56:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:56:00 INFO - 'tests': ['tsvgx', 18:56:00 INFO - 'tsvgr_opacity', 18:56:00 INFO - 'tart', 18:56:00 INFO - 'tscrollx', 18:56:00 INFO - 'cart', 18:56:00 INFO - 'tsvg_static']}, 18:56:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:56:00 INFO - 'tsvgr_opacity', 18:56:00 INFO - 'tart', 18:56:00 INFO - 'tscrollx', 18:56:00 INFO - 'cart', 18:56:00 INFO - 'tsvg_static']}, 18:56:00 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:56:00 INFO - 'talos_options': ['--disable-e10s'], 18:56:00 INFO - 'tests': ['tp5o']}, 18:56:00 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:56:00 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:56:00 INFO - 'talos_options': ['--disable-e10s', 18:56:00 INFO - '--xperf_path', 18:56:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:56:00 INFO - 'tests': ['tp5n']}, 18:56:00 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:56:00 INFO - 'talos_options': ['--xperf_path', 18:56:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:56:00 INFO - 'tests': ['tp5n']}}, 18:56:00 INFO - 'talos.zip': {'path': '', 18:56:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:56:00 INFO - Running post-action listener: _resource_record_post_action 18:56:00 INFO - [mozharness: 2017-01-06 02:56:00.167417Z] Finished populate-webroot step (success) 18:56:00 INFO - [mozharness: 2017-01-06 02:56:00.167551Z] Running create-virtualenv step. 18:56:00 INFO - Running pre-action listener: _resource_record_pre_action 18:56:00 INFO - Running main action method: create_virtualenv 18:56:00 INFO - Creating virtualenv /builds/slave/test/build/venv 18:56:00 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 18:56:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:56:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:56:00 INFO - Using real prefix '/tools/python27' 18:56:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:56:01 INFO - Installing distribute.............................................................................................................................................................................................done. 18:56:04 INFO - Installing pip.................done. 18:56:04 INFO - Return code: 0 18:56:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:56:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:56:04 INFO - Reading from file tmpfile_stdout 18:56:04 INFO - Output received: 18:56:04 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:56:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f0ad822d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10522fd30>, '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 0x105156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105224be8>, '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.9v8IGTuwlI/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.mmCg8MUUIE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 18:56:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:56:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:56:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9v8IGTuwlI/Render', 18:56:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:04 INFO - 'HOME': '/Users/cltbld', 18:56:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:04 INFO - 'LOGNAME': 'cltbld', 18:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:04 INFO - 'MOZ_NO_REMOTE': '1', 18:56:04 INFO - 'NO_EM_RESTART': '1', 18:56:04 INFO - 'PAGER': '/bin/cat', 18:56:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:04 INFO - 'PWD': '/builds/slave/test', 18:56:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:04 INFO - 'SHELL': '/bin/bash', 18:56:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mmCg8MUUIE/Listeners', 18:56:04 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 18:56:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:04 INFO - 'USER': 'cltbld', 18:56:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:04 INFO - 'XPC_FLAGS': '0x0', 18:56:04 INFO - 'XPC_SERVICE_NAME': '0', 18:56:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:04 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 18:56:04 INFO - Cleaning up... 18:56:04 INFO - Return code: 0 18:56:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f0ad822d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10522fd30>, '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 0x105156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105224be8>, '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.9v8IGTuwlI/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.mmCg8MUUIE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 18:56:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:56:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:56:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9v8IGTuwlI/Render', 18:56:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:04 INFO - 'HOME': '/Users/cltbld', 18:56:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:04 INFO - 'LOGNAME': 'cltbld', 18:56:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:04 INFO - 'MOZ_NO_REMOTE': '1', 18:56:04 INFO - 'NO_EM_RESTART': '1', 18:56:04 INFO - 'PAGER': '/bin/cat', 18:56:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:04 INFO - 'PWD': '/builds/slave/test', 18:56:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:04 INFO - 'SHELL': '/bin/bash', 18:56:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mmCg8MUUIE/Listeners', 18:56:04 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 18:56:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:04 INFO - 'USER': 'cltbld', 18:56:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:04 INFO - 'XPC_FLAGS': '0x0', 18:56:04 INFO - 'XPC_SERVICE_NAME': '0', 18:56:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:04 INFO - Downloading/unpacking psutil>=3.1.1 18:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:56:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:56:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:56:08 INFO - Installing collected packages: psutil 18:56:08 INFO - Running setup.py install for psutil 18:56:08 INFO - building 'psutil._psutil_osx' extension 18:56:08 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 18:56:08 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 18:56:08 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 18:56:09 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 18:56:09 INFO - building 'psutil._psutil_posix' extension 18:56:09 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 18:56:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:56:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:56:09 INFO - ^ 18:56:09 INFO - 1 warning generated. 18:56:09 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 18:56:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:56:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:56:09 INFO - Successfully installed psutil 18:56:09 INFO - Cleaning up... 18:56:09 INFO - Return code: 0 18:56:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:56:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:09 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 0x105451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f0ad822d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10522fd30>, '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 0x105156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105224be8>, '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.9v8IGTuwlI/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.mmCg8MUUIE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 18:56:09 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 18:56:09 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 18:56:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9v8IGTuwlI/Render', 18:56:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:09 INFO - 'HOME': '/Users/cltbld', 18:56:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:09 INFO - 'LOGNAME': 'cltbld', 18:56:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:09 INFO - 'MOZ_NO_REMOTE': '1', 18:56:09 INFO - 'NO_EM_RESTART': '1', 18:56:09 INFO - 'PAGER': '/bin/cat', 18:56:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:09 INFO - 'PWD': '/builds/slave/test', 18:56:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:09 INFO - 'SHELL': '/bin/bash', 18:56:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mmCg8MUUIE/Listeners', 18:56:09 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 18:56:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:09 INFO - 'USER': 'cltbld', 18:56:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:09 INFO - 'XPC_FLAGS': '0x0', 18:56:09 INFO - 'XPC_SERVICE_NAME': '0', 18:56:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:56:09 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 18:56:09 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 18:56:09 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 18:56:09 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 18:56:09 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 18:56:09 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 18:56:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:56:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:56:12 INFO - Installing collected packages: mozsystemmonitor 18:56:12 INFO - Running setup.py install for mozsystemmonitor 18:56:12 INFO - Successfully installed mozsystemmonitor 18:56:12 INFO - Cleaning up... 18:56:12 INFO - Return code: 0 18:56:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:56:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:56:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f0ad822d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10522fd30>, '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 0x105156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105224be8>, '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.9v8IGTuwlI/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.mmCg8MUUIE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 18:56:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:56:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:56:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9v8IGTuwlI/Render', 18:56:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:12 INFO - 'HOME': '/Users/cltbld', 18:56:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:12 INFO - 'LOGNAME': 'cltbld', 18:56:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:12 INFO - 'MOZ_NO_REMOTE': '1', 18:56:12 INFO - 'NO_EM_RESTART': '1', 18:56:12 INFO - 'PAGER': '/bin/cat', 18:56:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:12 INFO - 'PWD': '/builds/slave/test', 18:56:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:12 INFO - 'SHELL': '/bin/bash', 18:56:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mmCg8MUUIE/Listeners', 18:56:12 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 18:56:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:12 INFO - 'USER': 'cltbld', 18:56:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:12 INFO - 'XPC_FLAGS': '0x0', 18:56:12 INFO - 'XPC_SERVICE_NAME': '0', 18:56:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:12 INFO - Downloading/unpacking jsonschema==2.5.1 18:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:56:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:15 INFO - Downloading functools32-3.2.3-2.tar.gz 18:56:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:56:16 INFO - warning: no files found matching '*.txt' 18:56:16 INFO - no previously-included directories found matching 'build' 18:56:16 INFO - no previously-included directories found matching 'dist' 18:56:16 INFO - no previously-included directories found matching '.git*' 18:56:16 INFO - Installing collected packages: jsonschema, functools32 18:56:16 INFO - Running setup.py install for functools32 18:56:16 INFO - warning: no files found matching '*.txt' 18:56:16 INFO - no previously-included directories found matching 'build' 18:56:16 INFO - no previously-included directories found matching 'dist' 18:56:16 INFO - no previously-included directories found matching '.git*' 18:56:16 INFO - Successfully installed jsonschema functools32 18:56:16 INFO - Cleaning up... 18:56:16 INFO - Return code: 0 18:56:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:56:16 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')]} 18:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:16 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')]} 18:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:16 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 0x105451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f0ad822d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10522fd30>, '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 0x105156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105224be8>, '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.9v8IGTuwlI/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.mmCg8MUUIE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 18:56:16 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 18:56:16 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 18:56:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9v8IGTuwlI/Render', 18:56:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:16 INFO - 'HOME': '/Users/cltbld', 18:56:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:16 INFO - 'LOGNAME': 'cltbld', 18:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:16 INFO - 'MOZ_NO_REMOTE': '1', 18:56:16 INFO - 'NO_EM_RESTART': '1', 18:56:16 INFO - 'PAGER': '/bin/cat', 18:56:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:16 INFO - 'PWD': '/builds/slave/test', 18:56:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:16 INFO - 'SHELL': '/bin/bash', 18:56:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mmCg8MUUIE/Listeners', 18:56:16 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 18:56:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:16 INFO - 'USER': 'cltbld', 18:56:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:16 INFO - 'XPC_FLAGS': '0x0', 18:56:16 INFO - 'XPC_SERVICE_NAME': '0', 18:56:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:56:16 INFO - Cleaning up... 18:56:16 INFO - Return code: 0 18:56:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:56:16 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')]} 18:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:16 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')]} 18:56:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:16 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 0x105451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f0ad822d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10522fd30>, '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 0x105156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105224be8>, '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.9v8IGTuwlI/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.mmCg8MUUIE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 18:56:16 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 18:56:16 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 18:56:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9v8IGTuwlI/Render', 18:56:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:16 INFO - 'HOME': '/Users/cltbld', 18:56:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:16 INFO - 'LOGNAME': 'cltbld', 18:56:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:16 INFO - 'MOZ_NO_REMOTE': '1', 18:56:16 INFO - 'NO_EM_RESTART': '1', 18:56:16 INFO - 'PAGER': '/bin/cat', 18:56:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:16 INFO - 'PWD': '/builds/slave/test', 18:56:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:16 INFO - 'SHELL': '/bin/bash', 18:56:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mmCg8MUUIE/Listeners', 18:56:16 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 18:56:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:16 INFO - 'USER': 'cltbld', 18:56:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:16 INFO - 'XPC_FLAGS': '0x0', 18:56:16 INFO - 'XPC_SERVICE_NAME': '0', 18:56:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:16 INFO - Downloading/unpacking blobuploader==1.2.4 18:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:19 INFO - Downloading blobuploader-1.2.4.tar.gz 18:56:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:56:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:56:19 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 18:56:19 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 18:56:19 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 18:56:19 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 18:56:19 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 18:56:19 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 18:56:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:56:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:20 INFO - Downloading docopt-0.6.1.tar.gz 18:56:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:56:20 INFO - Installing collected packages: blobuploader, requests, docopt 18:56:20 INFO - Running setup.py install for blobuploader 18:56:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:56:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:56:21 INFO - Running setup.py install for requests 18:56:21 INFO - Running setup.py install for docopt 18:56:21 INFO - Successfully installed blobuploader requests docopt 18:56:21 INFO - Cleaning up... 18:56:21 INFO - Return code: 0 18:56:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:21 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')]} 18:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:21 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')]} 18:56:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:21 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 0x105451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f0ad822d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10522fd30>, '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 0x105156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105224be8>, '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.9v8IGTuwlI/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.mmCg8MUUIE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 18:56:21 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 18:56:21 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 18:56:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9v8IGTuwlI/Render', 18:56:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:21 INFO - 'HOME': '/Users/cltbld', 18:56:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:21 INFO - 'LOGNAME': 'cltbld', 18:56:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:21 INFO - 'MOZ_NO_REMOTE': '1', 18:56:21 INFO - 'NO_EM_RESTART': '1', 18:56:21 INFO - 'PAGER': '/bin/cat', 18:56:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:21 INFO - 'PWD': '/builds/slave/test', 18:56:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:21 INFO - 'SHELL': '/bin/bash', 18:56:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mmCg8MUUIE/Listeners', 18:56:21 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 18:56:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:21 INFO - 'USER': 'cltbld', 18:56:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:21 INFO - 'XPC_FLAGS': '0x0', 18:56:21 INFO - 'XPC_SERVICE_NAME': '0', 18:56:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:22 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-c6w3Xf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:22 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-YstbqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:22 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-_ZuOyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:22 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-njkMyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:22 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-ZnOZEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:22 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-9f99TV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:22 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-nX23iI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:22 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-WflUkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:22 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-utwk99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:22 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-ydu3Cv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:23 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-203UoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:23 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-wN6rO4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:23 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-sUA6Qv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:23 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-qkM0eI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:23 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-10ALAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:23 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-dkfPGL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:23 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-HSkPn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:56:23 INFO - Running setup.py install for manifestparser 18:56:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:56:23 INFO - Running setup.py install for mozcrash 18:56:24 INFO - Running setup.py install for mozdebug 18:56:24 INFO - Running setup.py install for mozdevice 18:56:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:56:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:56:24 INFO - Running setup.py install for mozfile 18:56:24 INFO - Running setup.py install for mozhttpd 18:56:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:56:24 INFO - Running setup.py install for mozinfo 18:56:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:56:24 INFO - Running setup.py install for mozInstall 18:56:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:56:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:56:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:56:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:56:24 INFO - Running setup.py install for mozleak 18:56:25 INFO - Running setup.py install for mozlog 18:56:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:56:25 INFO - Running setup.py install for moznetwork 18:56:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:56:25 INFO - Running setup.py install for mozprocess 18:56:25 INFO - Running setup.py install for mozprofile 18:56:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:56:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:56:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:56:25 INFO - Running setup.py install for mozrunner 18:56:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:56:25 INFO - Running setup.py install for mozscreenshot 18:56:26 INFO - Running setup.py install for moztest 18:56:26 INFO - Running setup.py install for mozversion 18:56:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:56:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:56:26 INFO - Cleaning up... 18:56:26 INFO - Return code: 0 18:56:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:26 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')]} 18:56:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:26 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')]} 18:56:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:26 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 0x105451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f0ad822d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10522fd30>, '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 0x105156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105224be8>, '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.9v8IGTuwlI/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.mmCg8MUUIE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 18:56:26 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 18:56:26 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 18:56:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9v8IGTuwlI/Render', 18:56:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:26 INFO - 'HOME': '/Users/cltbld', 18:56:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:26 INFO - 'LOGNAME': 'cltbld', 18:56:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:26 INFO - 'MOZ_NO_REMOTE': '1', 18:56:26 INFO - 'NO_EM_RESTART': '1', 18:56:26 INFO - 'PAGER': '/bin/cat', 18:56:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:26 INFO - 'PWD': '/builds/slave/test', 18:56:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:26 INFO - 'SHELL': '/bin/bash', 18:56:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mmCg8MUUIE/Listeners', 18:56:26 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 18:56:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:26 INFO - 'USER': 'cltbld', 18:56:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:26 INFO - 'XPC_FLAGS': '0x0', 18:56:26 INFO - 'XPC_SERVICE_NAME': '0', 18:56:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:56:26 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-MgQtfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:56:26 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)) 18:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:56:26 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-nVOktX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:56:26 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)) 18:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:56:26 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-_AIRUT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:56:26 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)) 18:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:56:26 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-re3hUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:56:27 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)) 18:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:56:27 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-e0Ap98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:56:27 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)) 18:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:56:27 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-g7O4Vm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:56:27 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)) 18:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:56:27 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-cn_pdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:56:27 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)) 18:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:56:27 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-ZGAdmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:56:27 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)) 18:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:56:27 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-NNSaZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:56:27 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)) 18:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:56:27 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-WwxkHi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:56:27 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)) 18:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:56:27 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-FAXbXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:56:27 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)) 18:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:56:27 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-PRSgZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:56:27 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)) 18:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:56:27 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-nTaa_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:56:28 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)) 18:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:56:28 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-QJnfIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:56:28 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)) 18:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:28 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-MwQXil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:56:28 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)) 18:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:56:28 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-JUZOi9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:56:28 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)) 18:56:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:56:28 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-v5iLPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:56:28 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)) 18:56:28 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)) 18:56:28 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)) 18:56:28 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)) 18:56:28 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)) 18:56:28 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)) 18:56:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:56:31 INFO - Downloading blessings-1.6.tar.gz 18:56:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:56:31 INFO - Installing collected packages: blessings 18:56:31 INFO - Running setup.py install for blessings 18:56:31 INFO - Successfully installed blessings 18:56:31 INFO - Cleaning up... 18:56:31 INFO - Return code: 0 18:56:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:56:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:56:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:56:31 INFO - Reading from file tmpfile_stdout 18:56:31 INFO - Current package versions: 18:56:31 INFO - blessings == 1.6 18:56:31 INFO - blobuploader == 1.2.4 18:56:31 INFO - docopt == 0.6.1 18:56:31 INFO - functools32 == 3.2.3-2 18:56:31 INFO - jsonschema == 2.5.1 18:56:31 INFO - manifestparser == 1.1 18:56:31 INFO - mozInstall == 1.12 18:56:31 INFO - mozcrash == 1.0 18:56:31 INFO - mozdebug == 0.1 18:56:31 INFO - mozdevice == 0.49 18:56:31 INFO - mozfile == 1.2 18:56:31 INFO - mozhttpd == 0.7 18:56:31 INFO - mozinfo == 0.9 18:56:31 INFO - mozleak == 0.1 18:56:31 INFO - mozlog == 3.4 18:56:31 INFO - moznetwork == 0.27 18:56:31 INFO - mozprocess == 0.23 18:56:31 INFO - mozprofile == 0.28 18:56:31 INFO - mozrunner == 6.13 18:56:31 INFO - mozscreenshot == 0.1 18:56:31 INFO - mozsystemmonitor == 0.3 18:56:31 INFO - moztest == 0.8 18:56:31 INFO - mozversion == 1.4 18:56:31 INFO - psutil == 3.1.1 18:56:31 INFO - requests == 1.2.3 18:56:31 INFO - wsgiref == 0.1.2 18:56:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:56:31 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')]} 18:56:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:32 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')]} 18:56:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:32 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 0x105451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f0ad822d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10522fd30>, '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 0x105156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105224be8>, '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.9v8IGTuwlI/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.mmCg8MUUIE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 18:56:32 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 18:56:32 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 18:56:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9v8IGTuwlI/Render', 18:56:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:32 INFO - 'HOME': '/Users/cltbld', 18:56:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:32 INFO - 'LOGNAME': 'cltbld', 18:56:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:32 INFO - 'MOZ_NO_REMOTE': '1', 18:56:32 INFO - 'NO_EM_RESTART': '1', 18:56:32 INFO - 'PAGER': '/bin/cat', 18:56:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:32 INFO - 'PWD': '/builds/slave/test', 18:56:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:32 INFO - 'SHELL': '/bin/bash', 18:56:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mmCg8MUUIE/Listeners', 18:56:32 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 18:56:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:32 INFO - 'USER': 'cltbld', 18:56:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:32 INFO - 'XPC_FLAGS': '0x0', 18:56:32 INFO - 'XPC_SERVICE_NAME': '0', 18:56:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:32 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)) 18:56:32 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)) 18:56:32 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)) 18:56:32 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)) 18:56:32 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)) 18:56:32 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)) 18:56:32 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)) 18:56:32 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)) 18:56:32 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)) 18:56:32 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)) 18:56:32 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)) 18:56:32 INFO - Cleaning up... 18:56:32 INFO - Return code: 0 18:56:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:56:32 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')]} 18:56:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:56:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:32 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')]} 18:56:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:56:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:56:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f0ad822d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10522fd30>, '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 0x105156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105224be8>, '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.9v8IGTuwlI/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.mmCg8MUUIE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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 18:56:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:56:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:56:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9v8IGTuwlI/Render', 18:56:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:33 INFO - 'HOME': '/Users/cltbld', 18:56:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:33 INFO - 'LOGNAME': 'cltbld', 18:56:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:33 INFO - 'MOZ_NO_REMOTE': '1', 18:56:33 INFO - 'NO_EM_RESTART': '1', 18:56:33 INFO - 'PAGER': '/bin/cat', 18:56:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:33 INFO - 'PWD': '/builds/slave/test', 18:56:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:33 INFO - 'SHELL': '/bin/bash', 18:56:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mmCg8MUUIE/Listeners', 18:56:33 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 18:56:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:33 INFO - 'USER': 'cltbld', 18:56:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:33 INFO - 'XPC_FLAGS': '0x0', 18:56:33 INFO - 'XPC_SERVICE_NAME': '0', 18:56:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:56:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:56:33 INFO - Cleaning up... 18:56:33 INFO - Return code: 0 18:56:33 INFO - Running post-action listener: _resource_record_post_action 18:56:33 INFO - Running post-action listener: _start_resource_monitoring 18:56:33 INFO - Starting resource monitoring. 18:56:33 INFO - [mozharness: 2017-01-06 02:56:33.431541Z] Finished create-virtualenv step (success) 18:56:33 INFO - [mozharness: 2017-01-06 02:56:33.432496Z] Running install step. 18:56:33 INFO - Running pre-action listener: _resource_record_pre_action 18:56:33 INFO - Running main action method: install 18:56:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:56:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:56:33 INFO - Reading from file tmpfile_stdout 18:56:33 INFO - Detecting whether we're running mozinstall >=1.0... 18:56:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:56:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:56:33 INFO - Reading from file tmpfile_stdout 18:56:33 INFO - Output received: 18:56:33 INFO - Usage: mozinstall [options] installer 18:56:33 INFO - Options: 18:56:33 INFO - -h, --help show this help message and exit 18:56:33 INFO - -d DEST, --destination=DEST 18:56:33 INFO - Directory to install application into. [default: 18:56:33 INFO - "/builds/slave/test"] 18:56:33 INFO - --app=APP Application being installed. [default: firefox] 18:56:33 INFO - mkdir: /builds/slave/test/build/application 18:56:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:56:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:56:55 INFO - Reading from file tmpfile_stdout 18:56:55 INFO - Output received: 18:56:55 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:56:55 INFO - Running post-action listener: _resource_record_post_action 18:56:55 INFO - [mozharness: 2017-01-06 02:56:55.469065Z] Finished install step (success) 18:56:55 INFO - [mozharness: 2017-01-06 02:56:55.469198Z] Running run-tests step. 18:56:55 INFO - Running pre-action listener: _resource_record_pre_action 18:56:55 INFO - Running main action method: run_tests 18:56:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:56:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:56:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:56:55 INFO - 2017-01-05 18:56:55.483 screenresolution[1628:7512] starting screenresolution argv=screenresolution get 18:56:55 INFO - 2017-01-05 18:56:55.486 screenresolution[1628:7512] Display 0: 1600x1200x32@60 18:56:55 INFO - 2017-01-05 18:56:55.491 screenresolution[1629:7514] starting screenresolution argv=screenresolution list 18:56:55 INFO - Available Modes on Display 0 18:56:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:56:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:56:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:56:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:56:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:56:55 INFO - Intel Iris: 18:56:55 INFO - Chipset Model: Intel Iris 18:56:55 INFO - Type: GPU 18:56:55 INFO - Bus: Built-In 18:56:55 INFO - VRAM (Dynamic, Max): 1536 MB 18:56:55 INFO - Vendor: Intel (0x8086) 18:56:55 INFO - Device ID: 0x0a2e 18:56:55 INFO - Revision ID: 0x0009 18:56:55 INFO - Displays: 18:56:55 INFO - Display: 18:56:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:56:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:56:55 INFO - Display Serial Number: 1600x1200 60 18:56:55 INFO - Main Display: Yes 18:56:55 INFO - Mirror: Off 18:56:55 INFO - Online: Yes 18:56:55 INFO - Rotation: Supported 18:56:55 INFO - Return code: 0 18:56:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:56:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:56:55 INFO - Python 2.7.3 18:56:55 INFO - Return code: 0 18:56:55 INFO - grabbing minidump binary from tooltool 18:56: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')]} 18:56:55 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10522fd30>, '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 0x105156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105224be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:56:55 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:56:55 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:56:55 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:56:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:56:55 INFO - Return code: 0 18:56:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:56:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:56:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:56:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:56:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:56:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0300', '--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 18:56:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0300 --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 18:56:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9v8IGTuwlI/Render', 18:56:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:56:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:56:55 INFO - 'HOME': '/Users/cltbld', 18:56:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:56:55 INFO - 'LOGNAME': 'cltbld', 18:56:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:56:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:56:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:56:55 INFO - 'MOZ_NO_REMOTE': '1', 18:56:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:56:55 INFO - 'NO_EM_RESTART': '1', 18:56:55 INFO - 'PAGER': '/bin/cat', 18:56:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:56:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:56:55 INFO - 'PWD': '/builds/slave/test', 18:56:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:56:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:56:55 INFO - 'SHELL': '/bin/bash', 18:56:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mmCg8MUUIE/Listeners', 18:56:55 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 18:56:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:56:55 INFO - 'USER': 'cltbld', 18:56:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:56:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:56:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:56:55 INFO - 'XPC_FLAGS': '0x0', 18:56:55 INFO - 'XPC_SERVICE_NAME': '0', 18:56:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:56:55 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0300', '--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 18:56:56 INFO - mozversion application_buildid: 20170105173113 18:56:56 INFO - mozversion application_changeset: a14094edbad78fc1d16e8d4c57902537cf286fd1 18:56:56 INFO - mozversion application_display_name: Nightly 18:56:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:56:56 INFO - mozversion application_name: Firefox 18:56:56 INFO - mozversion application_remotingname: firefox 18:56:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:56:56 INFO - mozversion application_vendor: Mozilla 18:56:56 INFO - mozversion application_version: 53.0a1 18:56:56 INFO - mozversion platform_buildid: 20170105173113 18:56:56 INFO - mozversion platform_changeset: a14094edbad78fc1d16e8d4c57902537cf286fd1 18:56:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:56:56 INFO - mozversion platform_version: 53.0a1 18:56:56 INFO - using testdate: 1483671416 18:56:56 INFO - actual date: 1483671416 18:56:56 INFO - starting webserver on 'localhost:49242' 18:56:56 INFO - SUITE-START | Running 2 tests 18:56:56 INFO - TEST-START | dromaeo_css 18:56:56 INFO - operating with platform_type : mac_ 18:56:56 INFO - Initialising browser for dromaeo_css test... 18:56:56 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpHmQbJA/profile http://localhost:49242/getInfo.html) 18:56:56 INFO - PROCESS | 1639 | 1483671416613 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:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 18:56:58 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 18:56:58 INFO - PROCESS | 1639 | colorDepth:24 18:56:58 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 18:56:58 INFO - PROCESS | 1639 | __metrics 18:56:58 INFO - PROCESS | 1639 | 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" 18:56:58 INFO - PROCESS | 1639 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:56:59 INFO - TEST-INFO | 1639: exit 0 18:56:59 INFO - Browser initialized. 18:56:59 INFO - Running cycle 1/1 for dromaeo_css test... 18:56:59 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpHmQbJA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:57:11 INFO - PROCESS | 1641 | RSS: Main: 225927168 18:57:11 INFO - PROCESS | 1641 | 19:00:16 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 19:00:18 INFO - PROCESS | 1641 | RSS: Main: 382537728 19:00:18 INFO - PROCESS | 1641 | 19:02:00 INFO - PROCESS | 1641 | ************************* 19:02:00 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 19:02:00 INFO - PROCESS | 1641 | 19:02:00 INFO - PROCESS | 1641 | Full message: ReferenceError: fetch is not defined 19:02:00 INFO - PROCESS | 1641 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 19:02:00 INFO - PROCESS | 1641 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 19:02:00 INFO - PROCESS | 1641 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 19:02:00 INFO - PROCESS | 1641 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:02:00 INFO - PROCESS | 1641 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:02:00 INFO - PROCESS | 1641 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:02:00 INFO - PROCESS | 1641 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 19:02:00 INFO - PROCESS | 1641 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 19:02:00 INFO - PROCESS | 1641 | TaskImpl@resource://gre/modules/Task.jsm:277:3 19:02:00 INFO - PROCESS | 1641 | asyncFunction@resource://gre/modules/Task.jsm:252:14 19:02:00 INFO - PROCESS | 1641 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 19:02:00 INFO - PROCESS | 1641 | 19:02:00 INFO - PROCESS | 1641 | ************************* 19:03:20 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 19:03:21 INFO - PROCESS | 1641 | RSS: Main: 367833088 19:03:21 INFO - PROCESS | 1641 | 19:06:23 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 19:06:24 INFO - PROCESS | 1641 | RSS: Main: 331825152 19:06:24 INFO - PROCESS | 1641 | 19:09:26 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 19:09:27 INFO - PROCESS | 1641 | RSS: Main: 350478336 19:09:27 INFO - PROCESS | 1641 | 19:12:31 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 19:12:31 INFO - PROCESS | 1641 | RSS: Main: 329916416 19:12:31 INFO - PROCESS | 1641 | 19:15:35 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 19:15:35 INFO - PROCESS | 1641 | RSS: Main: 388796416 19:15:35 INFO - PROCESS | 1641 | 19:15:35 INFO - PROCESS | 1641 | __start_tp_report 19:15:35 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 19:15:35 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 19:15:35 INFO - PROCESS | 1641 | |i|pagename|runs| 19:15:35 INFO - PROCESS | 1641 | |0;dojo.html;3295.373878962997;3324.5511855899445;3351.1119553318367;3351.597808263009;3404.7957122572648;5504.587155963309;5618.494335509803;5618.91571626426;5633.690147041916;5647.322321321447;5263.131583288753;5572.331320241567;5615.578831587637;5677.205191273225;5686.289213848269;5047.798088076473;5113.974430127844;5121.923171152435;5140.974295128538;5168.974155129219;5138.203578445328;5211.661242019257;5234.842954711346;5235.476452354753;5241.187615919539;5189.714565698885;5208.531232189102;5211.218317252424;5214.687118772886;5224.216367544861;5043.697378157286;5079;5100.132977393852;5156.484351564851;5156.690598564061;4999.32509111269;5093.414257360414;5099.566536844372;5107.080725469414;5113.718745468997;5533.418991005927;5622.887542249173;5631.662100273956;5688.288963879515;5693.117566777156;6070.150178974947;6085.178500902366;6098.481629061535;6105.969470152665;6115.510759139258;6035.215421995156;6093.69531523422;6101.2983506896835;6137.60105593135;6197.349278325756;5657.377688454267;5688.971555142198;5697.401772813878;5713.4000929902595;5775.537956963517;6151.507879369729;6204.348543402878;6235.875282494325;6243.625382477066;6243.8126856194385;4582.198115329804;5912.320083190488;6062.757489700451;6065.120557519223;6067.514598832083;6060.848478788065;6099.939000610026;6109.572329936862;6137.508999280047;6143.157052884207;6104.254103856739;6107.044717083349;6129.175413489998;6133.6582248142495;6147.508199344042;6127.4485296322655;6153.661548614779;6153.692315384213;6173.56785025044;6181.660008699475;4783.1868582341085;4811.591014763784;4833.936533962523;4842.394700662418;4850.708957462563;4275.978620106879;4336.111097225062;4336.392904993303;4351.673624478177;4403.691741578123;5611.438856114356;5660.660360378391;5664.915026274609;5674.659520428788;5676.063449530781;5929.762809487658;6143.815685529441;6164.260288765287;6175.320714721377;6205.6586887721605;4723.31511930775;5506.752196151183;5532.1425179097305;5628.774849005994;5647.040003199466;5310.070737620901;5321.733913304397;5348.625596208306;5361.195820626859;5384.488473595002;5230.241614965885;6267.0599410087025;6269.5611307208055;6271.404216599507;6293.96853015732;6066.332703402711;6073.392660733891;6107.67434349443;6126.693665316806;6184.6598437086395;6159.168512250789;6220.440160385406;6230.937690623217;6240.500759939103;6247.781327653511;5980.790672326448;6032.034874420072;6040.7583696651645;6072.514198864169;6086.452219300284;6133.05924499315;6138.786925362591;6144.709610981744;6195.876082478415;6199.876002480015;5786.103154011135;5804.390538993345;5846.649201047951;6024.378692324844;6044.24446944132;5911.527077833849;5961.672108033927;5975.551833612583;5977.372375900468;6043.667598281961;5786.9131963019745;5798.507126894261;5800.6809625471005;5802.94197058024;5808.128780682931;4524.63802895761;5810.82567522975;5936.940630593812;5981;5990.4908082813445;5681.744321505625;5707.942920570741;5729.570282228766;5731.770729170787;5740.799072032459 19:15:35 INFO - PROCESS | 1641 | |1;ext.html;443.7315424168377;450.4437020279952;495.1483448468634;502.61028256869764;524.5960610330046;30104;39645.4053189202;40930;41008.99999999996;41026.179476410456;42061.78969105158;42208.57791422085;42361.78819105908;42374;42474.57525424745;8396.454230475;9294.163525282724;9505.80988380232;9522.333436659403;9575.281853860954;8477.61850716716;9614.951925240364;11163.72090697735;11391.943040284787;11630.0114490268;11392.430378481044;11437.19939604228;11454.312741235512;11473.885261147407;11494;11347.978681918627;11420.54317827286;11432.314061156358;11437.142214333959;11447.942760286145;11247.493862776195;11314.490847911864;11322.94338528302;11333.773324533464;11347.886521134847;38928.80535597304;39266;39267.214655706724;39273.41089883654;39285.21429571392;3962.484876966005;3967.583403742623;4007.8797636070963;4018.6383225509553;4060.533038700558;29823.403531929456;30388.696113038808;30512.542311865338;30603.2349191272;30643.693563064306;3131.530270459449;3247.886323978673;3261.4944683574086;3273.541704161419;3326.085326535198;465393.34606653836;469022;470003.6499817479;470773;471415;57069.714651427304;58659.413405866246;58914.821703565685;58964;59123;62634;62658.0601290981;62682.68658656678;62768.175432105665;62769.68615156895;15276.083434993936;15639;15662.37350505967;15671.451499197474;15679.843201568066;10975.341479511255;11223.607173749044;11228.887711122947;12079.698007549881;12215.144939854124;16314.673706525802;16372.345106195618;16476.83523164753;16855.072970986712;17079.48761537156;5667.546596272288;6329.145565348711;6361.872762544724;6431.163948686641;6465.709043093072;748.584535582746;791.3194652598761;851.4891065360735;867.544539116956;872.437277955715;29086.418271634448;29205.561916571267;29252.561211581844;29581;29656.703432965827;1038.1227862456212;1080.827067669169;1083.2525557365393;1099;1153.103145325951;14690.779638305434;15194.392224311125;15431.99692020015;15530.767038494432;15609.765853512206;581416.0929195412;584396;584601;584683;585475.0726246426;57756;58082.7095864518;58099.12851307149;58121;58160.41839581635;1351.8310211223597;1392.7771556550904;1413.0956188039868;1413.6536548545466;1418.666613345863;25978.090766823072;27420.588691170047;27568.172954810587;28428.289292767848;29133.562996554643;49450.50549494459;49774;49889.50110498994;49983.25025124553;50067.49932500628;434656;435539;435599;435887;436453;24351.14770983007;25032.62451063272;25052.749472505042;39401.80299098486;39737.80131099326;41933.37099943564;42108;42209.57790422056;42579;43140.78429607832;35208;35513;35797.1050723734;36741;36768.448473273456;29839;35040.47439288464;35174.64825351714;36144.73493427718;36426.45360319544;34672.133196670286;34805.47791783074;35236.647633524364;35412.645873541966;35498.64501354953;29623.555646664885;31282.53076203904;31304.9043283484;31468.685313147493;31526.054218373487;20761.028954890022;20860.974727632412;23327.900488956075;23462.882685586465;23506.294811155694 19:15:35 INFO - PROCESS | 1641 | |2;jquery.html;329.2394568546657;366.6297039989613;387.41113507468646;395.8080331039445;417.80572034004183;15390.769138462918;15417.614559636015;17918;20934.057967391465;21484.355469335907;20299;20955.37133885986;21432.57134857302;22033.669494957587;22086;5349.545288650469;5483.424240454755;5519.337679478468;5593.188987596796;6020.969895150518;3913.3542965410675;5220;6013.037913933771;6123.387661233885;6125.479334256594;4111.217193071336;4116.444280022203;4131.917361652765;4144.626983571478;4144.97927510362;4106.8562600309;6754;6756.797296081077;6759.391654751072;6763.425108865752;5135.152699804527;5360.785568577252;5430.945690543083;6013.128096426038;6021.879562408771;13159.342032898317;13446.663833404244;13470.52853150135;16582.917085414618;25658.615120773204;2510.9874450627813;2513.635522849195;2550.2859199424192;2612.0857699804915;2612.373030472673;9423.48170850603;9588.712338629852;9699.078587534173;10110.34282771625;10240.94879525605;1801.4622000456322;1804.1520485371855;1809.1044932758368;1814.355903654197;1817;94500;129733.35133324367;136711.94932076026;137559;138968.30515847457;10683.83974240387;11200.327980321206;11523.481443334902;11536.884631153749;11571.710707232387;13952.441902323997;15487.922560387125;15515.612109697122;15541.766873496907;15552.222388880511;8275.793105172419;8527.40308178434;8533.232009119085;8646.135386461303;8667.609957551798;8227.8354432911;8239.958800206081;8247;8398.916010839936;8403.41176117666;4597.540245975376;4713.457952335457;4724.267738500538;4763.523647635208;4783.928241076386;1866.8263951282602;1937.0024437414743;1946.260421040024;2004.7494063242095;2006.5786184901042;846.3863698818308;1003.9698809035741;1007.3653598233067;1016.364772017489;1020.1175982774846;19398.224071037;20530.897345513178;20674.793252067586;20735.27426540064;20879.895600522203;943.4339396362265;983.2281658897731;1036.8185567525652;1049.5277125293649;1054.852320675106;10274.794504109876;10751.408672523086;10757.677269681923;10782.56869725218;10791.082757965503;591967;699996;707019;717892;729554;119796.40101799436;142440;149264.25367873092;159839.602405964;161375.19312403363;1042.74452759073;1061.3313612424124;1114.4093630375914;1115.252780636975;1116.1851848150895;18600.34898778536;18799.530011749815;19115.463639226786;19119.426417207505;19175.041247937268;55528.72235638796;55777;57586.7120664394;58940;59508.70245648917;81085.37829243264;81815;81870;81874.59062704648;82602;7199.388052015637;7349.55902645843;7517.985072015426;8134.145914678874;8565.785855353666;9317.534123293945;12425.62723118308;13144.802827957397;13504.324783760576;13578.25319607431;9637.614495420385;10584.36493810343;10971.83542246851;11068.5572577096;11609.071274298205;9268.443893366419;11132.109431245643;11307.491162897853;11546;11802;9848;11391.544338226378;11642.24325418845;11668;11815.586454474053;7474.439417043634;7636.694532218871;7646.847063058596;7731.458797884094;7764.961175194087;7442.367398770949;11112.611058612909;11167.385793781095;11656.825147622623;11742.236754610924 19:15:35 INFO - PROCESS | 1641 | |3;mootools.html;1438.6547228665124;1441.7921118481154;1443.7545617245069;1450.5793319937218;1456.3373664982435;8003.3997450191255;8400.3279737621;8411.91588084119;8413.663453461853;8415.284700800432;7417.777466675995;7640.770776876689;7995.800104997378;8230.176982301768;8238.505689658625;2273.4771002669395;2311.4221444638843;2314.837961342707;2315.548468048731;2320.6983092198075;2295.9426014349606;2321.315212012452;2332.0322066342446;2333.7782910623464;2334.2297041976194;2269.6595510673365;2278.2481781012225;2305.6310990241564;2313.144136669426;2314.3057082875152;2282.589133955887;2284.303287497313;2302.838801283911;2305.7002589663402;2316.0851463671925;2292.3810571145864;2299.72403311602;2325.3721495196373;2331.5453486570127;2335.4394945213025;8375.83248335036;8454.450460720034;8457.40798144124;8466.492010479391;8476.576171191477;5006.524380183877;5146.588272938156;5148.227765835118;5149.45930677281;5154.123798954187;3055.8624861881044;3261.608606967179;3397.590361445783;3534.6642069003406;3538.15084379747;1205.15036898986;1214.4110106598293;1235.7899157143218;1245.894099001586;1262.1480500662108;10483.056524912794;10694.893051069468;10715.46422678863;10735.355878647306;10747.623833165802;3943.0142464383903;4239.59723826236;4540.296254080623;4577.5880170784785;4618.053299073682;9349.579268932914;9358.906410935939;9359.859602105973;9384.5307734613;9392.530373481299;4426.89327668083;5122.257272695439;5153.3815942087185;5158.148905430562;5170.431252562215;4255.29787585045;4388.605025547716;4420.425344705232;4611.700239484489;4732.290156476556;3536.929261414757;3699.2231631357176;3726.5341832270965;3840.846366145379;3865.6520913117392;1138.1975707126455;1244.4151248912997;1246.9750604987848;1265.0638527489775;1265.316728966348;752.7144016368621;774.9380049596019;901.1889299630385;918.6187732091241;949.5489642419795;4603.803011217107;5245.816396426107;5278.9472105278455;5481.424450432647;5694.686792226467;554.8751530905595;576.5675743192605;581.1950448628595;582.912867502114;583.9036558967723;3075.077476756964;3600.9279814403567;3708.295423869456;3741.9251614968093;3757.1358587525024;9680.661176858775;9915.157211637092;10005.899941000496;10028.949855250823;10032.297739158188;9963.551640176209;9979.201663866874;9979.60081596728;10005.599776008878;10030.147437467751;7528.51064680794;7672.578008209491;7859.567723775133;7862.803429914298;7889.566073865993;5356.705381204071;5442.510174084351;5475.945240547703;5568.49883510486;5682.943170568242;7908.3673306136525;8984.820303594022;9022.097790220925;9143.542822858697;9240.95379523098;9103;9208.171264586219;9282.164605185566;9390.718278451657;9396.107369799858;6544.83637909037;6727.629980351127;6761.695723692347;7032.26161253061;7210.819729506804;7683;7943.84112317762;7963.16386779403;8052.396070294634;8055.476394034369;6126.5098792097415;6992.265812089658;7028.859422811617;7045.859082818418;7082.61045642474;6922.7923162305215;6943.756968506078;6984.615846128511;7010.789676309719;7014.508984371045;6215.471684906834;6319.557630965788;6402.10370548133;6409.9038514423255;6418.614883107029;4915.21356582945;4949.985253023049;4981.302617633607;5017.8243761468175;5083.135866902561;4887.511248875084;4918.827841025547;4979.402471703419;5052.595792336678;5114.9232761507865 19:15:35 INFO - PROCESS | 1641 | |4;prototype.html;343.8280859570215;347.09235349560896;356.8447725239521;365.3515010855729;370.81273956651887;5192.948070519294;5198.298229738989;5277.920831187531;5301.257823904647;5301.939612077581;4533.523979982096;4827.396575428072;5210;5235.162374020158;5248.5276325130735;3833.4666133546525;4094.8566800161925;4105.548389677133;4120.464339635843;4129.4012368206595;3491.720662347019;3960.2277555876617;4024.5371782245124;4080.489938757655;4114.958850411487;3603.2253065590894;4078.9802549362657;4079.5104587449546;4084.9183016339657;4109.794510274474;3612.638736126393;4075.042365044227;4088.8568900088503;4098.56550207428;4112.753234805891;3418.384690755663;3517.645706403028;3524.9953763177373;3530.841112149934;3609.1698909251018;4663.626909847204;5126.53861152498;5152.768125434364;5184.101024531819;5186.662866913676;3899.7075219358376;4518.43519560055;4728.219843725781;4739.73931433771;4855.684380515255;4709.835155769566;4885.853424397274;5158.380994280673;5440.292761940922;5580.218769372291;3527.4532447470683;3654.8852599957004;3781;3793.241351729665;3807.257584770971;5151.8316235514885;5463.672179669193;5463.836084917459;5781.797637082682;5799.710014499258;3643.2531331077066;3681.5582130144016;3689.601758856711;3800.258949504875;3857.8456861725776;3869.9806500967316;3871.845126194921;3880.650741433228;3882.650561449483;3894.7468414552964;3813.3517302058717;3968.3849003404516;4254.7021708480725;4522.615577675934;4541.341505481687;5236.476352364733;5383.327084114486;5536.425562113636;5545.502714267206;5566.435522871207;1130.8360287758228;1233.944977044614;1240.6972678687341;1250.481050364107;1250.5248005757753;692.4910191009604;708.5005071424633;843.5149788871423;853.9837593263996;858.3819649852096;4316.589923957219;4801.135795556764;4890.388701412324;4926.187179115406;5007.795011192839;4678.415198100238;5138;5383.16972229436;5383.380911195184;5435.347758268954;4104.794760261975;4243.596858298457;4630;4820.854065503734;4861.708297502161;2442.7175732740097;2728.717502773693;2800.7899407544514;2810.775137988956;2835.177798438471;5325.3875804282225;5399.622026458111;5516;5571.681946491502;5593.3847276799515;5210.759373469369;5489.596450200821;5510.421405752419;5528.308961379827;5546.63946843462;5528.695921724344;5530.806421775299;5540.56215265282;5567.220589117445;5580.660641446105;4740.786664600032;5117.053345131163;5310;5323.973380133075;5353.919691204557;4931.5944955687455;5264.341058163476;5275.017361891747;5605.990921633982;5620.718964051863;4849.636277279147;5031.113597091338;5443.374011988672;5543.362513311021;5817.301923769175;3689.5610711822947;3838.078861073378;3849.7882616455245;3849.7882616456363;3851.5378154621626;5087.338645976148;5161.541767016033;5191.299174611379;5672.404397538364;5684.7441865117;4486.5962063414445;4968.801247950041;5411.431799660979;5462.235287059735;5507.228987941611;4716.740579268036;5412.621116521806;5479.890402192013;5482.479164479368;5492.752826122914;4502.90994180108;4701.717896926195;4950.257461380822;5305;5317.548008419328;5110.872228194325;5168;5417.9729101355815;5485.149801780731;5533.253010843646;4616.6306695464955;4786.497417771223;5343.358796944236;5349.020862280825;5403.675779453245 19:15:35 INFO - PROCESS | 1641 | |5;yui.html;708.5005071424646;716.5736386849829;760.5588758520059;762.969481220751;824.484697064335;14660.560183194495;15387.230638468087;15682.84317156828;15701.214939253048;15703.371865125395;14120.505782297621;14285.142891426522;14306.213158276316;14308.35612397442;14322.856771432282;680.3502654964497;700.4396482813754;834.1158372125536;854.440341576267;861.7242482405633;859.0164261920098;866.2420382165606;866.6966561703416;868.3877866104381;868.7784614923186;924.856647219682;924.96300147994;926.073926073926;928.1368327455464;928.6703220356777;912.5072460871122;912.5802131019706;914.1133100892124;914.5838643417296;915.5834095486538;453.32454642582644;543.6680133672479;657.6481582353418;672.515788632184;681.4684546054036;11719.179657423983;11720.238184517979;11740;11740.58907938218;11759.882401175964;13344;13450.529231476949;13509.932450337783;13603;13639.318034098354;12122.27266364021;12290.938545307306;12345.259284442962;12477.75044499114;12893.226406415644;4340.770993624159;4383.430154079981;4452.643788496913;4496.9881776600005;4585.037142200142;88259.55870220672;97013.0298697011;98460;100129.49676258079;100294.49852750689;10620.681379558626;10641.680749577525;10643.41461219625;10683.14534837211;10690.66366704162;20110;20792.688109678365;21122.683159752614;21124.894375528333;21186.046627901782;7818;7894.565798881002;7903;7972.083210430758;8009.799755006172;3871.2257548490416;4309.525952145262;4392.494863090722;4393.736375817397;4514.210013247669;3566.982165089158;3632.8183590820354;3662.3957047086933;3670.7063434925144;3675.3751862183494;654.1626717801222;656.3370995294787;657.907892894995;670.9060731497497;672.525869262169;652.5790864892118;683.9042534045237;687.7936619014275;704.7110684619281;728.661172554754;9472.57373418184;10044;10120.544575494121;10362;10427.94786026065;643.8486955565438;686.5160062156168;687.6458623808743;687.6545605628581;693.767587858063;4631.421072365954;4914.262860570943;4915.941682362198;4922.9077642002;4977.9751101244265;83372.16627833765;85134;92155.53922230346;93399;94670;83193;84922;85781.71327430094;86041;86345.5682721595;72766.63616681883;72985.90521142186;73178.26821731821;73437.6328118356;75025.62487187529;9460.858087128836;9560.378575392577;9567.473788941676;9574.569144388659;9614.18279446255;50053.74973125257;50386.74033149201;51417.601151878516;51519.71200720012;51871.96256074933;60136.79726405535;61611.38388616056;61960.69019654873;62681;63345.683271583344;10686.946565267124;11472.369019703996;11505.884941150482;11568.710782230512;11968.940155299517;11849.881501185222;12702.30137342455;12806.295653739135;12914.935425322814;13398.799018014932;10491.108255798343;11586.826197606873;12041.397930103634;12142.210756300812;12254.4485498151;11642.94178529102;11933.224340417844;12032.217905836093;12359.505619775467;12486.250824950532;11664.708382290512;11778.469968851594;11828.526858925547;11853.5851245206;12220.45007974661;9387.29595280343;10056.949715251378;10157.288989770645;10528.578856845947;10566.524506397383;9144.542772861198;9299;9553.761155971157;9571.37786043905;9629.132821620917 19:15:35 INFO - PROCESS | 1641 | __end_tp_report 19:15:35 INFO - PROCESS | 1641 | __start_cc_report 19:15:35 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,1878 19:15:35 INFO - PROCESS | 1641 | __end_cc_report 19:15:35 INFO - PROCESS | 1641 | __startTimestamp1483672535285__endTimestamp 19:15:35 INFO - PROCESS | 1641 | 19:15:35 INFO - PROCESS | 1641 | ------- Summary: start ------- 19:15:35 INFO - PROCESS | 1641 | Number of tests: 6 19:15:35 INFO - PROCESS | 1641 | 19:15:35 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5620.67 Median:5775.54 stddev:632.96 (11.0%) stddev-sans-first:608.05 19:15:35 INFO - PROCESS | 1641 | Values: 3295.4 3324.6 3351.1 3351.6 3404.8 5504.6 5618.5 5618.9 5633.7 5647.3 5263.1 5572.3 5615.6 5677.2 5686.3 5047.8 5114.0 5121.9 5141.0 5169.0 5138.2 5211.7 5234.8 5235.5 5241.2 5189.7 5208.5 5211.2 5214.7 5224.2 5043.7 5079.0 5100.1 5156.5 5156.7 4999.3 5093.4 5099.6 5107.1 5113.7 5533.4 5622.9 5631.7 5688.3 5693.1 6070.2 6085.2 6098.5 6106.0 6115.5 6035.2 6093.7 6101.3 6137.6 6197.3 5657.4 5689.0 5697.4 5713.4 5775.5 6151.5 6204.3 6235.9 6243.6 6243.8 4582.2 5912.3 6062.8 6065.1 6067.5 6060.8 6099.9 6109.6 6137.5 6143.2 6104.3 6107.0 6129.2 6133.7 6147.5 6127.4 6153.7 6153.7 6173.6 6181.7 4783.2 4811.6 4833.9 4842.4 4850.7 4276.0 4336.1 4336.4 4351.7 4403.7 5611.4 5660.7 5664.9 5674.7 5676.1 5929.8 6143.8 6164.3 6175.3 6205.7 4723.3 5506.8 5532.1 5628.8 5647.0 5310.1 5321.7 5348.6 5361.2 5384.5 5230.2 6267.1 6269.6 6271.4 6294.0 6066.3 6073.4 6107.7 6126.7 6184.7 6159.2 6220.4 6230.9 6240.5 6247.8 5980.8 6032.0 6040.8 6072.5 6086.5 6133.1 6138.8 6144.7 6195.9 6199.9 5786.1 5804.4 5846.6 6024.4 6044.2 5911.5 5961.7 5975.6 5977.4 6043.7 5786.9 5798.5 5800.7 5802.9 5808.1 4524.6 5810.8 5936.9 5981.0 5990.5 5681.7 5707.9 5729.6 5731.8 5740.8 19:15:35 INFO - PROCESS | 1641 | 19:15:35 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:63558.77 Median:35505.82 stddev:133279.14 (375.4%) stddev-sans-first:133568.78 19:15:35 INFO - PROCESS | 1641 | Values: 443.7 450.4 495.1 502.6 524.6 30104.0 39645.4 40930.0 41009.0 41026.2 42061.8 42208.6 42361.8 42374.0 42474.6 8396.5 9294.2 9505.8 9522.3 9575.3 8477.6 9615.0 11163.7 11391.9 11630.0 11392.4 11437.2 11454.3 11473.9 11494.0 11348.0 11420.5 11432.3 11437.1 11447.9 11247.5 11314.5 11322.9 11333.8 11347.9 38928.8 39266.0 39267.2 39273.4 39285.2 3962.5 3967.6 4007.9 4018.6 4060.5 29823.4 30388.7 30512.5 30603.2 30643.7 3131.5 3247.9 3261.5 3273.5 3326.1 465393.3 469022.0 470003.6 470773.0 471415.0 57069.7 58659.4 58914.8 58964.0 59123.0 62634.0 62658.1 62682.7 62768.2 62769.7 15276.1 15639.0 15662.4 15671.5 15679.8 10975.3 11223.6 11228.9 12079.7 12215.1 16314.7 16372.3 16476.8 16855.1 17079.5 5667.5 6329.1 6361.9 6431.2 6465.7 748.6 791.3 851.5 867.5 872.4 29086.4 29205.6 29252.6 29581.0 29656.7 1038.1 1080.8 1083.3 1099.0 1153.1 14690.8 15194.4 15432.0 15530.8 15609.8 581416.1 584396.0 584601.0 584683.0 585475.1 57756.0 58082.7 58099.1 58121.0 58160.4 1351.8 1392.8 1413.1 1413.7 1418.7 25978.1 27420.6 27568.2 28428.3 29133.6 49450.5 49774.0 49889.5 49983.3 50067.5 434656.0 435539.0 435599.0 435887.0 436453.0 24351.1 25032.6 25052.7 39401.8 39737.8 41933.4 42108.0 42209.6 42579.0 43140.8 35208.0 35513.0 35797.1 36741.0 36768.4 29839.0 35040.5 35174.6 36144.7 36426.5 34672.1 34805.5 35236.6 35412.6 35498.6 29623.6 31282.5 31304.9 31468.7 31526.1 20761.0 20861.0 23327.9 23462.9 23506.3 19:15:35 INFO - PROCESS | 1641 | 19:15:35 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:38192.63 Median:22059.83 stddev:115410.35 (523.2%) stddev-sans-first:115699.08 19:15:35 INFO - PROCESS | 1641 | Values: 329.2 366.6 387.4 395.8 417.8 15390.8 15417.6 17918.0 20934.1 21484.4 20299.0 20955.4 21432.6 22033.7 22086.0 5349.5 5483.4 5519.3 5593.2 6021.0 3913.4 5220.0 6013.0 6123.4 6125.5 4111.2 4116.4 4131.9 4144.6 4145.0 4106.9 6754.0 6756.8 6759.4 6763.4 5135.2 5360.8 5430.9 6013.1 6021.9 13159.3 13446.7 13470.5 16582.9 25658.6 2511.0 2513.6 2550.3 2612.1 2612.4 9423.5 9588.7 9699.1 10110.3 10240.9 1801.5 1804.2 1809.1 1814.4 1817.0 94500.0 129733.4 136711.9 137559.0 138968.3 10683.8 11200.3 11523.5 11536.9 11571.7 13952.4 15487.9 15515.6 15541.8 15552.2 8275.8 8527.4 8533.2 8646.1 8667.6 8227.8 8240.0 8247.0 8398.9 8403.4 4597.5 4713.5 4724.3 4763.5 4783.9 1866.8 1937.0 1946.3 2004.7 2006.6 846.4 1004.0 1007.4 1016.4 1020.1 19398.2 20530.9 20674.8 20735.3 20879.9 943.4 983.2 1036.8 1049.5 1054.9 10274.8 10751.4 10757.7 10782.6 10791.1 591967.0 699996.0 707019.0 717892.0 729554.0 119796.4 142440.0 149264.3 159839.6 161375.2 1042.7 1061.3 1114.4 1115.3 1116.2 18600.3 18799.5 19115.5 19119.4 19175.0 55528.7 55777.0 57586.7 58940.0 59508.7 81085.4 81815.0 81870.0 81874.6 82602.0 7199.4 7349.6 7518.0 8134.1 8565.8 9317.5 12425.6 13144.8 13504.3 13578.3 9637.6 10584.4 10971.8 11068.6 11609.1 9268.4 11132.1 11307.5 11546.0 11802.0 9848.0 11391.5 11642.2 11668.0 11815.6 7474.4 7636.7 7646.8 7731.5 7765.0 7442.4 11112.6 11167.4 11656.8 11742.2 19:15:35 INFO - PROCESS | 1641 | 19:15:35 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5356.13 Median:5148.84 stddev:2951.32 (57.3%) stddev-sans-first:2944.91 19:15:35 INFO - PROCESS | 1641 | Values: 1438.7 1441.8 1443.8 1450.6 1456.3 8003.4 8400.3 8411.9 8413.7 8415.3 7417.8 7640.8 7995.8 8230.2 8238.5 2273.5 2311.4 2314.8 2315.5 2320.7 2295.9 2321.3 2332.0 2333.8 2334.2 2269.7 2278.2 2305.6 2313.1 2314.3 2282.6 2284.3 2302.8 2305.7 2316.1 2292.4 2299.7 2325.4 2331.5 2335.4 8375.8 8454.5 8457.4 8466.5 8476.6 5006.5 5146.6 5148.2 5149.5 5154.1 3055.9 3261.6 3397.6 3534.7 3538.2 1205.2 1214.4 1235.8 1245.9 1262.1 10483.1 10694.9 10715.5 10735.4 10747.6 3943.0 4239.6 4540.3 4577.6 4618.1 9349.6 9358.9 9359.9 9384.5 9392.5 4426.9 5122.3 5153.4 5158.1 5170.4 4255.3 4388.6 4420.4 4611.7 4732.3 3536.9 3699.2 3726.5 3840.8 3865.7 1138.2 1244.4 1247.0 1265.1 1265.3 752.7 774.9 901.2 918.6 949.5 4603.8 5245.8 5278.9 5481.4 5694.7 554.9 576.6 581.2 582.9 583.9 3075.1 3600.9 3708.3 3741.9 3757.1 9680.7 9915.2 10005.9 10028.9 10032.3 9963.6 9979.2 9979.6 10005.6 10030.1 7528.5 7672.6 7859.6 7862.8 7889.6 5356.7 5442.5 5475.9 5568.5 5682.9 7908.4 8984.8 9022.1 9143.5 9241.0 9103.0 9208.2 9282.2 9390.7 9396.1 6544.8 6727.6 6761.7 7032.3 7210.8 7683.0 7943.8 7963.2 8052.4 8055.5 6126.5 6992.3 7028.9 7045.9 7082.6 6922.8 6943.8 6984.6 7010.8 7014.5 6215.5 6319.6 6402.1 6409.9 6418.6 4915.2 4950.0 4981.3 5017.8 5083.1 4887.5 4918.8 4979.4 5052.6 5114.9 19:15:35 INFO - PROCESS | 1641 | 19:15:35 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4390.93 Median:4888.12 stddev:1319.96 (27.0%) stddev-sans-first:1288.23 19:15:35 INFO - PROCESS | 1641 | Values: 343.8 347.1 356.8 365.4 370.8 5192.9 5198.3 5277.9 5301.3 5301.9 4533.5 4827.4 5210.0 5235.2 5248.5 3833.5 4094.9 4105.5 4120.5 4129.4 3491.7 3960.2 4024.5 4080.5 4115.0 3603.2 4079.0 4079.5 4084.9 4109.8 3612.6 4075.0 4088.9 4098.6 4112.8 3418.4 3517.6 3525.0 3530.8 3609.2 4663.6 5126.5 5152.8 5184.1 5186.7 3899.7 4518.4 4728.2 4739.7 4855.7 4709.8 4885.9 5158.4 5440.3 5580.2 3527.5 3654.9 3781.0 3793.2 3807.3 5151.8 5463.7 5463.8 5781.8 5799.7 3643.3 3681.6 3689.6 3800.3 3857.8 3870.0 3871.8 3880.7 3882.7 3894.7 3813.4 3968.4 4254.7 4522.6 4541.3 5236.5 5383.3 5536.4 5545.5 5566.4 1130.8 1233.9 1240.7 1250.5 1250.5 692.5 708.5 843.5 854.0 858.4 4316.6 4801.1 4890.4 4926.2 5007.8 4678.4 5138.0 5383.2 5383.4 5435.3 4104.8 4243.6 4630.0 4820.9 4861.7 2442.7 2728.7 2800.8 2810.8 2835.2 5325.4 5399.6 5516.0 5571.7 5593.4 5210.8 5489.6 5510.4 5528.3 5546.6 5528.7 5530.8 5540.6 5567.2 5580.7 4740.8 5117.1 5310.0 5324.0 5353.9 4931.6 5264.3 5275.0 5606.0 5620.7 4849.6 5031.1 5443.4 5543.4 5817.3 3689.6 3838.1 3849.8 3849.8 3851.5 5087.3 5161.5 5191.3 5672.4 5684.7 4486.6 4968.8 5411.4 5462.2 5507.2 4716.7 5412.6 5479.9 5482.5 5492.8 4502.9 4701.7 4950.3 5305.0 5317.5 5110.9 5168.0 5418.0 5485.1 5533.3 4616.6 4786.5 5343.4 5349.0 5403.7 19:15:35 INFO - PROCESS | 1641 | 19:15:35 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19120.06 Median:27515.86 stddev:27020.67 (98.2%) stddev-sans-first:27061.10 19:15:35 INFO - PROCESS | 1641 | Values: 708.5 716.6 760.6 763.0 824.5 14660.6 15387.2 15682.8 15701.2 15703.4 14120.5 14285.1 14306.2 14308.4 14322.9 680.4 700.4 834.1 854.4 861.7 859.0 866.2 866.7 868.4 868.8 924.9 925.0 926.1 928.1 928.7 912.5 912.6 914.1 914.6 915.6 453.3 543.7 657.6 672.5 681.5 11719.2 11720.2 11740.0 11740.6 11759.9 13344.0 13450.5 13509.9 13603.0 13639.3 12122.3 12290.9 12345.3 12477.8 12893.2 4340.8 4383.4 4452.6 4497.0 4585.0 88259.6 97013.0 98460.0 100129.5 100294.5 10620.7 10641.7 10643.4 10683.1 10690.7 20110.0 20792.7 21122.7 21124.9 21186.0 7818.0 7894.6 7903.0 7972.1 8009.8 3871.2 4309.5 4392.5 4393.7 4514.2 3567.0 3632.8 3662.4 3670.7 3675.4 654.2 656.3 657.9 670.9 672.5 652.6 683.9 687.8 704.7 728.7 9472.6 10044.0 10120.5 10362.0 10427.9 643.8 686.5 687.6 687.7 693.8 4631.4 4914.3 4915.9 4922.9 4978.0 83372.2 85134.0 92155.5 93399.0 94670.0 83193.0 84922.0 85781.7 86041.0 86345.6 72766.6 72985.9 73178.3 73437.6 75025.6 9460.9 9560.4 9567.5 9574.6 9614.2 50053.7 50386.7 51417.6 51519.7 51872.0 60136.8 61611.4 61960.7 62681.0 63345.7 10686.9 11472.4 11505.9 11568.7 11968.9 11849.9 12702.3 12806.3 12914.9 13398.8 10491.1 11586.8 12041.4 12142.2 12254.4 11642.9 11933.2 12032.2 12359.5 12486.3 11664.7 11778.5 11828.5 11853.6 12220.5 9387.3 10056.9 10157.3 10528.6 10566.5 9144.5 9299.0 9553.8 9571.4 9629.1 19:15:35 INFO - PROCESS | 1641 | -------- Summary: end -------- 19:15:35 INFO - PROCESS | 1641 | 19:15:35 INFO - TEST-INFO | 1641: exit 0 19:15:35 INFO - TEST-OK | dromaeo_css | took 1119854ms 19:15:35 INFO - TEST-START | kraken 19:15:35 INFO - operating with platform_type : mac_ 19:15:35 INFO - Initialising browser for kraken test... 19:15:35 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpfhDV9x/profile http://localhost:49242/getInfo.html) 19:15:36 INFO - PROCESS | 1651 | 1483672536413 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:4907 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3762 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2795 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 19:15:38 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 19:15:38 INFO - PROCESS | 1651 | colorDepth:24 19:15:38 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 19:15:38 INFO - PROCESS | 1651 | __metrics 19:15:38 INFO - PROCESS | 1651 | 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" 19:15:38 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:15:39 INFO - TEST-INFO | 1651: exit 0 19:15:39 INFO - Browser initialized. 19:15:39 INFO - Running cycle 1/1 for kraken test... 19:15:39 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpfhDV9x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:15:51 INFO - PROCESS | 1652 | RSS: Main: 225804288 19:15:51 INFO - PROCESS | 1652 | 19:15:53 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 19:15:54 INFO - PROCESS | 1652 | RSS: Main: 287158272 19:15:54 INFO - PROCESS | 1652 | 19:15:57 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 19:15:57 INFO - PROCESS | 1652 | RSS: Main: 290181120 19:15:57 INFO - PROCESS | 1652 | 19:16:01 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 19:16:02 INFO - PROCESS | 1652 | RSS: Main: 378138624 19:16:02 INFO - PROCESS | 1652 | 19:16:04 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 19:16:04 INFO - PROCESS | 1652 | RSS: Main: 282124288 19:16:04 INFO - PROCESS | 1652 | 19:16:06 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:16:07 INFO - PROCESS | 1652 | RSS: Main: 285847552 19:16:07 INFO - PROCESS | 1652 | 19:16:10 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 19:16:10 INFO - PROCESS | 1652 | RSS: Main: 328527872 19:16:10 INFO - PROCESS | 1652 | 19:16:13 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 19:16:14 INFO - PROCESS | 1652 | RSS: Main: 328982528 19:16:14 INFO - PROCESS | 1652 | 19:16:17 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 19:16:17 INFO - PROCESS | 1652 | RSS: Main: 328503296 19:16:17 INFO - PROCESS | 1652 | 19:16:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:16:19 INFO - PROCESS | 1652 | RSS: Main: 303955968 19:16:19 INFO - PROCESS | 1652 | 19:16:21 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:16:22 INFO - PROCESS | 1652 | RSS: Main: 306675712 19:16:22 INFO - PROCESS | 1652 | 19:16:24 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:16:25 INFO - PROCESS | 1652 | RSS: Main: 308572160 19:16:25 INFO - PROCESS | 1652 | 19:16:28 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:16:29 INFO - PROCESS | 1652 | RSS: Main: 324153344 19:16:29 INFO - PROCESS | 1652 | 19:16:32 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:16:32 INFO - PROCESS | 1652 | RSS: Main: 318472192 19:16:32 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 19:16:34 INFO - PROCESS | 1652 | RSS: Main: 362369024 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | __start_tp_report 19:16:34 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 19:16:34 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 19:16:34 INFO - PROCESS | 1652 | |i|pagename|runs| 19:16:34 INFO - PROCESS | 1652 | |0;ai-astar;111;113;113;113;113;115;110;113;115;113 19:16:34 INFO - PROCESS | 1652 | |1;audio-beat-detection;143;108;105;141;103;108;107;107;129;107 19:16:34 INFO - PROCESS | 1652 | |2;audio-dft;187;181;182;184;182;183;181;207;261;183 19:16:34 INFO - PROCESS | 1652 | |3;audio-fft;79;59;58;86;59;59;59;57;89;81 19:16:34 INFO - PROCESS | 1652 | |4;audio-oscillator;90;71;86;70;69;70;83;69;70;85 19:16:34 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;89;86;89;84;88;85;89;86;90;129 19:16:34 INFO - PROCESS | 1652 | |6;imaging-darkroom;97;97;99;97;95;102;162;96;100;103 19:16:34 INFO - PROCESS | 1652 | |7;imaging-desaturate;86;87;86;88;87;85;87;84;87;85 19:16:34 INFO - PROCESS | 1652 | |8;json-parse-financial;57;57;55;55;57;56;55;56;56;57 19:16:34 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;74;70;74;76;76;71;71;74;73;74 19:16:34 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;86;94;107;105;105;89;85;87;90;86 19:16:34 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;139;139;139;138;138;136;128;163;203;184 19:16:34 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;175;176;170;170;172;169;201;221;230;170 19:16:34 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;67;71;83;88;85;71;67;66;66;67 19:16:34 INFO - PROCESS | 1652 | __end_tp_report 19:16:34 INFO - PROCESS | 1652 | __start_cc_report 19:16:34 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1996 19:16:34 INFO - PROCESS | 1652 | __end_cc_report 19:16:34 INFO - PROCESS | 1652 | __startTimestamp1483672594930__endTimestamp 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | ------- Summary: start ------- 19:16:34 INFO - PROCESS | 1652 | Number of tests: 14 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:112.90 Median:113.00 stddev:1.52 (1.3%) stddev-sans-first:1.45 19:16:34 INFO - PROCESS | 1652 | Values: 111.0 113.0 113.0 113.0 113.0 115.0 110.0 113.0 115.0 113.0 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:115.80 Median:108.00 stddev:15.58 (14.4%) stddev-sans-first:13.05 19:16:34 INFO - PROCESS | 1652 | Values: 143.0 108.0 105.0 141.0 103.0 108.0 107.0 107.0 129.0 107.0 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:193.10 Median:183.50 stddev:25.09 (13.7%) stddev-sans-first:26.52 19:16:34 INFO - PROCESS | 1652 | Values: 187.0 181.0 182.0 184.0 182.0 183.0 181.0 207.0 261.0 183.0 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:68.60 Median:69.00 stddev:13.32 (19.3%) stddev-sans-first:13.58 19:16:34 INFO - PROCESS | 1652 | Values: 79.0 59.0 58.0 86.0 59.0 59.0 59.0 57.0 89.0 81.0 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:77.00 stddev:8.54 (11.1%) stddev-sans-first:7.48 19:16:34 INFO - PROCESS | 1652 | Values: 90.0 71.0 86.0 70.0 69.0 70.0 83.0 69.0 70.0 85.0 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:88.50 stddev:13.33 (15.1%) stddev-sans-first:14.10 19:16:34 INFO - PROCESS | 1652 | Values: 89.0 86.0 89.0 84.0 88.0 85.0 89.0 86.0 90.0 129.0 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:104.80 Median:96.50 stddev:20.26 (21.0%) stddev-sans-first:21.30 19:16:34 INFO - PROCESS | 1652 | Values: 97.0 97.0 99.0 97.0 95.0 102.0 162.0 96.0 100.0 103.0 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:86.20 Median:87.00 stddev:1.23 (1.4%) stddev-sans-first:1.30 19:16:34 INFO - PROCESS | 1652 | Values: 86.0 87.0 86.0 88.0 87.0 85.0 87.0 84.0 87.0 85.0 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:56.50 stddev:0.88 (1.5%) stddev-sans-first:0.87 19:16:34 INFO - PROCESS | 1652 | Values: 57.0 57.0 55.0 55.0 57.0 56.0 55.0 56.0 56.0 57.0 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:74.00 stddev:2.06 (2.8%) stddev-sans-first:2.17 19:16:34 INFO - PROCESS | 1652 | Values: 74.0 70.0 74.0 76.0 76.0 71.0 71.0 74.0 73.0 74.0 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:93.40 Median:86.50 stddev:8.86 (10.2%) stddev-sans-first:8.98 19:16:34 INFO - PROCESS | 1652 | Values: 86.0 94.0 107.0 105.0 105.0 89.0 85.0 87.0 90.0 86.0 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:150.70 Median:139.00 stddev:24.63 (17.7%) stddev-sans-first:25.76 19:16:34 INFO - PROCESS | 1652 | Values: 139.0 139.0 139.0 138.0 138.0 136.0 128.0 163.0 203.0 184.0 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.40 Median:175.50 stddev:23.23 (13.2%) stddev-sans-first:24.33 19:16:34 INFO - PROCESS | 1652 | Values: 175.0 176.0 170.0 170.0 172.0 169.0 201.0 221.0 230.0 170.0 19:16:34 INFO - PROCESS | 1652 | 19:16:34 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:71.00 stddev:8.71 (12.3%) stddev-sans-first:8.96 19:16:34 INFO - PROCESS | 1652 | Values: 67.0 71.0 83.0 88.0 85.0 71.0 67.0 66.0 66.0 67.0 19:16:34 INFO - PROCESS | 1652 | -------- Summary: end -------- 19:16:34 INFO - PROCESS | 1652 | 19:16:35 INFO - TEST-INFO | 1652: exit 0 19:16:35 INFO - TEST-OK | kraken | took 60029ms 19:16:35 INFO - SUITE-END | took 1179s 19:16:36 INFO - Completed test suite (00:19:40) 19:16:36 INFO - javascript benchmark 19:16:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3295.373878962997, 3324.5511855899445, 3351.1119553318367, 3351.597808263009, 3404.7957122572648, 5504.587155963309, 5618.494335509803, 5618.91571626426, 5633.690147041916, 5647.322321321447, 5263.131583288753, 5572.331320241567, 5615.578831587637, 5677.205191273225, 5686.289213848269, 5047.798088076473, 5113.974430127844, 5121.923171152435, 5140.974295128538, 5168.974155129219, 5138.203578445328, 5211.661242019257, 5234.842954711346, 5235.476452354753, 5241.187615919539, 5189.714565698885, 5208.531232189102, 5211.218317252424, 5214.687118772886, 5224.216367544861, 5043.697378157286, 5079.0, 5100.132977393852, 5156.484351564851, 5156.690598564061, 4999.32509111269, 5093.414257360414, 5099.566536844372, 5107.080725469414, 5113.718745468997, 5533.418991005927, 5622.887542249173, 5631.662100273956, 5688.288963879515, 5693.117566777156, 6070.150178974947, 6085.178500902366, 6098.481629061535, 6105.969470152665, 6115.510759139258, 6035.215421995156, 6093.69531523422, 6101.2983506896835, 6137.60105593135, 6197.349278325756, 5657.377688454267, 5688.971555142198, 5697.401772813878, 5713.4000929902595, 5775.537956963517, 6151.507879369729, 6204.348543402878, 6235.875282494325, 6243.625382477066, 6243.8126856194385, 4582.198115329804, 5912.320083190488, 6062.757489700451, 6065.120557519223, 6067.514598832083, 6060.848478788065, 6099.939000610026, 6109.572329936862, 6137.508999280047, 6143.157052884207, 6104.254103856739, 6107.044717083349, 6129.175413489998, 6133.6582248142495, 6147.508199344042, 6127.4485296322655, 6153.661548614779, 6153.692315384213, 6173.56785025044, 6181.660008699475, 4783.1868582341085, 4811.591014763784, 4833.936533962523, 4842.394700662418, 4850.708957462563, 4275.978620106879, 4336.111097225062, 4336.392904993303, 4351.673624478177, 4403.691741578123, 5611.438856114356, 5660.660360378391, 5664.915026274609, 5674.659520428788, 5676.063449530781, 5929.762809487658, 6143.815685529441, 6164.260288765287, 6175.320714721377, 6205.6586887721605, 4723.31511930775, 5506.752196151183, 5532.1425179097305, 5628.774849005994, 5647.040003199466, 5310.070737620901, 5321.733913304397, 5348.625596208306, 5361.195820626859, 5384.488473595002, 5230.241614965885, 6267.0599410087025, 6269.5611307208055, 6271.404216599507, 6293.96853015732, 6066.332703402711, 6073.392660733891, 6107.67434349443, 6126.693665316806, 6184.6598437086395, 6159.168512250789, 6220.440160385406, 6230.937690623217, 6240.500759939103, 6247.781327653511, 5980.790672326448, 6032.034874420072, 6040.7583696651645, 6072.514198864169, 6086.452219300284, 6133.05924499315, 6138.786925362591, 6144.709610981744, 6195.876082478415, 6199.876002480015, 5786.103154011135, 5804.390538993345, 5846.649201047951, 6024.378692324844, 6044.24446944132, 5911.527077833849, 5961.672108033927, 5975.551833612583, 5977.372375900468, 6043.667598281961, 5786.9131963019745, 5798.507126894261, 5800.6809625471005, 5802.94197058024, 5808.128780682931, 4524.63802895761, 5810.82567522975, 5936.940630593812, 5981.0, 5990.4908082813445, 5681.744321505625, 5707.942920570741, 5729.570282228766, 5731.770729170787, 5740.799072032459], "value": 5582.016346285462, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [443.7315424168377, 450.4437020279952, 495.1483448468634, 502.61028256869764, 524.5960610330046, 30104.0, 39645.4053189202, 40930.0, 41008.99999999996, 41026.179476410456, 42061.78969105158, 42208.57791422085, 42361.78819105908, 42374.0, 42474.57525424745, 8396.454230475, 9294.163525282724, 9505.80988380232, 9522.333436659403, 9575.281853860954, 8477.61850716716, 9614.951925240364, 11163.72090697735, 11391.943040284787, 11630.0114490268, 11392.430378481044, 11437.19939604228, 11454.312741235512, 11473.885261147407, 11494.0, 11347.978681918627, 11420.54317827286, 11432.314061156358, 11437.142214333959, 11447.942760286145, 11247.493862776195, 11314.490847911864, 11322.94338528302, 11333.773324533464, 11347.886521134847, 38928.80535597304, 39266.0, 39267.214655706724, 39273.41089883654, 39285.21429571392, 3962.484876966005, 3967.583403742623, 4007.8797636070963, 4018.6383225509553, 4060.533038700558, 29823.403531929456, 30388.696113038808, 30512.542311865338, 30603.2349191272, 30643.693563064306, 3131.530270459449, 3247.886323978673, 3261.4944683574086, 3273.541704161419, 3326.085326535198, 465393.34606653836, 469022.0, 470003.6499817479, 470773.0, 471415.0, 57069.714651427304, 58659.413405866246, 58914.821703565685, 58964.0, 59123.0, 62634.0, 62658.0601290981, 62682.68658656678, 62768.175432105665, 62769.68615156895, 15276.083434993936, 15639.0, 15662.37350505967, 15671.451499197474, 15679.843201568066, 10975.341479511255, 11223.607173749044, 11228.887711122947, 12079.698007549881, 12215.144939854124, 16314.673706525802, 16372.345106195618, 16476.83523164753, 16855.072970986712, 17079.48761537156, 5667.546596272288, 6329.145565348711, 6361.872762544724, 6431.163948686641, 6465.709043093072, 748.584535582746, 791.3194652598761, 851.4891065360735, 867.544539116956, 872.437277955715, 29086.418271634448, 29205.561916571267, 29252.561211581844, 29581.0, 29656.703432965827, 1038.1227862456212, 1080.827067669169, 1083.2525557365393, 1099.0, 1153.103145325951, 14690.779638305434, 15194.392224311125, 15431.99692020015, 15530.767038494432, 15609.765853512206, 581416.0929195412, 584396.0, 584601.0, 584683.0, 585475.0726246426, 57756.0, 58082.7095864518, 58099.12851307149, 58121.0, 58160.41839581635, 1351.8310211223597, 1392.7771556550904, 1413.0956188039868, 1413.6536548545466, 1418.666613345863, 25978.090766823072, 27420.588691170047, 27568.172954810587, 28428.289292767848, 29133.562996554643, 49450.50549494459, 49774.0, 49889.50110498994, 49983.25025124553, 50067.49932500628, 434656.0, 435539.0, 435599.0, 435887.0, 436453.0, 24351.14770983007, 25032.62451063272, 25052.749472505042, 39401.80299098486, 39737.80131099326, 41933.37099943564, 42108.0, 42209.57790422056, 42579.0, 43140.78429607832, 35208.0, 35513.0, 35797.1050723734, 36741.0, 36768.448473273456, 29839.0, 35040.47439288464, 35174.64825351714, 36144.73493427718, 36426.45360319544, 34672.133196670286, 34805.47791783074, 35236.647633524364, 35412.645873541966, 35498.64501354953, 29623.555646664885, 31282.53076203904, 31304.9043283484, 31468.685313147493, 31526.054218373487, 20761.028954890022, 20860.974727632412, 23327.900488956075, 23462.882685586465, 23506.294811155694], "value": 19462.683159636996, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.2394568546657, 366.6297039989613, 387.41113507468646, 395.8080331039445, 417.80572034004183, 15390.769138462918, 15417.614559636015, 17918.0, 20934.057967391465, 21484.355469335907, 20299.0, 20955.37133885986, 21432.57134857302, 22033.669494957587, 22086.0, 5349.545288650469, 5483.424240454755, 5519.337679478468, 5593.188987596796, 6020.969895150518, 3913.3542965410675, 5220.0, 6013.037913933771, 6123.387661233885, 6125.479334256594, 4111.217193071336, 4116.444280022203, 4131.917361652765, 4144.626983571478, 4144.97927510362, 4106.8562600309, 6754.0, 6756.797296081077, 6759.391654751072, 6763.425108865752, 5135.152699804527, 5360.785568577252, 5430.945690543083, 6013.128096426038, 6021.879562408771, 13159.342032898317, 13446.663833404244, 13470.52853150135, 16582.917085414618, 25658.615120773204, 2510.9874450627813, 2513.635522849195, 2550.2859199424192, 2612.0857699804915, 2612.373030472673, 9423.48170850603, 9588.712338629852, 9699.078587534173, 10110.34282771625, 10240.94879525605, 1801.4622000456322, 1804.1520485371855, 1809.1044932758368, 1814.355903654197, 1817.0, 94500.0, 129733.35133324367, 136711.94932076026, 137559.0, 138968.30515847457, 10683.83974240387, 11200.327980321206, 11523.481443334902, 11536.884631153749, 11571.710707232387, 13952.441902323997, 15487.922560387125, 15515.612109697122, 15541.766873496907, 15552.222388880511, 8275.793105172419, 8527.40308178434, 8533.232009119085, 8646.135386461303, 8667.609957551798, 8227.8354432911, 8239.958800206081, 8247.0, 8398.916010839936, 8403.41176117666, 4597.540245975376, 4713.457952335457, 4724.267738500538, 4763.523647635208, 4783.928241076386, 1866.8263951282602, 1937.0024437414743, 1946.260421040024, 2004.7494063242095, 2006.5786184901042, 846.3863698818308, 1003.9698809035741, 1007.3653598233067, 1016.364772017489, 1020.1175982774846, 19398.224071037, 20530.897345513178, 20674.793252067586, 20735.27426540064, 20879.895600522203, 943.4339396362265, 983.2281658897731, 1036.8185567525652, 1049.5277125293649, 1054.852320675106, 10274.794504109876, 10751.408672523086, 10757.677269681923, 10782.56869725218, 10791.082757965503, 591967.0, 699996.0, 707019.0, 717892.0, 729554.0, 119796.40101799436, 142440.0, 149264.25367873092, 159839.602405964, 161375.19312403363, 1042.74452759073, 1061.3313612424124, 1114.4093630375914, 1115.252780636975, 1116.1851848150895, 18600.34898778536, 18799.530011749815, 19115.463639226786, 19119.426417207505, 19175.041247937268, 55528.72235638796, 55777.0, 57586.7120664394, 58940.0, 59508.70245648917, 81085.37829243264, 81815.0, 81870.0, 81874.59062704648, 82602.0, 7199.388052015637, 7349.55902645843, 7517.985072015426, 8134.145914678874, 8565.785855353666, 9317.534123293945, 12425.62723118308, 13144.802827957397, 13504.324783760576, 13578.25319607431, 9637.614495420385, 10584.36493810343, 10971.83542246851, 11068.5572577096, 11609.071274298205, 9268.443893366419, 11132.109431245643, 11307.491162897853, 11546.0, 11802.0, 9848.0, 11391.544338226378, 11642.24325418845, 11668.0, 11815.586454474053, 7474.439417043634, 7636.694532218871, 7646.847063058596, 7731.458797884094, 7764.961175194087, 7442.367398770949, 11112.611058612909, 11167.385793781095, 11656.825147622623, 11742.236754610924], "value": 9496.312181440935, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1438.6547228665124, 1441.7921118481154, 1443.7545617245069, 1450.5793319937218, 1456.3373664982435, 8003.3997450191255, 8400.3279737621, 8411.91588084119, 8413.663453461853, 8415.284700800432, 7417.777466675995, 7640.770776876689, 7995.800104997378, 8230.176982301768, 8238.505689658625, 2273.4771002669395, 2311.4221444638843, 2314.837961342707, 2315.548468048731, 2320.6983092198075, 2295.9426014349606, 2321.315212012452, 2332.0322066342446, 2333.7782910623464, 2334.2297041976194, 2269.6595510673365, 2278.2481781012225, 2305.6310990241564, 2313.144136669426, 2314.3057082875152, 2282.589133955887, 2284.303287497313, 2302.838801283911, 2305.7002589663402, 2316.0851463671925, 2292.3810571145864, 2299.72403311602, 2325.3721495196373, 2331.5453486570127, 2335.4394945213025, 8375.83248335036, 8454.450460720034, 8457.40798144124, 8466.492010479391, 8476.576171191477, 5006.524380183877, 5146.588272938156, 5148.227765835118, 5149.45930677281, 5154.123798954187, 3055.8624861881044, 3261.608606967179, 3397.590361445783, 3534.6642069003406, 3538.15084379747, 1205.15036898986, 1214.4110106598293, 1235.7899157143218, 1245.894099001586, 1262.1480500662108, 10483.056524912794, 10694.893051069468, 10715.46422678863, 10735.355878647306, 10747.623833165802, 3943.0142464383903, 4239.59723826236, 4540.296254080623, 4577.5880170784785, 4618.053299073682, 9349.579268932914, 9358.906410935939, 9359.859602105973, 9384.5307734613, 9392.530373481299, 4426.89327668083, 5122.257272695439, 5153.3815942087185, 5158.148905430562, 5170.431252562215, 4255.29787585045, 4388.605025547716, 4420.425344705232, 4611.700239484489, 4732.290156476556, 3536.929261414757, 3699.2231631357176, 3726.5341832270965, 3840.846366145379, 3865.6520913117392, 1138.1975707126455, 1244.4151248912997, 1246.9750604987848, 1265.0638527489775, 1265.316728966348, 752.7144016368621, 774.9380049596019, 901.1889299630385, 918.6187732091241, 949.5489642419795, 4603.803011217107, 5245.816396426107, 5278.9472105278455, 5481.424450432647, 5694.686792226467, 554.8751530905595, 576.5675743192605, 581.1950448628595, 582.912867502114, 583.9036558967723, 3075.077476756964, 3600.9279814403567, 3708.295423869456, 3741.9251614968093, 3757.1358587525024, 9680.661176858775, 9915.157211637092, 10005.899941000496, 10028.949855250823, 10032.297739158188, 9963.551640176209, 9979.201663866874, 9979.60081596728, 10005.599776008878, 10030.147437467751, 7528.51064680794, 7672.578008209491, 7859.567723775133, 7862.803429914298, 7889.566073865993, 5356.705381204071, 5442.510174084351, 5475.945240547703, 5568.49883510486, 5682.943170568242, 7908.3673306136525, 8984.820303594022, 9022.097790220925, 9143.542822858697, 9240.95379523098, 9103.0, 9208.171264586219, 9282.164605185566, 9390.718278451657, 9396.107369799858, 6544.83637909037, 6727.629980351127, 6761.695723692347, 7032.26161253061, 7210.819729506804, 7683.0, 7943.84112317762, 7963.16386779403, 8052.396070294634, 8055.476394034369, 6126.5098792097415, 6992.265812089658, 7028.859422811617, 7045.859082818418, 7082.61045642474, 6922.7923162305215, 6943.756968506078, 6984.615846128511, 7010.789676309719, 7014.508984371045, 6215.471684906834, 6319.557630965788, 6402.10370548133, 6409.9038514423255, 6418.614883107029, 4915.21356582945, 4949.985253023049, 4981.302617633607, 5017.8243761468175, 5083.135866902561, 4887.511248875084, 4918.827841025547, 4979.402471703419, 5052.595792336678, 5114.9232761507865], "value": 4307.245424221742, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [343.8280859570215, 347.09235349560896, 356.8447725239521, 365.3515010855729, 370.81273956651887, 5192.948070519294, 5198.298229738989, 5277.920831187531, 5301.257823904647, 5301.939612077581, 4533.523979982096, 4827.396575428072, 5210.0, 5235.162374020158, 5248.5276325130735, 3833.4666133546525, 4094.8566800161925, 4105.548389677133, 4120.464339635843, 4129.4012368206595, 3491.720662347019, 3960.2277555876617, 4024.5371782245124, 4080.489938757655, 4114.958850411487, 3603.2253065590894, 4078.9802549362657, 4079.5104587449546, 4084.9183016339657, 4109.794510274474, 3612.638736126393, 4075.042365044227, 4088.8568900088503, 4098.56550207428, 4112.753234805891, 3418.384690755663, 3517.645706403028, 3524.9953763177373, 3530.841112149934, 3609.1698909251018, 4663.626909847204, 5126.53861152498, 5152.768125434364, 5184.101024531819, 5186.662866913676, 3899.7075219358376, 4518.43519560055, 4728.219843725781, 4739.73931433771, 4855.684380515255, 4709.835155769566, 4885.853424397274, 5158.380994280673, 5440.292761940922, 5580.218769372291, 3527.4532447470683, 3654.8852599957004, 3781.0, 3793.241351729665, 3807.257584770971, 5151.8316235514885, 5463.672179669193, 5463.836084917459, 5781.797637082682, 5799.710014499258, 3643.2531331077066, 3681.5582130144016, 3689.601758856711, 3800.258949504875, 3857.8456861725776, 3869.9806500967316, 3871.845126194921, 3880.650741433228, 3882.650561449483, 3894.7468414552964, 3813.3517302058717, 3968.3849003404516, 4254.7021708480725, 4522.615577675934, 4541.341505481687, 5236.476352364733, 5383.327084114486, 5536.425562113636, 5545.502714267206, 5566.435522871207, 1130.8360287758228, 1233.944977044614, 1240.6972678687341, 1250.481050364107, 1250.5248005757753, 692.4910191009604, 708.5005071424633, 843.5149788871423, 853.9837593263996, 858.3819649852096, 4316.589923957219, 4801.135795556764, 4890.388701412324, 4926.187179115406, 5007.795011192839, 4678.415198100238, 5138.0, 5383.16972229436, 5383.380911195184, 5435.347758268954, 4104.794760261975, 4243.596858298457, 4630.0, 4820.854065503734, 4861.708297502161, 2442.7175732740097, 2728.717502773693, 2800.7899407544514, 2810.775137988956, 2835.177798438471, 5325.3875804282225, 5399.622026458111, 5516.0, 5571.681946491502, 5593.3847276799515, 5210.759373469369, 5489.596450200821, 5510.421405752419, 5528.308961379827, 5546.63946843462, 5528.695921724344, 5530.806421775299, 5540.56215265282, 5567.220589117445, 5580.660641446105, 4740.786664600032, 5117.053345131163, 5310.0, 5323.973380133075, 5353.919691204557, 4931.5944955687455, 5264.341058163476, 5275.017361891747, 5605.990921633982, 5620.718964051863, 4849.636277279147, 5031.113597091338, 5443.374011988672, 5543.362513311021, 5817.301923769175, 3689.5610711822947, 3838.078861073378, 3849.7882616455245, 3849.7882616456363, 3851.5378154621626, 5087.338645976148, 5161.541767016033, 5191.299174611379, 5672.404397538364, 5684.7441865117, 4486.5962063414445, 4968.801247950041, 5411.431799660979, 5462.235287059735, 5507.228987941611, 4716.740579268036, 5412.621116521806, 5479.890402192013, 5482.479164479368, 5492.752826122914, 4502.90994180108, 4701.717896926195, 4950.257461380822, 5305.0, 5317.548008419328, 5110.872228194325, 5168.0, 5417.9729101355815, 5485.149801780731, 5533.253010843646, 4616.6306695464955, 4786.497417771223, 5343.358796944236, 5349.020862280825, 5403.675779453245], "value": 3975.564459340441, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.5005071424646, 716.5736386849829, 760.5588758520059, 762.969481220751, 824.484697064335, 14660.560183194495, 15387.230638468087, 15682.84317156828, 15701.214939253048, 15703.371865125395, 14120.505782297621, 14285.142891426522, 14306.213158276316, 14308.35612397442, 14322.856771432282, 680.3502654964497, 700.4396482813754, 834.1158372125536, 854.440341576267, 861.7242482405633, 859.0164261920098, 866.2420382165606, 866.6966561703416, 868.3877866104381, 868.7784614923186, 924.856647219682, 924.96300147994, 926.073926073926, 928.1368327455464, 928.6703220356777, 912.5072460871122, 912.5802131019706, 914.1133100892124, 914.5838643417296, 915.5834095486538, 453.32454642582644, 543.6680133672479, 657.6481582353418, 672.515788632184, 681.4684546054036, 11719.179657423983, 11720.238184517979, 11740.0, 11740.58907938218, 11759.882401175964, 13344.0, 13450.529231476949, 13509.932450337783, 13603.0, 13639.318034098354, 12122.27266364021, 12290.938545307306, 12345.259284442962, 12477.75044499114, 12893.226406415644, 4340.770993624159, 4383.430154079981, 4452.643788496913, 4496.9881776600005, 4585.037142200142, 88259.55870220672, 97013.0298697011, 98460.0, 100129.49676258079, 100294.49852750689, 10620.681379558626, 10641.680749577525, 10643.41461219625, 10683.14534837211, 10690.66366704162, 20110.0, 20792.688109678365, 21122.683159752614, 21124.894375528333, 21186.046627901782, 7818.0, 7894.565798881002, 7903.0, 7972.083210430758, 8009.799755006172, 3871.2257548490416, 4309.525952145262, 4392.494863090722, 4393.736375817397, 4514.210013247669, 3566.982165089158, 3632.8183590820354, 3662.3957047086933, 3670.7063434925144, 3675.3751862183494, 654.1626717801222, 656.3370995294787, 657.907892894995, 670.9060731497497, 672.525869262169, 652.5790864892118, 683.9042534045237, 687.7936619014275, 704.7110684619281, 728.661172554754, 9472.57373418184, 10044.0, 10120.544575494121, 10362.0, 10427.94786026065, 643.8486955565438, 686.5160062156168, 687.6458623808743, 687.6545605628581, 693.767587858063, 4631.421072365954, 4914.262860570943, 4915.941682362198, 4922.9077642002, 4977.9751101244265, 83372.16627833765, 85134.0, 92155.53922230346, 93399.0, 94670.0, 83193.0, 84922.0, 85781.71327430094, 86041.0, 86345.5682721595, 72766.63616681883, 72985.90521142186, 73178.26821731821, 73437.6328118356, 75025.62487187529, 9460.858087128836, 9560.378575392577, 9567.473788941676, 9574.569144388659, 9614.18279446255, 50053.74973125257, 50386.74033149201, 51417.601151878516, 51519.71200720012, 51871.96256074933, 60136.79726405535, 61611.38388616056, 61960.69019654873, 62681.0, 63345.683271583344, 10686.946565267124, 11472.369019703996, 11505.884941150482, 11568.710782230512, 11968.940155299517, 11849.881501185222, 12702.30137342455, 12806.295653739135, 12914.935425322814, 13398.799018014932, 10491.108255798343, 11586.826197606873, 12041.397930103634, 12142.210756300812, 12254.4485498151, 11642.94178529102, 11933.224340417844, 12032.217905836093, 12359.505619775467, 12486.250824950532, 11664.708382290512, 11778.469968851594, 11828.526858925547, 11853.5851245206, 12220.45007974661, 9387.29595280343, 10056.949715251378, 10157.288989770645, 10528.578856845947, 10566.524506397383, 9144.542772861198, 9299.0, 9553.761155971157, 9571.37786043905, 9629.132821620917], "value": 7225.086414942735, "unit": "score"}], "name": "dromaeo_css", "value": 7095.884033947609, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 113.0, 113.0, 113.0, 115.0, 110.0, 113.0, 115.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 108.0, 105.0, 141.0, 103.0, 108.0, 107.0, 107.0, 129.0, 107.0], "value": 115.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 181.0, 182.0, 184.0, 182.0, 183.0, 181.0, 207.0, 261.0, 183.0], "value": 193.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 58.0, 86.0, 59.0, 59.0, 59.0, 57.0, 89.0, 81.0], "value": 68.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 86.0, 70.0, 69.0, 70.0, 83.0, 69.0, 70.0, 85.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 84.0, 88.0, 85.0, 89.0, 86.0, 90.0, 129.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 99.0, 97.0, 95.0, 102.0, 162.0, 96.0, 100.0, 103.0], "value": 104.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 86.0, 88.0, 87.0, 85.0, 87.0, 84.0, 87.0, 85.0], "value": 86.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 55.0, 55.0, 57.0, 56.0, 55.0, 56.0, 56.0, 57.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 70.0, 74.0, 76.0, 76.0, 71.0, 71.0, 74.0, 73.0, 74.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 94.0, 107.0, 105.0, 105.0, 89.0, 85.0, 87.0, 90.0, 86.0], "value": 93.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 139.0, 138.0, 138.0, 136.0, 128.0, 163.0, 203.0, 184.0], "value": 150.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 170.0, 170.0, 172.0, 169.0, 201.0, 221.0, 230.0, 170.0], "value": 185.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 83.0, 88.0, 85.0, 71.0, 67.0, 66.0, 66.0, 67.0], "value": 73.1, "unit": "score"}], "name": "kraken", "value": 1481.2, "alertThreshold": 2.0}]} 19:16:36 INFO - Return code: 0 19:16:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:16:36 INFO - # TBPL SUCCESS # 19:16:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:16:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:16:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:16:36 INFO - 2017-01-05 19:16:36.277 screenresolution[1656:9970] starting screenresolution argv=screenresolution get 19:16:36 INFO - 2017-01-05 19:16:36.279 screenresolution[1656:9970] Display 0: 1600x1200x32@60 19:16:36 INFO - 2017-01-05 19:16:36.285 screenresolution[1657:9974] starting screenresolution argv=screenresolution list 19:16:36 INFO - Available Modes on Display 0 19:16:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:16:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:16:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:16:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:16:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:16:36 INFO - Intel Iris: 19:16:36 INFO - Chipset Model: Intel Iris 19:16:36 INFO - Type: GPU 19:16:36 INFO - Bus: Built-In 19:16:36 INFO - VRAM (Dynamic, Max): 1536 MB 19:16:36 INFO - Vendor: Intel (0x8086) 19:16:36 INFO - Device ID: 0x0a2e 19:16:36 INFO - Revision ID: 0x0009 19:16:36 INFO - Displays: 19:16:36 INFO - Display: 19:16:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:16:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:16:36 INFO - Display Serial Number: 1600x1200 60 19:16:36 INFO - Main Display: Yes 19:16:36 INFO - Mirror: Off 19:16:36 INFO - Online: Yes 19:16:36 INFO - Rotation: Supported 19:16:36 INFO - Return code: 0 19:16:36 INFO - Running post-action listener: _resource_record_post_action 19:16:36 INFO - [mozharness: 2017-01-06 03:16:36.526619Z] Finished run-tests step (success) 19:16:36 INFO - Running post-run listener: _resource_record_post_run 19:16:36 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 37336064; Write bytes: 551099904; Read time: 239; Write time: 1642 19:16:36 INFO - TinderboxPrint: CPU usage
53.8% 19:16:36 INFO - TinderboxPrint: I/O read bytes / time
37,336,064 / 239 19:16:36 INFO - TinderboxPrint: I/O write bytes / time
551,099,904 / 1,642 19:16:36 INFO - TinderboxPrint: CPU idle
2,223.2 (46.2%) 19:16:36 INFO - TinderboxPrint: CPU system
964.1 (20.0%) 19:16:36 INFO - TinderboxPrint: CPU user
1,622.4 (33.7%) 19:16:36 INFO - TinderboxPrint: Swap in / out
287,019,008 / 0 19:16:36 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250741248; Write bytes: 252442624; Read time: 18149; Write time: 536 19:16:36 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 33936384; Write bytes: 295837696; Read time: 210; Write time: 1095 19:16:36 INFO - Running post-run listener: _upload_blobber_files 19:16:36 INFO - Blob upload gear active. 19:16:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:16:36 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 19:16:36 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'] 19:16:36 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 19:16:37 INFO - (blobuploader) - INFO - Open directory for files ... 19:16:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:16:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:16:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:16:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:16:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:16:38 INFO - (blobuploader) - INFO - Done attempting. 19:16:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:16:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:16:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:16:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:16:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:16:40 INFO - (blobuploader) - INFO - Done attempting. 19:16:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:16:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:16:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:16:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:16:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:16:41 INFO - (blobuploader) - INFO - Done attempting. 19:16:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:16:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:16:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:16:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:16:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:16:43 INFO - (blobuploader) - INFO - Done attempting. 19:16:43 INFO - (blobuploader) - INFO - Iteration through files over. 19:16:43 INFO - Return code: 0 19:16:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:16:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:16:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81ac9b4b7f313fbe5ea8840b94ab3c972aef5709107daa184a4d06a823564f29d70623831fe05db74e4ce51b23136b5eb11ef7dbe59281377ef5cf0e0c5fc091", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2173b550ee3fe59c1ddff570194666228242c2e326a504876b6f206d7cabcad1dab99481aa5aa32c6e651144d584df6923a0b2d98881b578c74f90571b880ad3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61b35aaa0b1f2845bcdf8dd0a53a1f20c09fa17afef2cc434aa15187cc266aa3b22733c60f4bcb8d7be26e0a47260629acd4098f86b655f8b800cd8e460e8736", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/998dc9d3adeb2ec602bc3fff3b22b6c967bffdc2d485df075b126d65cd144b1398b2510085f093091b54decd416feb45ab067aa4ef0236af2b13484252c7677c"} 19:16:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:16:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:16:43 INFO - Contents: 19:16:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/81ac9b4b7f313fbe5ea8840b94ab3c972aef5709107daa184a4d06a823564f29d70623831fe05db74e4ce51b23136b5eb11ef7dbe59281377ef5cf0e0c5fc091", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2173b550ee3fe59c1ddff570194666228242c2e326a504876b6f206d7cabcad1dab99481aa5aa32c6e651144d584df6923a0b2d98881b578c74f90571b880ad3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61b35aaa0b1f2845bcdf8dd0a53a1f20c09fa17afef2cc434aa15187cc266aa3b22733c60f4bcb8d7be26e0a47260629acd4098f86b655f8b800cd8e460e8736", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/998dc9d3adeb2ec602bc3fff3b22b6c967bffdc2d485df075b126d65cd144b1398b2510085f093091b54decd416feb45ab067aa4ef0236af2b13484252c7677c"} 19:16:43 INFO - Running post-run listener: copy_logs_to_upload_dir 19:16:43 INFO - Copying logs to upload dir... 19:16:43 INFO - mkdir: /builds/slave/test/build/upload/logs 19:16:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.206480 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2017-01-05 19:16:43.570330) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 19:16:43.573421) ========= 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.9v8IGTuwlI/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.mmCg8MUUIE/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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/81ac9b4b7f313fbe5ea8840b94ab3c972aef5709107daa184a4d06a823564f29d70623831fe05db74e4ce51b23136b5eb11ef7dbe59281377ef5cf0e0c5fc091", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2173b550ee3fe59c1ddff570194666228242c2e326a504876b6f206d7cabcad1dab99481aa5aa32c6e651144d584df6923a0b2d98881b578c74f90571b880ad3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61b35aaa0b1f2845bcdf8dd0a53a1f20c09fa17afef2cc434aa15187cc266aa3b22733c60f4bcb8d7be26e0a47260629acd4098f86b655f8b800cd8e460e8736", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/998dc9d3adeb2ec602bc3fff3b22b6c967bffdc2d485df075b126d65cd144b1398b2510085f093091b54decd416feb45ab067aa4ef0236af2b13484252c7677c"} build_url:https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010934 build_url: 'https://queue.taskcluster.net/v1/task/TpsRAfyBQRmFSJfhyzIO5A/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/81ac9b4b7f313fbe5ea8840b94ab3c972aef5709107daa184a4d06a823564f29d70623831fe05db74e4ce51b23136b5eb11ef7dbe59281377ef5cf0e0c5fc091", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/2173b550ee3fe59c1ddff570194666228242c2e326a504876b6f206d7cabcad1dab99481aa5aa32c6e651144d584df6923a0b2d98881b578c74f90571b880ad3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/61b35aaa0b1f2845bcdf8dd0a53a1f20c09fa17afef2cc434aa15187cc266aa3b22733c60f4bcb8d7be26e0a47260629acd4098f86b655f8b800cd8e460e8736", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/998dc9d3adeb2ec602bc3fff3b22b6c967bffdc2d485df075b126d65cd144b1398b2510085f093091b54decd416feb45ab067aa4ef0236af2b13484252c7677c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 19:16:43.606705) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 19:16:43.607034) ========= 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.9v8IGTuwlI/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.mmCg8MUUIE/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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.005029 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 19:16:43.655846) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 19:16:43.656686) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 19:16:43.656999) ========= ========= Total master_lag: 0.18 =========