builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0078 starttime: 1483974926.91 results: success (0) buildid: 20170109063807 builduid: c4c19be52d6a4b99bb24425502dd204c revision: 2977ca1224525680cbfb5c3ce3018818b6dfd8f2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 07:15:26.906163) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 07:15:26.906648) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 07:15:26.906957) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 07:15:26.934631) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 07:15:26.935099) ========= 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.M9aVhBGul7/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.VTGLgvMVUV/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-09 07:15:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 26.4M=0s 2017-01-09 07:15:27 (26.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.485764 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 07:15:27.447764) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 07:15:27.448315) ========= 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.M9aVhBGul7/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.VTGLgvMVUV/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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.028810 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 07:15:27.500982) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 07:15:27.501403) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2977ca1224525680cbfb5c3ce3018818b6dfd8f2 --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 2977ca1224525680cbfb5c3ce3018818b6dfd8f2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.M9aVhBGul7/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.VTGLgvMVUV/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-09 07:15:27,604 truncating revision to first 12 chars 2017-01-09 07:15:27,604 Setting DEBUG logging. 2017-01-09 07:15:27,604 attempt 1/10 2017-01-09 07:15:27,604 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2977ca122452?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 07:15:28,478 unpacking tar archive at: mozilla-central-2977ca122452/testing/mozharness/ program finished with exit code 0 elapsedTime=1.147642 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-09 07:15:28.683430) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 07:15:28.683869) ========= script_repo_revision: 2977ca1224525680cbfb5c3ce3018818b6dfd8f2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 07:15:28.684405) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 07:15:28.684776) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 07:15:28.705693) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-09 07:15:28.706100) ========= /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.M9aVhBGul7/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.VTGLgvMVUV/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 07:15:28 INFO - MultiFileLogger online at 20170109 07:15:28 in /builds/slave/test 07:15:28 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 07:15:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:15:28 INFO - {'append_to_log': False, 07:15:28 INFO - 'base_work_dir': '/builds/slave/test', 07:15:28 INFO - 'blob_upload_branch': 'Firefox', 07:15:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:15:28 INFO - 'branch': 'Firefox', 07:15:28 INFO - 'buildbot_json_path': 'buildprops.json', 07:15:28 INFO - 'config_files': ('talos/mac_config.py',), 07:15:28 INFO - 'default_actions': ('clobber', 07:15:28 INFO - 'read-buildbot-config', 07:15:28 INFO - 'download-and-extract', 07:15:28 INFO - 'populate-webroot', 07:15:28 INFO - 'create-virtualenv', 07:15:28 INFO - 'install', 07:15:28 INFO - 'run-tests'), 07:15:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:15:28 INFO - 'download_minidump_stackwalk': True, 07:15:28 INFO - 'download_symbols': 'ondemand', 07:15:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:15:28 INFO - 'tooltool.py': '/tools/tooltool.py', 07:15:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:15:28 INFO - '/tools/misc-python/virtualenv.py')}, 07:15:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:15:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:15:28 INFO - 'installer_path': 'installer.exe', 07:15:28 INFO - 'log_level': 'info', 07:15:28 INFO - 'log_name': 'talos', 07:15:28 INFO - 'log_to_console': True, 07:15:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:15:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:15:28 INFO - 'opt_config_files': (), 07:15:28 INFO - 'pip_index': False, 07:15:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:15:28 INFO - 'cmd': ('bash', 07:15:28 INFO - '-c', 07:15:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:15:28 INFO - 'enabled': True, 07:15:28 INFO - 'halt_on_failure': False, 07:15:28 INFO - 'name': 'check_screen_resolution'},), 07:15:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:15:28 INFO - 'cmd': ('bash', 07:15:28 INFO - '-c', 07:15:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:15:28 INFO - 'enabled': True, 07:15:28 INFO - 'halt_on_failure': False, 07:15:28 INFO - 'name': 'check_screen_resolution'},), 07:15:28 INFO - 'run_cmd_checks_enabled': True, 07:15:28 INFO - 'sps_profile': False, 07:15:28 INFO - 'sps_profile_interval': 0, 07:15:28 INFO - 'suite': 'dromaeojs', 07:15:28 INFO - 'system_bits': '32', 07:15:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:15:28 INFO - 'title': 't-yosemite-r7-0078', 07:15:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:15:28 INFO - 'use_talos_json': True, 07:15:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:15:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:15:28 INFO - 'work_dir': 'build'} 07:15:28 INFO - [mozharness: 2017-01-09 15:15:28.911672Z] Running clobber step. 07:15:28 INFO - Running pre-action listener: _resource_record_pre_action 07:15:28 INFO - Running main action method: clobber 07:15:28 INFO - rmtree: /builds/slave/test/build 07:15:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:15:31 INFO - Running post-action listener: _resource_record_post_action 07:15:31 INFO - [mozharness: 2017-01-09 15:15:31.337054Z] Finished clobber step (success) 07:15:31 INFO - [mozharness: 2017-01-09 15:15:31.337192Z] Running read-buildbot-config step. 07:15:31 INFO - Running pre-action listener: _resource_record_pre_action 07:15:31 INFO - Running main action method: read_buildbot_config 07:15:31 INFO - Using buildbot properties: 07:15:31 INFO - { 07:15:31 INFO - "project": "", 07:15:31 INFO - "product": "firefox", 07:15:31 INFO - "script_repo_revision": "production", 07:15:31 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 07:15:31 INFO - "repository": "", 07:15:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 07:15:31 INFO - "buildid": "20170109063807", 07:15:31 INFO - "pgo_build": "False", 07:15:31 INFO - "basedir": "/builds/slave/test", 07:15:31 INFO - "buildnumber": 0, 07:15:31 INFO - "slavename": "t-yosemite-r7-0078", 07:15:31 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 07:15:31 INFO - "platform": "yosemite_r7", 07:15:31 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 07:15:31 INFO - "branch": "mozilla-central", 07:15:31 INFO - "repo_path": "mozilla-central", 07:15:31 INFO - "revision": "2977ca1224525680cbfb5c3ce3018818b6dfd8f2", 07:15:31 INFO - "stage_platform": "macosx64", 07:15:31 INFO - "builduid": "c4c19be52d6a4b99bb24425502dd204c", 07:15:31 INFO - "slavebuilddir": "test" 07:15:31 INFO - } 07:15:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 07:15:31 INFO - Running post-action listener: _resource_record_post_action 07:15:31 INFO - [mozharness: 2017-01-09 15:15:31.342983Z] Finished read-buildbot-config step (success) 07:15:31 INFO - [mozharness: 2017-01-09 15:15:31.343214Z] Running download-and-extract step. 07:15:31 INFO - Running pre-action listener: _resource_record_pre_action 07:15:31 INFO - Running main action method: download_and_extract 07:15:31 INFO - mkdir: /builds/slave/test/build/tests 07:15: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')]} 07:15:31 INFO - trying https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 07:15:31 INFO - Downloading https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/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 07:15:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/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 07:15:32 INFO - Downloaded 1199 bytes. 07:15:32 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 07:15:32 INFO - Using the following test package requirements: 07:15:32 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:15:32 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:15:32 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 07:15:32 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:15:32 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 07:15:32 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:15:32 INFO - u'jsshell-mac.zip'], 07:15:32 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:15:32 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 07:15:32 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 07:15:32 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:15:32 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 07:15:32 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:15:32 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 07:15:32 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:15:32 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 07:15:32 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 07:15:32 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 07:15:32 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:15:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:15:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:15:32 INFO - Fetch https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:15:33 INFO - Expected file size: 16304887 07:15:33 INFO - Obtained file size: 16304887 07:15:40 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 07:15:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 07:15:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:15:40 INFO - Fetch https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 07:15:41 INFO - Expected file size: 16304887 07:15:41 INFO - Obtained file size: 16304887 07:15:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 07:15:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:15:47 INFO - Fetch https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 07:15:48 INFO - Expected file size: 14403537 07:15:48 INFO - Obtained file size: 14403537 07:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:15:48 INFO - trying https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:15:48 INFO - Downloading https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 07:15:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/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 07:15:50 INFO - Downloaded 69771121 bytes. 07:15:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:15:50 INFO - mkdir: /builds/slave/test/properties 07:15:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:15:50 INFO - Writing to file /builds/slave/test/properties/build_url 07:15:50 INFO - Contents: 07:15:50 INFO - build_url:https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 07:15:51 INFO - Running post-action listener: _resource_record_post_action 07:15:51 INFO - Running post-action listener: set_extra_try_arguments 07:15:51 INFO - [mozharness: 2017-01-09 15:15:51.341326Z] Finished download-and-extract step (success) 07:15:51 INFO - [mozharness: 2017-01-09 15:15:51.341448Z] Running populate-webroot step. 07:15:51 INFO - Running pre-action listener: _resource_record_pre_action 07:15:51 INFO - Running main action method: populate_webroot 07:15:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:15:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:15:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:15:51 INFO - '--tppagecycles', 07:15:51 INFO - '7'], 07:15:51 INFO - 'tests': ['tsvgm']}}, 07:15:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:15:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:15:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:15:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:15:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:15:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:15:51 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:15:51 INFO - 'talos_options': ['--disable-e10s'], 07:15:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:15:51 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:15:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:15:51 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:15:51 INFO - 'talos_options': ['--disable-e10s'], 07:15:51 INFO - 'tests': ['damp', 'tps']}, 07:15:51 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:15:51 INFO - 'tests': ['damp', 'tps']}, 07:15:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:15:51 INFO - 'tests': ['dromaeo_dom']}, 07:15:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:15:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:15:51 INFO - 'tests': ['basic_compositor_video']}, 07:15:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:15:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:15:51 INFO - 'tests': ['a11yr', 07:15:51 INFO - 'ts_paint', 07:15:51 INFO - 'tpaint', 07:15:51 INFO - 'sessionrestore', 07:15:51 INFO - 'sessionrestore_no_auto_restore', 07:15:51 INFO - 'tabpaint']}, 07:15:51 INFO - 'other-e10s': {'tests': ['a11yr', 07:15:51 INFO - 'ts_paint', 07:15:51 INFO - 'tpaint', 07:15:51 INFO - 'sessionrestore', 07:15:51 INFO - 'sessionrestore_no_auto_restore', 07:15:51 INFO - 'tabpaint']}, 07:15:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:15:51 INFO - 'ts_paint', 07:15:51 INFO - 'tpaint', 07:15:51 INFO - 'sessionrestore', 07:15:51 INFO - 'sessionrestore_no_auto_restore', 07:15:51 INFO - 'tabpaint']}, 07:15:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:15:51 INFO - 'ts_paint', 07:15:51 INFO - 'tpaint', 07:15:51 INFO - 'sessionrestore', 07:15:51 INFO - 'sessionrestore_no_auto_restore', 07:15:51 INFO - 'tabpaint']}, 07:15:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:15:51 INFO - 'tests': ['a11yr', 07:15:51 INFO - 'ts_paint', 07:15:51 INFO - 'tpaint', 07:15:51 INFO - 'sessionrestore', 07:15:51 INFO - 'sessionrestore_no_auto_restore', 07:15:51 INFO - 'tabpaint']}, 07:15:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:15:51 INFO - 'tests': ['a11yr', 07:15:51 INFO - 'ts_paint', 07:15:51 INFO - 'tpaint', 07:15:51 INFO - 'sessionrestore', 07:15:51 INFO - 'sessionrestore_no_auto_restore', 07:15:51 INFO - 'tabpaint']}, 07:15:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:15:51 INFO - 'tests': ['tsvgx', 07:15:51 INFO - 'tsvgr_opacity', 07:15:51 INFO - 'tart', 07:15:51 INFO - 'tscrollx', 07:15:51 INFO - 'cart', 07:15:51 INFO - 'tsvg_static']}, 07:15:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:15:51 INFO - 'tsvgr_opacity', 07:15:51 INFO - 'tart', 07:15:51 INFO - 'tscrollx', 07:15:51 INFO - 'cart', 07:15:51 INFO - 'tsvg_static']}, 07:15:51 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:15:51 INFO - 'talos_options': ['--disable-e10s'], 07:15:51 INFO - 'tests': ['tp5o']}, 07:15:51 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:15:51 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:15:51 INFO - 'talos_options': ['--disable-e10s', 07:15:51 INFO - '--xperf_path', 07:15:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:15:51 INFO - 'tests': ['tp5n']}, 07:15:51 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:15:51 INFO - 'talos_options': ['--xperf_path', 07:15:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:15:51 INFO - 'tests': ['tp5n']}}, 07:15:51 INFO - 'talos.zip': {'path': '', 07:15:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:15:51 INFO - Running post-action listener: _resource_record_post_action 07:15:51 INFO - [mozharness: 2017-01-09 15:15:51.359332Z] Finished populate-webroot step (success) 07:15:51 INFO - [mozharness: 2017-01-09 15:15:51.359448Z] Running create-virtualenv step. 07:15:51 INFO - Running pre-action listener: _resource_record_pre_action 07:15:51 INFO - Running main action method: create_virtualenv 07:15:51 INFO - Creating virtualenv /builds/slave/test/build/venv 07:15:51 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 07:15:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:15:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:15:51 INFO - Using real prefix '/tools/python27' 07:15:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:15:52 INFO - Installing distribute.............................................................................................................................................................................................done. 07:15:55 INFO - Installing pip.................done. 07:15:55 INFO - Return code: 0 07:15:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:15:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:15:55 INFO - Reading from file tmpfile_stdout 07:15:55 INFO - Output received: 07:15:55 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) 07:15:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:15: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')]} 07:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:15:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:15: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')]} 07:15:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:15:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:15:55 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 0x10cb26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5934b3730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904d30>, '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 0x10c82bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9be8>, '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.M9aVhBGul7/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.VTGLgvMVUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 07:15:55 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 07:15:55 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 07:15:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M9aVhBGul7/Render', 07:15:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:15:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:15:55 INFO - 'HOME': '/Users/cltbld', 07:15:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:15:55 INFO - 'LOGNAME': 'cltbld', 07:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:15:55 INFO - 'MOZ_NO_REMOTE': '1', 07:15:55 INFO - 'NO_EM_RESTART': '1', 07:15:55 INFO - 'PAGER': '/bin/cat', 07:15:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:15:55 INFO - 'PWD': '/builds/slave/test', 07:15:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:15:55 INFO - 'SHELL': '/bin/bash', 07:15:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VTGLgvMVUV/Listeners', 07:15:55 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 07:15:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:15:55 INFO - 'USER': 'cltbld', 07:15:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:15:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:15:55 INFO - 'XPC_FLAGS': '0x0', 07:15:55 INFO - 'XPC_SERVICE_NAME': '0', 07:15:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:15:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:15:55 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 07:15:55 INFO - Cleaning up... 07:15:55 INFO - Return code: 0 07:15:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:15: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')]} 07:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:15:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:15: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')]} 07:15:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:15:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:15:55 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 0x10cb26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5934b3730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904d30>, '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 0x10c82bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9be8>, '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.M9aVhBGul7/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.VTGLgvMVUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 07:15:55 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 07:15:55 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 07:15:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M9aVhBGul7/Render', 07:15:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:15:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:15:55 INFO - 'HOME': '/Users/cltbld', 07:15:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:15:55 INFO - 'LOGNAME': 'cltbld', 07:15:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:15:55 INFO - 'MOZ_NO_REMOTE': '1', 07:15:55 INFO - 'NO_EM_RESTART': '1', 07:15:55 INFO - 'PAGER': '/bin/cat', 07:15:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:15:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:15:55 INFO - 'PWD': '/builds/slave/test', 07:15:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:15:55 INFO - 'SHELL': '/bin/bash', 07:15:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VTGLgvMVUV/Listeners', 07:15:55 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 07:15:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:15:55 INFO - 'USER': 'cltbld', 07:15:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:15:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:15:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:15:55 INFO - 'XPC_FLAGS': '0x0', 07:15:55 INFO - 'XPC_SERVICE_NAME': '0', 07:15:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:15:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:15:55 INFO - Downloading/unpacking psutil>=3.1.1 07:15:55 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 07:15:55 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 07:15:55 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 07:15:55 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 07:15:55 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 07:15:55 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 07:15:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:15:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:15:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:15:59 INFO - Installing collected packages: psutil 07:15:59 INFO - Running setup.py install for psutil 07:15:59 INFO - building 'psutil._psutil_osx' extension 07:15:59 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 07:16:00 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 07:16:00 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 07:16:00 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 07:16:00 INFO - building 'psutil._psutil_posix' extension 07:16:00 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 07:16:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:16:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:16:00 INFO - ^ 07:16:00 INFO - 1 warning generated. 07:16:00 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 07:16:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:16:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:16:00 INFO - Successfully installed psutil 07:16:00 INFO - Cleaning up... 07:16:01 INFO - Return code: 0 07:16:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:16:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cb26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5934b3730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904d30>, '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 0x10c82bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9be8>, '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.M9aVhBGul7/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.VTGLgvMVUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 07:16:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:16:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:16:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M9aVhBGul7/Render', 07:16:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:01 INFO - 'HOME': '/Users/cltbld', 07:16:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:01 INFO - 'LOGNAME': 'cltbld', 07:16:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:01 INFO - 'MOZ_NO_REMOTE': '1', 07:16:01 INFO - 'NO_EM_RESTART': '1', 07:16:01 INFO - 'PAGER': '/bin/cat', 07:16:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:01 INFO - 'PWD': '/builds/slave/test', 07:16:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:01 INFO - 'SHELL': '/bin/bash', 07:16:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VTGLgvMVUV/Listeners', 07:16:01 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 07:16:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:01 INFO - 'USER': 'cltbld', 07:16:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:01 INFO - 'XPC_FLAGS': '0x0', 07:16:01 INFO - 'XPC_SERVICE_NAME': '0', 07:16:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:16:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:16:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:16:04 INFO - Installing collected packages: mozsystemmonitor 07:16:04 INFO - Running setup.py install for mozsystemmonitor 07:16:04 INFO - Successfully installed mozsystemmonitor 07:16:04 INFO - Cleaning up... 07:16:04 INFO - Return code: 0 07:16:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:16: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')]} 07:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16: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')]} 07:16:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16: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', '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 0x10cb26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5934b3730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904d30>, '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 0x10c82bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9be8>, '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.M9aVhBGul7/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.VTGLgvMVUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 07:16: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:16: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 jsonschema==2.5.1 07:16:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M9aVhBGul7/Render', 07:16:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:04 INFO - 'HOME': '/Users/cltbld', 07:16:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:04 INFO - 'LOGNAME': 'cltbld', 07:16:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:04 INFO - 'MOZ_NO_REMOTE': '1', 07:16:04 INFO - 'NO_EM_RESTART': '1', 07:16:04 INFO - 'PAGER': '/bin/cat', 07:16:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:04 INFO - 'PWD': '/builds/slave/test', 07:16:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:04 INFO - 'SHELL': '/bin/bash', 07:16:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VTGLgvMVUV/Listeners', 07:16:04 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 07:16:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:04 INFO - 'USER': 'cltbld', 07:16:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:04 INFO - 'XPC_FLAGS': '0x0', 07:16:04 INFO - 'XPC_SERVICE_NAME': '0', 07:16:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:04 INFO - Downloading/unpacking jsonschema==2.5.1 07:16: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 07:16: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 07:16: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 07:16: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 07:16: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 07:16: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 07:16:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:16:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:07 INFO - Downloading functools32-3.2.3-2.tar.gz 07:16:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:16:07 INFO - warning: no files found matching '*.txt' 07:16:07 INFO - no previously-included directories found matching 'build' 07:16:07 INFO - no previously-included directories found matching 'dist' 07:16:07 INFO - no previously-included directories found matching '.git*' 07:16:07 INFO - Installing collected packages: jsonschema, functools32 07:16:07 INFO - Running setup.py install for functools32 07:16:07 INFO - warning: no files found matching '*.txt' 07:16:07 INFO - no previously-included directories found matching 'build' 07:16:07 INFO - no previously-included directories found matching 'dist' 07:16:07 INFO - no previously-included directories found matching '.git*' 07:16:07 INFO - Successfully installed jsonschema functools32 07:16:07 INFO - Cleaning up... 07:16:07 INFO - Return code: 0 07:16:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:16:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:16:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cb26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5934b3730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904d30>, '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 0x10c82bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9be8>, '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.M9aVhBGul7/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.VTGLgvMVUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 07:16:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:16:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:16:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M9aVhBGul7/Render', 07:16:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:07 INFO - 'HOME': '/Users/cltbld', 07:16:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:07 INFO - 'LOGNAME': 'cltbld', 07:16:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:07 INFO - 'MOZ_NO_REMOTE': '1', 07:16:07 INFO - 'NO_EM_RESTART': '1', 07:16:07 INFO - 'PAGER': '/bin/cat', 07:16:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:07 INFO - 'PWD': '/builds/slave/test', 07:16:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:07 INFO - 'SHELL': '/bin/bash', 07:16:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VTGLgvMVUV/Listeners', 07:16:07 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 07:16:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:07 INFO - 'USER': 'cltbld', 07:16:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:07 INFO - 'XPC_FLAGS': '0x0', 07:16:07 INFO - 'XPC_SERVICE_NAME': '0', 07:16:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:16:08 INFO - Cleaning up... 07:16:08 INFO - Return code: 0 07:16:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:16:08 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')]} 07:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:08 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')]} 07:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:08 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 0x10cb26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5934b3730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904d30>, '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 0x10c82bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9be8>, '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.M9aVhBGul7/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.VTGLgvMVUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 07:16:08 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 07:16:08 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 07:16:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M9aVhBGul7/Render', 07:16:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:08 INFO - 'HOME': '/Users/cltbld', 07:16:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:08 INFO - 'LOGNAME': 'cltbld', 07:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:08 INFO - 'MOZ_NO_REMOTE': '1', 07:16:08 INFO - 'NO_EM_RESTART': '1', 07:16:08 INFO - 'PAGER': '/bin/cat', 07:16:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:08 INFO - 'PWD': '/builds/slave/test', 07:16:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:08 INFO - 'SHELL': '/bin/bash', 07:16:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VTGLgvMVUV/Listeners', 07:16:08 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 07:16:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:08 INFO - 'USER': 'cltbld', 07:16:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:08 INFO - 'XPC_FLAGS': '0x0', 07:16:08 INFO - 'XPC_SERVICE_NAME': '0', 07:16:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:08 INFO - Downloading/unpacking blobuploader==1.2.4 07:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:16:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:16:10 INFO - Downloading blobuploader-1.2.4.tar.gz 07:16:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:16:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:16:11 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 07:16:11 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 07:16:11 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 07:16:11 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 07:16:11 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 07:16:11 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 07:16:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:16:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:16:11 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 07:16:11 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 07:16:11 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 07:16:11 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 07:16:11 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 07:16:11 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 07:16:12 INFO - Downloading docopt-0.6.1.tar.gz 07:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:16:12 INFO - Installing collected packages: blobuploader, requests, docopt 07:16:12 INFO - Running setup.py install for blobuploader 07:16:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:16:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:16:12 INFO - Running setup.py install for requests 07:16:13 INFO - Running setup.py install for docopt 07:16:13 INFO - Successfully installed blobuploader requests docopt 07:16:13 INFO - Cleaning up... 07:16:13 INFO - Return code: 0 07:16:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:16:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:16:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:13 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 0x10cb26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5934b3730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904d30>, '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 0x10c82bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9be8>, '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.M9aVhBGul7/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.VTGLgvMVUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 07:16:13 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 07:16:13 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 07:16:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M9aVhBGul7/Render', 07:16:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:13 INFO - 'HOME': '/Users/cltbld', 07:16:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:13 INFO - 'LOGNAME': 'cltbld', 07:16:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:13 INFO - 'MOZ_NO_REMOTE': '1', 07:16:13 INFO - 'NO_EM_RESTART': '1', 07:16:13 INFO - 'PAGER': '/bin/cat', 07:16:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:13 INFO - 'PWD': '/builds/slave/test', 07:16:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:13 INFO - 'SHELL': '/bin/bash', 07:16:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VTGLgvMVUV/Listeners', 07:16:13 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 07:16:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:13 INFO - 'USER': 'cltbld', 07:16:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:13 INFO - 'XPC_FLAGS': '0x0', 07:16:13 INFO - 'XPC_SERVICE_NAME': '0', 07:16:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:16:13 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-xNxUK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:16:13 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-3FFqEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:16:13 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-rtpuYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:16:13 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-BAiQGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:16:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:16:13 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-8IXKxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:16:14 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-NqFQDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:16:14 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-FwaCtk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:16:14 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-PPIhCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:16:14 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-cBstZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:16:14 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-89L3DX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:16:14 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-d1u7MO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:16:14 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-Lt3a8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:16:14 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-K0rSGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:16:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:16:14 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-bAqw0I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:15 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-bXafP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:16:15 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-yLKclv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:16:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:16:15 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-4MJ0LL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:16:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:16:15 INFO - Running setup.py install for manifestparser 07:16:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:16:15 INFO - Running setup.py install for mozcrash 07:16:15 INFO - Running setup.py install for mozdebug 07:16:15 INFO - Running setup.py install for mozdevice 07:16:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:16:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:16:15 INFO - Running setup.py install for mozfile 07:16:16 INFO - Running setup.py install for mozhttpd 07:16:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:16:16 INFO - Running setup.py install for mozinfo 07:16:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:16:16 INFO - Running setup.py install for mozInstall 07:16:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:16:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:16:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:16:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:16:16 INFO - Running setup.py install for mozleak 07:16:16 INFO - Running setup.py install for mozlog 07:16:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:16:16 INFO - Running setup.py install for moznetwork 07:16:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:16:16 INFO - Running setup.py install for mozprocess 07:16:17 INFO - Running setup.py install for mozprofile 07:16:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:16:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:16:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:16:17 INFO - Running setup.py install for mozrunner 07:16:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:16:17 INFO - Running setup.py install for mozscreenshot 07:16:17 INFO - Running setup.py install for moztest 07:16:17 INFO - Running setup.py install for mozversion 07:16:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:16:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:16:17 INFO - Cleaning up... 07:16:17 INFO - Return code: 0 07:16:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:17 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 0x10cb26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5934b3730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904d30>, '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 0x10c82bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9be8>, '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.M9aVhBGul7/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.VTGLgvMVUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 07:16:17 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 07:16:17 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 07:16:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M9aVhBGul7/Render', 07:16:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:17 INFO - 'HOME': '/Users/cltbld', 07:16:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:17 INFO - 'LOGNAME': 'cltbld', 07:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:17 INFO - 'MOZ_NO_REMOTE': '1', 07:16:17 INFO - 'NO_EM_RESTART': '1', 07:16:17 INFO - 'PAGER': '/bin/cat', 07:16:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:17 INFO - 'PWD': '/builds/slave/test', 07:16:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:17 INFO - 'SHELL': '/bin/bash', 07:16:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VTGLgvMVUV/Listeners', 07:16:17 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 07:16:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:17 INFO - 'USER': 'cltbld', 07:16:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:17 INFO - 'XPC_FLAGS': '0x0', 07:16:17 INFO - 'XPC_SERVICE_NAME': '0', 07:16:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:16:18 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-9aQv27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:16:18 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)) 07:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:16:18 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-snmwiw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:16:18 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)) 07:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:16:18 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-LS8Zhy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:16:18 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)) 07:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:16:18 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-xE3y31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:16:18 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)) 07:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:16:18 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-zpEGnf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:16:18 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)) 07:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:16:18 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-ovcYaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:16:18 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)) 07:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:16:18 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-EZ127j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:16:18 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)) 07:16:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:16:18 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-iakL0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:16:19 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)) 07:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:16:19 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-6soWQ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:16:19 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)) 07:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:16:19 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-sYfB5n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:16:19 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)) 07:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:16:19 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-Acc_Pz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:16:19 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)) 07:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:16:19 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-ZFnvY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:16:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:16:19 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-AeAuoM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:16:19 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)) 07:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:16:19 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-PeXwX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:16:19 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)) 07:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:19 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-2GGINW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:16:19 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)) 07:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:16:19 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-Abys1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:16:19 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)) 07:16:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:16:19 INFO - Running setup.py (path:/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/pip-ysbom4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:16:19 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)) 07:16:19 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)) 07:16:19 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)) 07:16:19 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)) 07:16:19 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)) 07:16:19 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)) 07:16:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:16: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 07:16: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 07:16: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 07:16: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 07:16: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 07:16: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 07:16:22 INFO - Downloading blessings-1.6.tar.gz 07:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:16:22 INFO - Installing collected packages: blessings 07:16:22 INFO - Running setup.py install for blessings 07:16:22 INFO - Successfully installed blessings 07:16:22 INFO - Cleaning up... 07:16:22 INFO - Return code: 0 07:16:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:16:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:16:23 INFO - Reading from file tmpfile_stdout 07:16:23 INFO - Current package versions: 07:16:23 INFO - blessings == 1.6 07:16:23 INFO - blobuploader == 1.2.4 07:16:23 INFO - docopt == 0.6.1 07:16:23 INFO - functools32 == 3.2.3-2 07:16:23 INFO - jsonschema == 2.5.1 07:16:23 INFO - manifestparser == 1.1 07:16:23 INFO - mozInstall == 1.12 07:16:23 INFO - mozcrash == 1.0 07:16:23 INFO - mozdebug == 0.1 07:16:23 INFO - mozdevice == 0.49 07:16:23 INFO - mozfile == 1.2 07:16:23 INFO - mozhttpd == 0.7 07:16:23 INFO - mozinfo == 0.9 07:16:23 INFO - mozleak == 0.1 07:16:23 INFO - mozlog == 3.4 07:16:23 INFO - moznetwork == 0.27 07:16:23 INFO - mozprocess == 0.24 07:16:23 INFO - mozprofile == 0.28 07:16:23 INFO - mozrunner == 6.13 07:16:23 INFO - mozscreenshot == 0.1 07:16:23 INFO - mozsystemmonitor == 0.3 07:16:23 INFO - moztest == 0.8 07:16:23 INFO - mozversion == 1.4 07:16:23 INFO - psutil == 3.1.1 07:16:23 INFO - requests == 1.2.3 07:16:23 INFO - wsgiref == 0.1.2 07:16:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:16:23 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')]} 07:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:23 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')]} 07:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:23 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 0x10cb26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5934b3730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904d30>, '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 0x10c82bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9be8>, '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.M9aVhBGul7/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.VTGLgvMVUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 07:16:23 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 07:16:23 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 07:16:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M9aVhBGul7/Render', 07:16:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:23 INFO - 'HOME': '/Users/cltbld', 07:16:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:23 INFO - 'LOGNAME': 'cltbld', 07:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:23 INFO - 'MOZ_NO_REMOTE': '1', 07:16:23 INFO - 'NO_EM_RESTART': '1', 07:16:23 INFO - 'PAGER': '/bin/cat', 07:16:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:23 INFO - 'PWD': '/builds/slave/test', 07:16:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:23 INFO - 'SHELL': '/bin/bash', 07:16:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VTGLgvMVUV/Listeners', 07:16:23 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 07:16:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:23 INFO - 'USER': 'cltbld', 07:16:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:23 INFO - 'XPC_FLAGS': '0x0', 07:16:23 INFO - 'XPC_SERVICE_NAME': '0', 07:16:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:23 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)) 07:16:23 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)) 07:16:23 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)) 07:16:23 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)) 07:16:23 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)) 07:16:23 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)) 07:16:23 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)) 07:16:23 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)) 07:16:23 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)) 07:16:23 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)) 07:16:23 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)) 07:16:23 INFO - Cleaning up... 07:16:23 INFO - Return code: 0 07:16:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:16:23 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')]} 07:16:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:16:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:23 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')]} 07:16:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:16:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:16:23 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 0x10cb26b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cb29768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb5934b3730>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c904d30>, '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 0x10c82bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9be8>, '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.M9aVhBGul7/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.VTGLgvMVUV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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 07:16:23 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 07:16:23 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 07:16:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M9aVhBGul7/Render', 07:16:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:23 INFO - 'HOME': '/Users/cltbld', 07:16:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:23 INFO - 'LOGNAME': 'cltbld', 07:16:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:23 INFO - 'MOZ_NO_REMOTE': '1', 07:16:23 INFO - 'NO_EM_RESTART': '1', 07:16:23 INFO - 'PAGER': '/bin/cat', 07:16:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:23 INFO - 'PWD': '/builds/slave/test', 07:16:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:23 INFO - 'SHELL': '/bin/bash', 07:16:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VTGLgvMVUV/Listeners', 07:16:23 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 07:16:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:23 INFO - 'USER': 'cltbld', 07:16:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:23 INFO - 'XPC_FLAGS': '0x0', 07:16:23 INFO - 'XPC_SERVICE_NAME': '0', 07:16:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:16:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:16:23 INFO - Cleaning up... 07:16:23 INFO - Return code: 0 07:16:23 INFO - Running post-action listener: _resource_record_post_action 07:16:23 INFO - Running post-action listener: _start_resource_monitoring 07:16:23 INFO - Starting resource monitoring. 07:16:23 INFO - [mozharness: 2017-01-09 15:16:23.827141Z] Finished create-virtualenv step (success) 07:16:23 INFO - [mozharness: 2017-01-09 15:16:23.828169Z] Running install step. 07:16:23 INFO - Running pre-action listener: _resource_record_pre_action 07:16:23 INFO - Running main action method: install 07:16:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:16:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:16:24 INFO - Reading from file tmpfile_stdout 07:16:24 INFO - Detecting whether we're running mozinstall >=1.0... 07:16:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:16:24 INFO - Reading from file tmpfile_stdout 07:16:24 INFO - Output received: 07:16:24 INFO - Usage: mozinstall [options] installer 07:16:24 INFO - Options: 07:16:24 INFO - -h, --help show this help message and exit 07:16:24 INFO - -d DEST, --destination=DEST 07:16:24 INFO - Directory to install application into. [default: 07:16:24 INFO - "/builds/slave/test"] 07:16:24 INFO - --app=APP Application being installed. [default: firefox] 07:16:24 INFO - mkdir: /builds/slave/test/build/application 07:16:24 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'] 07:16:24 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 07:16:46 INFO - Reading from file tmpfile_stdout 07:16:46 INFO - Output received: 07:16:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:16:46 INFO - Running post-action listener: _resource_record_post_action 07:16:46 INFO - [mozharness: 2017-01-09 15:16:46.404668Z] Finished install step (success) 07:16:46 INFO - [mozharness: 2017-01-09 15:16:46.404823Z] Running run-tests step. 07:16:46 INFO - Running pre-action listener: _resource_record_pre_action 07:16:46 INFO - Running main action method: run_tests 07:16:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:16:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:16:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:16:46 INFO - 2017-01-09 07:16:46.419 screenresolution[1633:8212] starting screenresolution argv=screenresolution get 07:16:46 INFO - 2017-01-09 07:16:46.422 screenresolution[1633:8212] Display 0: 1600x1200x32@60 07:16:46 INFO - 2017-01-09 07:16:46.427 screenresolution[1634:8215] starting screenresolution argv=screenresolution list 07:16:46 INFO - Available Modes on Display 0 07:16:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:16:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:16:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:16:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:16:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:16:46 INFO - Intel Iris: 07:16:46 INFO - Chipset Model: Intel Iris 07:16:46 INFO - Type: GPU 07:16:46 INFO - Bus: Built-In 07:16:46 INFO - VRAM (Dynamic, Max): 1536 MB 07:16:46 INFO - Vendor: Intel (0x8086) 07:16:46 INFO - Device ID: 0x0a2e 07:16:46 INFO - Revision ID: 0x0009 07:16:46 INFO - Displays: 07:16:46 INFO - Display: 07:16:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:16:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:16:46 INFO - Display Serial Number: 1600x1200 60 07:16:46 INFO - Main Display: Yes 07:16:46 INFO - Mirror: Off 07:16:46 INFO - Online: Yes 07:16:46 INFO - Rotation: Supported 07:16:46 INFO - Return code: 0 07:16:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:16:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:16:46 INFO - Python 2.7.3 07:16:46 INFO - Return code: 0 07:16:46 INFO - grabbing minidump binary from tooltool 07:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:16:46 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 0x10c904d30>, '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 0x10c82bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:16:46 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 07:16:46 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 07:16:46 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 07:16:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:16:46 INFO - Return code: 0 07:16:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:16:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:16:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:16:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:16:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:16:46 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/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0078', '--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 07:16:46 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/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0078 --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 07:16:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M9aVhBGul7/Render', 07:16:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:16:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:16:46 INFO - 'HOME': '/Users/cltbld', 07:16:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:16:46 INFO - 'LOGNAME': 'cltbld', 07:16:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:16:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:16:46 INFO - 'MOZ_NO_REMOTE': '1', 07:16:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:16:46 INFO - 'NO_EM_RESTART': '1', 07:16:46 INFO - 'PAGER': '/bin/cat', 07:16:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:16:46 INFO - 'PWD': '/builds/slave/test', 07:16:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:16:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:16:46 INFO - 'SHELL': '/bin/bash', 07:16:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VTGLgvMVUV/Listeners', 07:16:46 INFO - 'TMPDIR': '/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/', 07:16:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:16:46 INFO - 'USER': 'cltbld', 07:16:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:16:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:16:46 INFO - 'XPC_FLAGS': '0x0', 07:16:46 INFO - 'XPC_SERVICE_NAME': '0', 07:16:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:16:46 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/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0078', '--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 07:16:47 INFO - mozversion application_buildid: 20170109063807 07:16:47 INFO - mozversion application_changeset: 2977ca1224525680cbfb5c3ce3018818b6dfd8f2 07:16:47 INFO - mozversion application_display_name: Nightly 07:16:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:16:47 INFO - mozversion application_name: Firefox 07:16:47 INFO - mozversion application_remotingname: firefox 07:16:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 07:16:47 INFO - mozversion application_vendor: Mozilla 07:16:47 INFO - mozversion application_version: 53.0a1 07:16:47 INFO - mozversion platform_buildid: 20170109063807 07:16:47 INFO - mozversion platform_changeset: 2977ca1224525680cbfb5c3ce3018818b6dfd8f2 07:16:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 07:16:47 INFO - mozversion platform_version: 53.0a1 07:16:47 INFO - using testdate: 1483975007 07:16:47 INFO - actual date: 1483975007 07:16:47 INFO - starting webserver on 'localhost:49243' 07:16:47 INFO - SUITE-START | Running 2 tests 07:16:47 INFO - TEST-START | dromaeo_css 07:16:47 INFO - operating with platform_type : mac_ 07:16:47 INFO - Initialising browser for dromaeo_css test... 07:16:47 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmp4AdYTY/profile http://localhost:49243/getInfo.html) 07:16:47 INFO - PROCESS | 1644 | 1483975007527 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 07:16:49 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 07:16:49 INFO - PROCESS | 1644 | colorDepth:24 07:16:49 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 07:16:49 INFO - PROCESS | 1644 | __metrics 07:16:49 INFO - PROCESS | 1644 | 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" 07:16:49 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:16:50 INFO - TEST-INFO | 1644: exit 0 07:16:50 INFO - Browser initialized. 07:16:50 INFO - Running cycle 1/1 for dromaeo_css test... 07:16:50 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmp4AdYTY/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:17:02 INFO - PROCESS | 1647 | RSS: Main: 235016192 07:17:02 INFO - PROCESS | 1647 | 07:20:06 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 07:20:08 INFO - PROCESS | 1647 | RSS: Main: 324354048 07:20:08 INFO - PROCESS | 1647 | 07:21:50 INFO - PROCESS | 1647 | ************************* 07:21:50 INFO - PROCESS | 1647 | A coding exception was thrown and uncaught in a Task. 07:21:50 INFO - PROCESS | 1647 | 07:21:50 INFO - PROCESS | 1647 | Full message: ReferenceError: fetch is not defined 07:21:50 INFO - PROCESS | 1647 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 07:21:50 INFO - PROCESS | 1647 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 07:21:50 INFO - PROCESS | 1647 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 07:21:50 INFO - PROCESS | 1647 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:21:50 INFO - PROCESS | 1647 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:21:50 INFO - PROCESS | 1647 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:21:50 INFO - PROCESS | 1647 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 07:21:50 INFO - PROCESS | 1647 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 07:21:50 INFO - PROCESS | 1647 | TaskImpl@resource://gre/modules/Task.jsm:277:3 07:21:50 INFO - PROCESS | 1647 | asyncFunction@resource://gre/modules/Task.jsm:252:14 07:21:50 INFO - PROCESS | 1647 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 07:21:50 INFO - PROCESS | 1647 | 07:21:50 INFO - PROCESS | 1647 | ************************* 07:23:11 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 07:23:12 INFO - PROCESS | 1647 | RSS: Main: 367915008 07:23:12 INFO - PROCESS | 1647 | 07:26:14 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 07:26:14 INFO - PROCESS | 1647 | RSS: Main: 345374720 07:26:14 INFO - PROCESS | 1647 | 07:29:17 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 07:29:17 INFO - PROCESS | 1647 | RSS: Main: 345862144 07:29:17 INFO - PROCESS | 1647 | 07:32:21 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 07:32:21 INFO - PROCESS | 1647 | RSS: Main: 320614400 07:32:21 INFO - PROCESS | 1647 | 07:35:25 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 07:35:25 INFO - PROCESS | 1647 | RSS: Main: 376492032 07:35:25 INFO - PROCESS | 1647 | 07:35:25 INFO - PROCESS | 1647 | __start_tp_report 07:35:25 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 07:35:25 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 07:35:25 INFO - PROCESS | 1647 | |i|pagename|runs| 07:35:25 INFO - PROCESS | 1647 | |0;dojo.html;2788.637477127973;3147.5121356189784;3322.5680661514007;3330.250693593942;3344.2475443025305;5541.196526503664;5588.469095435938;5633.183188437674;5653.462921022507;5684.261046064016;5639.464250896158;5646.576506761999;5725.255716756827;5735.942640573593;5821.825345239639;5180.067587834188;5234.581233501321;5251.658642188265;5259.789608415659;5290.259363689087;5173.676318948724;5285.603579731516;5299.311089558371;5313.20301954708;5315.441878602749;4378.64970802335;5250.133727934905;5262.315898933133;5263.605229607776;5280.630355875091;5126.077306084903;5235.424103348629;5246.213068039825;5282.9207561886415;5287.365516138049;5096.821611243588;5133.255677926717;5145.794168233304;5207.973960130213;5218.817341393033;5674.744636491368;5688.488036076773;5696.772129114872;5717.056685646863;5793;6280.685965701742;6281.497480201619;6290.905636415458;6297.275813281485;6303.338149494283;6053.939460605382;6105.186826067707;6166.84582885427;6255.687215639246;6261.092141639437;5739.827805165894;5789.536837053026;5806.535477161817;5824.417558244142;5877.588568800143;6171.326057706647;6176.289726681399;6208.689565521706;6213.906791398133;6232.937670623326;6121.326654068049;6150.538709596798;6175.135481032659;6181.320054793969;6195.752169913243;5476.342838859362;5864.179014937912;6061.030235162353;6161.199044124235;6211.254649442006;6065.75736970525;6132.63204207749;6209.937900620936;6224.844378890564;6236.34518375564;6133.365267548775;6170.228721409824;6174.289956654952;6182.505399568024;6188.8762224755255;4892.315075889378;4894.559489645949;4944.950550494521;4957.851264462072;4959.057779021974;4407.140607581491;4454.4654641442585;4484.304932735431;4500.549945005473;4521.660875434355;5641.323041235078;5698.544116470755;5711;5817.360090390053;5826.359100498942;6179.104029915637;6204.255489341308;6212.658303793244;6223.626582405071;6234.1583886175695;5824.854378640568;5832.825015249549;5845.152452894306;5857.062869940874;5907.1139329100115;5329.7068661224;5404.837854864281;5408.810691625774;5435.021696094662;5471.8084867029465;6168.167297414791;6199.5660303779205;6214.533909956681;6215.160953271431;6245.250569931637;6216.689165541795;6227.626342419469;6237.157983672146;6243.719032643451;6258.12386265914;5410.639593503182;6260.342664020212;6263.248410190807;6283.497320214463;6297.716602752978;6067.908981365372;6110.012029148408;6124.571280010535;6127.372786728474;6239.594426362272;5062.341895553554;6271.874562508816;6282.403171698681;6289.559730818799;6304.5271604628915;6154.630722156685;6157.230346206724;6158.199434073542;6159.938400615936;6161.4146656067605;5870.53035757147;5999.430054144849;6022.066579680157;6098;6138.723757431016;5941.168236446814;5998.610090344114;6048.3649216833355;6084.847878803065;6105.45050945417;5959.612625179349;6179.28938172116;6181.567290289637;6209.130721699056;6211.2857021443115;5794.507466865195;5856.3558008618165;5911.379305172895;5922.7927022554;5989.520838332835 07:35:25 INFO - PROCESS | 1647 | |1;ext.html;439.28615999001624;518.9584833213341;542.5930552085936;553.2586334312022;573.0802062689382;34382.656173438256;38119.80940095299;40647.99999999996;40740;40927.59072409275;40555.594444055554;41043.794781026125;41079.58920410795;41182.970425739375;41392;8405.579721013955;8687.305015598737;8704;9242.35303528755;9458;8601.483910965331;8698.347623928199;10983.231173817836;11276.32342059479;11311.604093856717;11294.265872718248;11384.829227561593;11388.54445822166;11402.885971140266;11407.08743300534;11434.256773309708;11505.13711471643;11519.884801152008;11544.191906566544;11549.249298795552;8413.579321033923;8621.137886211329;11068.778624427547;11250.437478126061;11291.887081129165;39762.0059498512;40058.79970600158;40097;40165.39751903724;40203.79898100491;3901.200253947934;4002.3596224604216;4018.5378681451716;4053.9594604053877;4143.6892233082635;29367.965023451918;30216.24459388511;30422;30579.08262752121;30605.693943060505;3162.5256211568217;3302.0424077017638;3308.338332333519;3375.206826395795;3375.6961873431264;312277;312661.43669281737;312680;313768.43115784274;314975;59158.40841591615;59940;60024.39975600188;60120;60269.69865150734;62915.37084629095;63042.68478657669;63097.36902630915;63210;63312;15371.692566148615;15824.44614438489;15838.495342942402;15841.128737919526;15852.682946341009;11027.61403350879;11277.097832173406;12129.575464858688;12301.753964920652;12353.752924941451;14257.50098746539;14980.62548436276;16571.585710357336;16928.322867085426;16936.57658558546;7155.606441645656;7160.964195178991;7161.314507059853;7261.927380726231;7291.817704557428;869.5521806269776;873.4671850171391;874.8818909447143;882.2412725056446;917.5274733512247;29770.55344169839;29820;29946.101616951528;30626.54060189099;30663;1062.8937106289463;1088.2327958789035;1095.7917995580815;1108.539955918285;1161.802493576094;14381.92809035969;15616;17200.483985480456;17239.138043097795;17630.735538966925;636340;647905;649346;651008;652496;62592;63693.452261909006;63725.681371592844;63963;63978.68010659917;1426.9571912842423;1461.4738694070127;1469.992650036743;1491;1491.7091167222288;26288.079917202806;30412.84793576018;31218.531722024643;31263.905763299084;31691;53849.73075134599;54580;54751;54785.72607136939;54813.725931371686;574977;580107;581742;581917;582392;42203.3669494964;42583.5741642592;42708.44790177257;42867.785661071495;42921.926951826455;39410.40884386794;46018;46736.53263467322;47463;47742.52257477381;31789.523157153122;38722;38821;39422.80288598539;39474.60525394709;38076;38136;39194.80402597969;39295.607043929194;39569.80215098906;37225.627743722216;37449;37941.81029094947;37980.62019379771;37982.620173797906;32053.7714712472;32118.83940580282;33896.830515847265;33963.15092122717;34034.489482657285;21601.351959441243;23855.284341469785;24762;25268.74731252664;25276.74723252744 07:35:25 INFO - PROCESS | 1647 | |2;jquery.html;329.7082082357114;374.3392911511182;390.11185860435467;395.10902913929084;420.7980169518631;15309.923450382747;15787.28957196926;18092.276308947643;20498.89750551249;20600;21543.892280538614;21810.454738631543;22017.33947981559;22045.228417005408;22341.44146396337;5906.320773111082;5942.6434413935085;6101.0848372744;6145.754169833202;6204.317525072239;5953.2856057272975;6062.060380640985;6071.301800292978;6071.51427885768;6075.483583895374;4203.684723645739;4416.668749843775;4422.867313980586;5779.393163717812;6792.558483698544;6567.211934567882;6793.728250869959;6796.932030679704;6800.931990680079;6815.556988795737;5795.391483894173;5881.2354393928945;5882.764689412418;5883.499902508292;5890.087036509348;13601.591952241448;22893.397462177654;24732.258032259062;25748.356291092685;27297.181084567495;2448.865312407817;2468.7531246875346;2619.6594442722326;2639.920802375932;2655.9734402656113;10224.182065434747;10345.068943795095;10667.86642671461;10726.463676816127;12770.744585108247;1627.170143226951;1844.880082794614;1846.8522518198647;1853.2216469083016;1861.2275905499203;135372;144860.27569862083;145770;146401.99999999892;150426.24786876104;11828.290302581872;11841.171118021828;11934.582289619822;11951.402429878472;12066.336351500577;15795.447159349367;15811.762823557656;15846.76229856553;15864.841351586567;15888.285027173804;6791.4227290679855;8581.742547723577;8585.957070214734;8692.304615630734;8711.172438618321;8130.959345203236;8211.58942052895;8247.546384948886;8352.916470835335;8397.664093436317;4787.234042553175;4798.184308667535;4811.49479304675;4819.373481447459;4864.343313652683;1792.354752289175;1952.2971730177126;1962.9607407851763;2006.287767842424;2016.3043749906258;958.9376690515234;1016.8984596979332;1019.3486551786006;1054.7046826888484;1059.2373491086405;22351.776482235295;22796.316110516713;22831.31506054821;22896.084156633882;22939.88530057339;1010.4190090697878;1020.3673722292226;1036.6889933020063;1062.1661995333625;1071.7695695425366;11322.150838687132;11643.88356116445;11703.882961170279;11718.296902185895;11731.47208375625;708882;719043;727035;728001;733981;103017;144952;145440.27279863533;159782;159861;986.4722373530127;993.6869885985891;1007.6221416968637;1008.6620981971122;1010.5553556435292;15702.921485392957;19422.611547768684;19751.012449377187;19756.407307780788;19804.20783168657;56693.43306566882;57200.85598287928;57302;57856.553586158996;59176.704116479144;82254;82273;82347.76478352709;82687;83928.58035709782;8158;8196.713115040944;8235.50586964784;8535.359848011549;8643.962724473104;10505.474726263808;12628.368581571069;13241.536546221221;13583.52457663977;13615.591532253651;11166.609168679057;11324.830127547928;11588.015018723167;11590.768184636092;11735.23720958135;6978.860422791618;11423.257488263236;11429.314241145225;11604.94197529007;11956.880431195577;11527.135464840338;11538.134639901873;11751.823722643996;11862.110341724232;11900.880991190324;8342.958285208779;8510.531920744172;8556.27271681914;8571.100034496289;8637.857288006206;8745.47527148373;11299.604513842305;11437.370944598128;11705.356205408785;11779.705507362385 07:35:25 INFO - PROCESS | 1647 | |3;mootools.html;1458.9927050364747;1478.7338279109756;1485.628592851787;1503.9774403383947;1504.6539295961936;8062.475939063964;8466.788330291745;8468.407211495198;8490.787730306745;8509.361797865155;7495.73764918228;7709.6916123354995;7724.7682569523;8028.197180281969;8260.958695206531;2298.609236429807;2346.9530609387803;2349.0486353026968;2349.706286714161;2354.1289722802585;2340.2979106268135;2368.0409434179187;2369.206315884181;2372.6915500984846;2383.916562920298;2300.907963681451;2307.8846057697133;2328.75548067453;2333.614953532665;2333.94165145871;2320.6170981787986;2336.3107883174503;2342.23877239896;2343.203310874311;2344.6014177589764;2271.432141964509;2306.342692332677;2307.8384513084093;2309.4226443389152;2311.3297143828268;8359.749207523784;8402.117777633383;8408.83182336356;8413.789655258606;8424.747257582283;5028;5181.53366197044;5189.377274727057;5198.012377648235;5198.4801519847715;3334.549835772188;3501.142220156078;3539.1859872229275;3552.6980206682465;3557.1107223194203;1188.1326631558925;1216.604603503856;1225.3811825028392;1262.3435813377084;1264.8418947631546;10764.946175269151;10816.945915270373;10827.783444331148;10831.025207731263;10842.078423334026;4369.825206991684;4630;4665.440147182359;4700.929486057712;4715.882102947454;9349.626014959462;9350;9356.485393303297;9359.204467620328;9415.246780257563;5194.6104042196985;5197.792088316425;5201.401838788512;5209.843704688866;5220.791168353223;4060.3503439449546;4605.078984203173;4778.95221047792;4787.42550893895;4847.418309802846;3755.511783468132;3788.8295026723863;3792.962070379316;3858.7684738915755;3863.362545180014;1270.3457719274581;1273.7006803401193;1275.5535562553032;1291.289790615158;1293.8123972024005;780.30163004111;867.492516877621;894.4722613657843;900.0864122915247;929.1498279074706;5504.91742623861;5524.558035357162;5598.2442370280305;5814.476697097281;5842;1004.8944860789512;1009.8182327181178;1014.3406785589278;1020.7652239984845;1036.1658864613908;3287.161773747727;3774.8490060397826;3776.792276424823;3811.3711237646035;3912.882613521599;9703.417794932328;10111.93824648416;10131.290809643255;10139.138173255356;10152.847707284396;10077.445740484343;10082.395056296646;10091.69724908254;10096.747581310527;10097.990200979842;6646.53474256807;7757.456978011485;7819.100803407568;7914.366850651934;7927.5243485391065;5467.781288748564;5496.725163741877;5582.106862902078;5636.1827535008015;5678;9170.266378689555;9174.908250917406;9267.212286955684;9292.72121836346;9295.860562091439;8032.71476563747;8615.956920215358;9147.817043659223;9490.383125096847;9587.904120958701;6323.20959880015;6524.739010439529;7442.813929651802;7677.692892284246;7773.650185741768;7581.658825352983;7851.411144164096;7854.842903142019;7937.2459616336355;8054.395920306118;6875.621840798604;7046.506744527834;7108.46686498525;7197.856042879218;7224.494285399972;6844.315568443116;6860.211075726355;6922.480813938874;6953.617551034742;7055.435565154673;5898.938191125639;6045.21412216409;6263.6241825490615;6318.6840657965995;6436.678166091583;4367.45406824147;4801.975990120027;4921.409430868319;5011.072951503984;5111.335526381696;4882.121218180761;4955.65310428281;5036.597072234139;5073.264376665436;5205.92191117126 07:35:25 INFO - PROCESS | 1647 | |4;prototype.html;316.318333990251;351.53246182577175;365.55402409060946;369.30386221971577;373.34477991125556;4792.440768708299;5346.866328341794;5363.463653634634;5387.622866399359;5419.295491586093;4323.1137616788565;4471.374007638933;4491.1017796440765;5219.5041471060285;5239.345081864767;3611.880317101694;3910.76535407875;4119.423280740699;4191.748495090289;4226.344916537942;3225.5645487859074;3721.5348081489815;3859.8070096495203;3902.8876770120523;4229.344451610005;3520.806355650438;3789.2989796887528;3961.4255932889714;4174.97912510437;4200.768957707325;4138.689598280111;4163.562825903297;4173.770442625654;4180.916381672349;4201.537830838605;3307.5038744188323;3568.6252943440822;3702.5927148013698;3703.518542589473;3709.51776269086;4485.506594274627;4508.89532064643;4880.804767809319;5152.92270615941;5210.505002024839;4193.077522944947;4411.801468933906;4790.185668436339;4857.9028419431415;4879.365682461293;5075.249039081511;5215.348081489814;5607.439256074401;5634.408387119375;5667.971660141714;3935.311320518898;4076.634327500291;4098.446709694213;4098.754074755524;4121.8969525762095;4892.706437613754;5829.941700582982;5919.230500035011;5998.270786791999;6029.336772954972;3528.9647103528637;4105.97947010269;4216.482066456073;4228.281192197333;4231.851885184065;3847.4228865670375;4196.601322874322;4296.419983302277;4300.376445415397;4304.7632380218765;3985.720999530005;4244.554321796167;4842.322074909515;4884.609231261491;4926.531979462017;4636.374089497942;5227.9544091181915;5556.416576259516;5688.829335119953;5733.369329373765;1249.4315086635559;1352.4657760184673;1364.40648317982;1387.5975966969465;1388.7777955527067;813.2599334605599;890.5217339216869;896.9461832290084;949.368669834566;989.7973961636502;4366.607005369532;4373.1472362888935;4461.776911154429;5063.480955713271;5117.5394214520875;5378.78484860609;5380.2736630555155;5479.0959491684225;5618.269624948731;5778.335491418541;4819.325294458778;4935.580475659537;5015.489157589702;5020.51894691067;5026.924596130988;2760.3437937237495;2834.386157649014;2937.2510009947327;2937.7649788016906;2947.0991210669;4918.942427378131;5504.697241651667;5613.803516876889;5760.107183386582;5825.533957283407;5128.948710512922;5471.534919531884;5640.153976903415;5679.744411501492;5679.858003549862;5179.736765837498;5320.563447868976;5436.700981446058;5516.648421136847;5607.074832652566;4433.135538569948;5104.9489505104475;5354.437784032776;5372.979133964534;5453.909567824132;4777.402824646919;4911.9754401227765;5003.399592048978;5381.730913454233;5546;5186.6369354144845;5219.791208351623;5532.28080349552;5703.971480142573;5736.877355378537;3312.3832172584353;3669.7431179818223;3814.3706288463204;3955.545112312121;3992.5208974923194;4436.578525040116;5014.6991180529285;5182.714950677749;5510.724463776875;5518.255035570147;4392.6705497087205;4519;5344.396680995608;5374.892502150014;5381.48875856793;4451.777411129366;4481.663875209307;4634.173154375895;4878.219484882402;5254.343207099113;4578.633709303314;4872.634552408655;4983.825566105169;5275.920861187008;5318.484231993863;4720.881977950578;4767.4517430495935;5048.545630893237;5116.513931176532;5334.039872822929;5364.927014597019;5511.751971161388;5528.9723551381985;5544.972275138599;5561.499465048165 07:35:25 INFO - PROCESS | 1647 | |5;yui.html;730.3317464519961;731.3856360657048;732.3811379384418;786.2177133751918;849.4478588917204;14841.777373339395;15693.921530392347;15716.449924252653;15742.842571574281;15796.052236865797;14520.273986300695;14596.197209153492;14621.415143394279;14627.195504247262;14652.706945861102;680.7719413996317;705.0622671846432;851.1531026628513;868.4919322196523;883.3419102768446;868.3096937934325;874.1564390363299;875.181705105725;875.605977310213;877.4954401219293;896.5562046786818;915.0483497162943;940.8682784410188;944.8960614332418;947.1948843483053;940.05994005994;940.9905900941005;941.3504681769557;943.6084025129563;945.229637845154;539.6384422436937;556.3379578301808;677.4140368581191;694.6839188169371;700.5131433653613;15464.845351546453;15483.845161548466;15492.147931863743;15498.845011549853;15507.844921550753;13923.094998825043;13933.233672148028;13940.86059139416;13947.163170209718;13990.86009139916;12736.172148810298;12981.480740770357;13004;13093.541726039639;13497.055206135574;4450.777461126931;4499.75251361175;4524.592786649185;4688.507706690816;4699.483056863743;104260;104580.47709761403;105389.94610053879;105850;108308.45845770721;11088.22382433238;11152.776944461066;11192.832107518394;11285.435728213557;11614.30314181135;22439.88780056122;22592.209272675376;22790.088396463954;22854.657180142312;22893.427664308525;7429.442791790637;7443.574664229521;7448.813779655552;7472.813179670552;7665.578393188428;3874.8837534874;3963.841446342172;3965.2466031453932;3965.6629186518894;3969.503812023497;3024.561438591392;3435.604905435857;3528.4530897710897;3531.69980551656;3539.9292014159573;621.1366200980641;631.5957787016313;634.8063840528591;642.1363266406676;651.0137142229526;582.496140838172;611.4863514647717;615.7660089166178;618.3414663383483;650.5251166648374;9185.265178785681;9412.341136120544;9482.288828337902;9649.710508684751;9792.951035244778;586.9530437564985;604.9243844519435;610.1092405088532;614.0543562913064;622.6357580815184;4165.958340416618;4383.62739167168;4411.360352748833;4415.116976604692;4531.478879928851;98159.50920245495;99440;100259;102158;104694;94214.52892735493;94560;94588.0541194593;94616;95515.52242238744;81651.18348816555;81967.9999999988;83138.54090640912;84795.15204848119;85535;6882.8623427532175;8387.203215694499;8530.530820804666;8703.56482175876;8901.866472003054;41147;46129.76935115437;48065.75967120142;49778.751106244235;53793.92412151813;63400;65347.67326163339;65760.67119664562;66996.33003669902;67770.32229677838;12478.438470268678;12707;12715.745685086062;12729.618111456672;13102.410391532214;11415.258008229439;11927.284362938251;14276.30002396331;14657.853421465648;14688.045277056957;11555.88444115548;13274;13283.601491955256;13292;13333.599992000256;12600;13131.671708207372;13135.737285254432;13357.065005449525;13713.931430342784;12537.498500059895;12863.485460581473;13048.54330098442;13061.151025183333;13333;11012.215883635572;11016.724581885197;11221.495032723384;11464.31214127155;11551.537938482703;10748.462576870968;10853.077488413574;10890.401027943539;11276.32342059479;11524.13568982313 07:35:25 INFO - PROCESS | 1647 | __end_tp_report 07:35:25 INFO - PROCESS | 1647 | __start_cc_report 07:35:25 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1937 07:35:25 INFO - PROCESS | 1647 | __end_cc_report 07:35:25 INFO - PROCESS | 1647 | __startTimestamp1483976125464__endTimestamp 07:35:25 INFO - PROCESS | 1647 | 07:35:25 INFO - PROCESS | 1647 | ------- Summary: start ------- 07:35:25 INFO - PROCESS | 1647 | Number of tests: 6 07:35:25 INFO - PROCESS | 1647 | 07:35:25 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5720.39 Median:5911.38 stddev:652.42 (11.0%) stddev-sans-first:612.55 07:35:25 INFO - PROCESS | 1647 | Values: 2788.6 3147.5 3322.6 3330.3 3344.2 5541.2 5588.5 5633.2 5653.5 5684.3 5639.5 5646.6 5725.3 5735.9 5821.8 5180.1 5234.6 5251.7 5259.8 5290.3 5173.7 5285.6 5299.3 5313.2 5315.4 4378.6 5250.1 5262.3 5263.6 5280.6 5126.1 5235.4 5246.2 5282.9 5287.4 5096.8 5133.3 5145.8 5208.0 5218.8 5674.7 5688.5 5696.8 5717.1 5793.0 6280.7 6281.5 6290.9 6297.3 6303.3 6053.9 6105.2 6166.8 6255.7 6261.1 5739.8 5789.5 5806.5 5824.4 5877.6 6171.3 6176.3 6208.7 6213.9 6232.9 6121.3 6150.5 6175.1 6181.3 6195.8 5476.3 5864.2 6061.0 6161.2 6211.3 6065.8 6132.6 6209.9 6224.8 6236.3 6133.4 6170.2 6174.3 6182.5 6188.9 4892.3 4894.6 4945.0 4957.9 4959.1 4407.1 4454.5 4484.3 4500.5 4521.7 5641.3 5698.5 5711.0 5817.4 5826.4 6179.1 6204.3 6212.7 6223.6 6234.2 5824.9 5832.8 5845.2 5857.1 5907.1 5329.7 5404.8 5408.8 5435.0 5471.8 6168.2 6199.6 6214.5 6215.2 6245.3 6216.7 6227.6 6237.2 6243.7 6258.1 5410.6 6260.3 6263.2 6283.5 6297.7 6067.9 6110.0 6124.6 6127.4 6239.6 5062.3 6271.9 6282.4 6289.6 6304.5 6154.6 6157.2 6158.2 6159.9 6161.4 5870.5 5999.4 6022.1 6098.0 6138.7 5941.2 5998.6 6048.4 6084.8 6105.5 5959.6 6179.3 6181.6 6209.1 6211.3 5794.5 5856.4 5911.4 5922.8 5989.5 07:35:25 INFO - PROCESS | 1647 | 07:35:25 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:66193.43 Median:38429.00 stddev:142787.33 (371.6%) stddev-sans-first:143102.54 07:35:25 INFO - PROCESS | 1647 | Values: 439.3 519.0 542.6 553.3 573.1 34382.7 38119.8 40648.0 40740.0 40927.6 40555.6 41043.8 41079.6 41183.0 41392.0 8405.6 8687.3 8704.0 9242.4 9458.0 8601.5 8698.3 10983.2 11276.3 11311.6 11294.3 11384.8 11388.5 11402.9 11407.1 11434.3 11505.1 11519.9 11544.2 11549.2 8413.6 8621.1 11068.8 11250.4 11291.9 39762.0 40058.8 40097.0 40165.4 40203.8 3901.2 4002.4 4018.5 4054.0 4143.7 29368.0 30216.2 30422.0 30579.1 30605.7 3162.5 3302.0 3308.3 3375.2 3375.7 312277.0 312661.4 312680.0 313768.4 314975.0 59158.4 59940.0 60024.4 60120.0 60269.7 62915.4 63042.7 63097.4 63210.0 63312.0 15371.7 15824.4 15838.5 15841.1 15852.7 11027.6 11277.1 12129.6 12301.8 12353.8 14257.5 14980.6 16571.6 16928.3 16936.6 7155.6 7161.0 7161.3 7261.9 7291.8 869.6 873.5 874.9 882.2 917.5 29770.6 29820.0 29946.1 30626.5 30663.0 1062.9 1088.2 1095.8 1108.5 1161.8 14381.9 15616.0 17200.5 17239.1 17630.7 636340.0 647905.0 649346.0 651008.0 652496.0 62592.0 63693.5 63725.7 63963.0 63978.7 1427.0 1461.5 1470.0 1491.0 1491.7 26288.1 30412.8 31218.5 31263.9 31691.0 53849.7 54580.0 54751.0 54785.7 54813.7 574977.0 580107.0 581742.0 581917.0 582392.0 42203.4 42583.6 42708.4 42867.8 42921.9 39410.4 46018.0 46736.5 47463.0 47742.5 31789.5 38722.0 38821.0 39422.8 39474.6 38076.0 38136.0 39194.8 39295.6 39569.8 37225.6 37449.0 37941.8 37980.6 37982.6 32053.8 32118.8 33896.8 33963.2 34034.5 21601.4 23855.3 24762.0 25268.7 25276.7 07:35:25 INFO - PROCESS | 1647 | 07:35:25 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:40033.18 Median:22346.61 stddev:120704.15 (540.1%) stddev-sans-first:121005.94 07:35:25 INFO - PROCESS | 1647 | Values: 329.7 374.3 390.1 395.1 420.8 15309.9 15787.3 18092.3 20498.9 20600.0 21543.9 21810.5 22017.3 22045.2 22341.4 5906.3 5942.6 6101.1 6145.8 6204.3 5953.3 6062.1 6071.3 6071.5 6075.5 4203.7 4416.7 4422.9 5779.4 6792.6 6567.2 6793.7 6796.9 6800.9 6815.6 5795.4 5881.2 5882.8 5883.5 5890.1 13601.6 22893.4 24732.3 25748.4 27297.2 2448.9 2468.8 2619.7 2639.9 2656.0 10224.2 10345.1 10667.9 10726.5 12770.7 1627.2 1844.9 1846.9 1853.2 1861.2 135372.0 144860.3 145770.0 146402.0 150426.2 11828.3 11841.2 11934.6 11951.4 12066.3 15795.4 15811.8 15846.8 15864.8 15888.3 6791.4 8581.7 8586.0 8692.3 8711.2 8131.0 8211.6 8247.5 8352.9 8397.7 4787.2 4798.2 4811.5 4819.4 4864.3 1792.4 1952.3 1963.0 2006.3 2016.3 958.9 1016.9 1019.3 1054.7 1059.2 22351.8 22796.3 22831.3 22896.1 22939.9 1010.4 1020.4 1036.7 1062.2 1071.8 11322.2 11643.9 11703.9 11718.3 11731.5 708882.0 719043.0 727035.0 728001.0 733981.0 103017.0 144952.0 145440.3 159782.0 159861.0 986.5 993.7 1007.6 1008.7 1010.6 15702.9 19422.6 19751.0 19756.4 19804.2 56693.4 57200.9 57302.0 57856.6 59176.7 82254.0 82273.0 82347.8 82687.0 83928.6 8158.0 8196.7 8235.5 8535.4 8644.0 10505.5 12628.4 13241.5 13583.5 13615.6 11166.6 11324.8 11588.0 11590.8 11735.2 6978.9 11423.3 11429.3 11604.9 11956.9 11527.1 11538.1 11751.8 11862.1 11900.9 8343.0 8510.5 8556.3 8571.1 8637.9 8745.5 11299.6 11437.4 11705.4 11779.7 07:35:25 INFO - PROCESS | 1647 | 07:35:25 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5424.57 Median:5032.30 stddev:2936.09 (58.3%) stddev-sans-first:2929.20 07:35:25 INFO - PROCESS | 1647 | Values: 1459.0 1478.7 1485.6 1504.0 1504.7 8062.5 8466.8 8468.4 8490.8 8509.4 7495.7 7709.7 7724.8 8028.2 8261.0 2298.6 2347.0 2349.0 2349.7 2354.1 2340.3 2368.0 2369.2 2372.7 2383.9 2300.9 2307.9 2328.8 2333.6 2333.9 2320.6 2336.3 2342.2 2343.2 2344.6 2271.4 2306.3 2307.8 2309.4 2311.3 8359.7 8402.1 8408.8 8413.8 8424.7 5028.0 5181.5 5189.4 5198.0 5198.5 3334.5 3501.1 3539.2 3552.7 3557.1 1188.1 1216.6 1225.4 1262.3 1264.8 10764.9 10816.9 10827.8 10831.0 10842.1 4369.8 4630.0 4665.4 4700.9 4715.9 9349.6 9350.0 9356.5 9359.2 9415.2 5194.6 5197.8 5201.4 5209.8 5220.8 4060.4 4605.1 4779.0 4787.4 4847.4 3755.5 3788.8 3793.0 3858.8 3863.4 1270.3 1273.7 1275.6 1291.3 1293.8 780.3 867.5 894.5 900.1 929.1 5504.9 5524.6 5598.2 5814.5 5842.0 1004.9 1009.8 1014.3 1020.8 1036.2 3287.2 3774.8 3776.8 3811.4 3912.9 9703.4 10111.9 10131.3 10139.1 10152.8 10077.4 10082.4 10091.7 10096.7 10098.0 6646.5 7757.5 7819.1 7914.4 7927.5 5467.8 5496.7 5582.1 5636.2 5678.0 9170.3 9174.9 9267.2 9292.7 9295.9 8032.7 8616.0 9147.8 9490.4 9587.9 6323.2 6524.7 7442.8 7677.7 7773.7 7581.7 7851.4 7854.8 7937.2 8054.4 6875.6 7046.5 7108.5 7197.9 7224.5 6844.3 6860.2 6922.5 6953.6 7055.4 5898.9 6045.2 6263.6 6318.7 6436.7 4367.5 4802.0 4921.4 5011.1 5111.3 4882.1 4955.7 5036.6 5073.3 5205.9 07:35:25 INFO - PROCESS | 1647 | 07:35:25 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4428.17 Median:4878.79 stddev:1296.33 (26.6%) stddev-sans-first:1262.70 07:35:25 INFO - PROCESS | 1647 | Values: 316.3 351.5 365.6 369.3 373.3 4792.4 5346.9 5363.5 5387.6 5419.3 4323.1 4471.4 4491.1 5219.5 5239.3 3611.9 3910.8 4119.4 4191.7 4226.3 3225.6 3721.5 3859.8 3902.9 4229.3 3520.8 3789.3 3961.4 4175.0 4200.8 4138.7 4163.6 4173.8 4180.9 4201.5 3307.5 3568.6 3702.6 3703.5 3709.5 4485.5 4508.9 4880.8 5152.9 5210.5 4193.1 4411.8 4790.2 4857.9 4879.4 5075.2 5215.3 5607.4 5634.4 5668.0 3935.3 4076.6 4098.4 4098.8 4121.9 4892.7 5829.9 5919.2 5998.3 6029.3 3529.0 4106.0 4216.5 4228.3 4231.9 3847.4 4196.6 4296.4 4300.4 4304.8 3985.7 4244.6 4842.3 4884.6 4926.5 4636.4 5228.0 5556.4 5688.8 5733.4 1249.4 1352.5 1364.4 1387.6 1388.8 813.3 890.5 896.9 949.4 989.8 4366.6 4373.1 4461.8 5063.5 5117.5 5378.8 5380.3 5479.1 5618.3 5778.3 4819.3 4935.6 5015.5 5020.5 5026.9 2760.3 2834.4 2937.3 2937.8 2947.1 4918.9 5504.7 5613.8 5760.1 5825.5 5128.9 5471.5 5640.2 5679.7 5679.9 5179.7 5320.6 5436.7 5516.6 5607.1 4433.1 5104.9 5354.4 5373.0 5453.9 4777.4 4912.0 5003.4 5381.7 5546.0 5186.6 5219.8 5532.3 5704.0 5736.9 3312.4 3669.7 3814.4 3955.5 3992.5 4436.6 5014.7 5182.7 5510.7 5518.3 4392.7 4519.0 5344.4 5374.9 5381.5 4451.8 4481.7 4634.2 4878.2 5254.3 4578.6 4872.6 4983.8 5275.9 5318.5 4720.9 4767.5 5048.5 5116.5 5334.0 5364.9 5511.8 5529.0 5545.0 5561.5 07:35:25 INFO - PROCESS | 1647 | 07:35:25 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:20582.38 Median:4397.49 stddev:29793.23 (677.5%) stddev-sans-first:29839.52 07:35:25 INFO - PROCESS | 1647 | Values: 730.3 731.4 732.4 786.2 849.4 14841.8 15693.9 15716.4 15742.8 15796.1 14520.3 14596.2 14621.4 14627.2 14652.7 680.8 705.1 851.2 868.5 883.3 868.3 874.2 875.2 875.6 877.5 896.6 915.0 940.9 944.9 947.2 940.1 941.0 941.4 943.6 945.2 539.6 556.3 677.4 694.7 700.5 15464.8 15483.8 15492.1 15498.8 15507.8 13923.1 13933.2 13940.9 13947.2 13990.9 12736.2 12981.5 13004.0 13093.5 13497.1 4450.8 4499.8 4524.6 4688.5 4699.5 104260.0 104580.5 105389.9 105850.0 108308.5 11088.2 11152.8 11192.8 11285.4 11614.3 22439.9 22592.2 22790.1 22854.7 22893.4 7429.4 7443.6 7448.8 7472.8 7665.6 3874.9 3963.8 3965.2 3965.7 3969.5 3024.6 3435.6 3528.5 3531.7 3539.9 621.1 631.6 634.8 642.1 651.0 582.5 611.5 615.8 618.3 650.5 9185.3 9412.3 9482.3 9649.7 9793.0 587.0 604.9 610.1 614.1 622.6 4166.0 4383.6 4411.4 4415.1 4531.5 98159.5 99440.0 100259.0 102158.0 104694.0 94214.5 94560.0 94588.1 94616.0 95515.5 81651.2 81968.0 83138.5 84795.2 85535.0 6882.9 8387.2 8530.5 8703.6 8901.9 41147.0 46129.8 48065.8 49778.8 53793.9 63400.0 65347.7 65760.7 66996.3 67770.3 12478.4 12707.0 12715.7 12729.6 13102.4 11415.3 11927.3 14276.3 14657.9 14688.0 11555.9 13274.0 13283.6 13292.0 13333.6 12600.0 13131.7 13135.7 13357.1 13713.9 12537.5 12863.5 13048.5 13061.2 13333.0 11012.2 11016.7 11221.5 11464.3 11551.5 10748.5 10853.1 10890.4 11276.3 11524.1 07:35:25 INFO - PROCESS | 1647 | -------- Summary: end -------- 07:35:25 INFO - PROCESS | 1647 | 07:35:26 INFO - TEST-INFO | 1647: exit 0 07:35:26 INFO - TEST-OK | dromaeo_css | took 1119123ms 07:35:26 INFO - TEST-START | kraken 07:35:26 INFO - operating with platform_type : mac_ 07:35:26 INFO - Initialising browser for kraken test... 07:35:26 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmp9vszjz/profile http://localhost:49243/getInfo.html) 07:35:26 INFO - PROCESS | 1654 | 1483976126637 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4906 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2060 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3761 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2794 < callProvider()@resource://gre/modules/AddonManager.jsm:267 < _startProvider()@resource://gre/modules/AddonManager.jsm:973 < startup()@resource://gre/modules/AddonManager.jsm:1155 < startup()@resource://gre/modules/AddonManager.jsm:3124 < observe()@resource://gre/components/addonManager.js:65 07:35:28 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 07:35:28 INFO - PROCESS | 1654 | colorDepth:24 07:35:28 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 07:35:28 INFO - PROCESS | 1654 | __metrics 07:35:28 INFO - PROCESS | 1654 | 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" 07:35:28 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:35:29 INFO - TEST-INFO | 1654: exit 0 07:35:29 INFO - Browser initialized. 07:35:29 INFO - Running cycle 1/1 for kraken test... 07:35:29 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/T/tmp9vszjz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:35:41 INFO - PROCESS | 1655 | RSS: Main: 233598976 07:35:41 INFO - PROCESS | 1655 | 07:35:43 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 07:35:44 INFO - PROCESS | 1655 | RSS: Main: 286674944 07:35:44 INFO - PROCESS | 1655 | 07:35:47 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 07:35:48 INFO - PROCESS | 1655 | RSS: Main: 286052352 07:35:48 INFO - PROCESS | 1655 | 07:35:51 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 07:35:52 INFO - PROCESS | 1655 | RSS: Main: 379322368 07:35:52 INFO - PROCESS | 1655 | 07:35:54 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 07:35:54 INFO - PROCESS | 1655 | RSS: Main: 283160576 07:35:54 INFO - PROCESS | 1655 | 07:35:56 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:35:57 INFO - PROCESS | 1655 | RSS: Main: 287248384 07:35:57 INFO - PROCESS | 1655 | 07:36:00 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 07:36:00 INFO - PROCESS | 1655 | RSS: Main: 329502720 07:36:00 INFO - PROCESS | 1655 | 07:36:03 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 07:36:04 INFO - PROCESS | 1655 | RSS: Main: 329523200 07:36:04 INFO - PROCESS | 1655 | 07:36:07 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 07:36:07 INFO - PROCESS | 1655 | RSS: Main: 328327168 07:36:07 INFO - PROCESS | 1655 | 07:36:09 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:36:09 INFO - PROCESS | 1655 | RSS: Main: 304508928 07:36:09 INFO - PROCESS | 1655 | 07:36:11 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:36:12 INFO - PROCESS | 1655 | RSS: Main: 308924416 07:36:12 INFO - PROCESS | 1655 | 07:36:14 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:36:15 INFO - PROCESS | 1655 | RSS: Main: 309260288 07:36:15 INFO - PROCESS | 1655 | 07:36:18 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:36:18 INFO - PROCESS | 1655 | RSS: Main: 325378048 07:36:18 INFO - PROCESS | 1655 | 07:36:21 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:36:22 INFO - PROCESS | 1655 | RSS: Main: 319676416 07:36:22 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 07:36:24 INFO - PROCESS | 1655 | RSS: Main: 363778048 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | __start_tp_report 07:36:24 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 07:36:24 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 07:36:24 INFO - PROCESS | 1655 | |i|pagename|runs| 07:36:24 INFO - PROCESS | 1655 | |0;ai-astar;113;114;113;113;113;114;113;113;113;110 07:36:24 INFO - PROCESS | 1655 | |1;audio-beat-detection;142;109;108;145;110;111;108;107;131;110 07:36:24 INFO - PROCESS | 1655 | |2;audio-dft;183;187;184;187;184;184;184;185;230;208 07:36:24 INFO - PROCESS | 1655 | |3;audio-fft;83;59;60;87;60;58;59;58;85;59 07:36:24 INFO - PROCESS | 1655 | |4;audio-oscillator;90;67;85;70;69;69;83;69;67;85 07:36:24 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;90;87;91;88;90;119;91;88;91;89 07:36:24 INFO - PROCESS | 1655 | |6;imaging-darkroom;94;95;96;95;95;95;96;95;95;95 07:36:24 INFO - PROCESS | 1655 | |7;imaging-desaturate;88;86;85;88;89;88;87;87;89;86 07:36:24 INFO - PROCESS | 1655 | |8;json-parse-financial;56;56;53;53;55;55;55;55;55;54 07:36:24 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;65;72;75;82;81;74;71;69;73;71 07:36:24 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;90;87;86;87;87;86;90;86;86;90 07:36:24 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;146;150;150;140;139;141;134;137;185;150 07:36:24 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;174;167;176;171;175;174;172;174;172;172 07:36:24 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;68;68;67;67;66;67;65;66;65;65 07:36:24 INFO - PROCESS | 1655 | __end_tp_report 07:36:24 INFO - PROCESS | 1655 | __start_cc_report 07:36:24 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1967 07:36:24 INFO - PROCESS | 1655 | __end_cc_report 07:36:24 INFO - PROCESS | 1655 | __startTimestamp1483976184369__endTimestamp 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | ------- Summary: start ------- 07:36:24 INFO - PROCESS | 1655 | Number of tests: 14 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:112.90 Median:113.00 stddev:1.10 (1.0%) stddev-sans-first:1.17 07:36:24 INFO - PROCESS | 1655 | Values: 113.0 114.0 113.0 113.0 113.0 114.0 113.0 113.0 113.0 110.0 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:118.10 Median:110.50 stddev:15.10 (13.7%) stddev-sans-first:13.31 07:36:24 INFO - PROCESS | 1655 | Values: 142.0 109.0 108.0 145.0 110.0 111.0 108.0 107.0 131.0 110.0 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:191.60 Median:186.00 stddev:15.40 (8.3%) stddev-sans-first:16.02 07:36:24 INFO - PROCESS | 1655 | Values: 183.0 187.0 184.0 187.0 184.0 184.0 184.0 185.0 230.0 208.0 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:66.80 Median:60.00 stddev:12.61 (21.0%) stddev-sans-first:11.94 07:36:24 INFO - PROCESS | 1655 | Values: 83.0 59.0 60.0 87.0 60.0 58.0 59.0 58.0 85.0 59.0 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:75.40 Median:76.50 stddev:9.12 (11.9%) stddev-sans-first:8.00 07:36:24 INFO - PROCESS | 1655 | Values: 90.0 67.0 85.0 70.0 69.0 69.0 83.0 69.0 67.0 85.0 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:92.40 Median:90.00 stddev:9.45 (10.5%) stddev-sans-first:9.99 07:36:24 INFO - PROCESS | 1655 | Values: 90.0 87.0 91.0 88.0 90.0 119.0 91.0 88.0 91.0 89.0 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.44 07:36:24 INFO - PROCESS | 1655 | Values: 94.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 95.0 95.0 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:87.30 Median:88.00 stddev:1.34 (1.5%) stddev-sans-first:1.39 07:36:24 INFO - PROCESS | 1655 | Values: 88.0 86.0 85.0 88.0 89.0 88.0 87.0 87.0 89.0 86.0 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.06 (1.9%) stddev-sans-first:1.01 07:36:24 INFO - PROCESS | 1655 | Values: 56.0 56.0 53.0 53.0 55.0 55.0 55.0 55.0 55.0 54.0 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:73.50 stddev:5.14 (7.0%) stddev-sans-first:4.49 07:36:24 INFO - PROCESS | 1655 | Values: 65.0 72.0 75.0 82.0 81.0 74.0 71.0 69.0 73.0 71.0 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:87.00 stddev:1.78 (2.0%) stddev-sans-first:1.64 07:36:24 INFO - PROCESS | 1655 | Values: 90.0 87.0 86.0 87.0 87.0 86.0 90.0 86.0 86.0 90.0 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:147.20 Median:148.00 stddev:14.49 (9.8%) stddev-sans-first:15.36 07:36:24 INFO - PROCESS | 1655 | Values: 146.0 150.0 150.0 140.0 139.0 141.0 134.0 137.0 185.0 150.0 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.70 Median:174.00 stddev:2.54 (1.5%) stddev-sans-first:2.65 07:36:24 INFO - PROCESS | 1655 | Values: 174.0 167.0 176.0 171.0 175.0 174.0 172.0 174.0 172.0 172.0 07:36:24 INFO - PROCESS | 1655 | 07:36:24 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:1.17 (1.8%) stddev-sans-first:1.09 07:36:24 INFO - PROCESS | 1655 | Values: 68.0 68.0 67.0 67.0 66.0 67.0 65.0 66.0 65.0 65.0 07:36:24 INFO - PROCESS | 1655 | -------- Summary: end -------- 07:36:24 INFO - PROCESS | 1655 | 07:36:25 INFO - TEST-INFO | 1655: exit 0 07:36:25 INFO - TEST-OK | kraken | took 59124ms 07:36:25 INFO - SUITE-END | took 1178s 07:36:25 INFO - Completed test suite (00:19:38) 07:36:25 INFO - javascript benchmark 07:36:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2788.637477127973, 3147.5121356189784, 3322.5680661514007, 3330.250693593942, 3344.2475443025305, 5541.196526503664, 5588.469095435938, 5633.183188437674, 5653.462921022507, 5684.261046064016, 5639.464250896158, 5646.576506761999, 5725.255716756827, 5735.942640573593, 5821.825345239639, 5180.067587834188, 5234.581233501321, 5251.658642188265, 5259.789608415659, 5290.259363689087, 5173.676318948724, 5285.603579731516, 5299.311089558371, 5313.20301954708, 5315.441878602749, 4378.64970802335, 5250.133727934905, 5262.315898933133, 5263.605229607776, 5280.630355875091, 5126.077306084903, 5235.424103348629, 5246.213068039825, 5282.9207561886415, 5287.365516138049, 5096.821611243588, 5133.255677926717, 5145.794168233304, 5207.973960130213, 5218.817341393033, 5674.744636491368, 5688.488036076773, 5696.772129114872, 5717.056685646863, 5793.0, 6280.685965701742, 6281.497480201619, 6290.905636415458, 6297.275813281485, 6303.338149494283, 6053.939460605382, 6105.186826067707, 6166.84582885427, 6255.687215639246, 6261.092141639437, 5739.827805165894, 5789.536837053026, 5806.535477161817, 5824.417558244142, 5877.588568800143, 6171.326057706647, 6176.289726681399, 6208.689565521706, 6213.906791398133, 6232.937670623326, 6121.326654068049, 6150.538709596798, 6175.135481032659, 6181.320054793969, 6195.752169913243, 5476.342838859362, 5864.179014937912, 6061.030235162353, 6161.199044124235, 6211.254649442006, 6065.75736970525, 6132.63204207749, 6209.937900620936, 6224.844378890564, 6236.34518375564, 6133.365267548775, 6170.228721409824, 6174.289956654952, 6182.505399568024, 6188.8762224755255, 4892.315075889378, 4894.559489645949, 4944.950550494521, 4957.851264462072, 4959.057779021974, 4407.140607581491, 4454.4654641442585, 4484.304932735431, 4500.549945005473, 4521.660875434355, 5641.323041235078, 5698.544116470755, 5711.0, 5817.360090390053, 5826.359100498942, 6179.104029915637, 6204.255489341308, 6212.658303793244, 6223.626582405071, 6234.1583886175695, 5824.854378640568, 5832.825015249549, 5845.152452894306, 5857.062869940874, 5907.1139329100115, 5329.7068661224, 5404.837854864281, 5408.810691625774, 5435.021696094662, 5471.8084867029465, 6168.167297414791, 6199.5660303779205, 6214.533909956681, 6215.160953271431, 6245.250569931637, 6216.689165541795, 6227.626342419469, 6237.157983672146, 6243.719032643451, 6258.12386265914, 5410.639593503182, 6260.342664020212, 6263.248410190807, 6283.497320214463, 6297.716602752978, 6067.908981365372, 6110.012029148408, 6124.571280010535, 6127.372786728474, 6239.594426362272, 5062.341895553554, 6271.874562508816, 6282.403171698681, 6289.559730818799, 6304.5271604628915, 6154.630722156685, 6157.230346206724, 6158.199434073542, 6159.938400615936, 6161.4146656067605, 5870.53035757147, 5999.430054144849, 6022.066579680157, 6098.0, 6138.723757431016, 5941.168236446814, 5998.610090344114, 6048.3649216833355, 6084.847878803065, 6105.45050945417, 5959.612625179349, 6179.28938172116, 6181.567290289637, 6209.130721699056, 6211.2857021443115, 5794.507466865195, 5856.3558008618165, 5911.379305172895, 5922.7927022554, 5989.520838332835], "value": 5677.018329681403, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [439.28615999001624, 518.9584833213341, 542.5930552085936, 553.2586334312022, 573.0802062689382, 34382.656173438256, 38119.80940095299, 40647.99999999996, 40740.0, 40927.59072409275, 40555.594444055554, 41043.794781026125, 41079.58920410795, 41182.970425739375, 41392.0, 8405.579721013955, 8687.305015598737, 8704.0, 9242.35303528755, 9458.0, 8601.483910965331, 8698.347623928199, 10983.231173817836, 11276.32342059479, 11311.604093856717, 11294.265872718248, 11384.829227561593, 11388.54445822166, 11402.885971140266, 11407.08743300534, 11434.256773309708, 11505.13711471643, 11519.884801152008, 11544.191906566544, 11549.249298795552, 8413.579321033923, 8621.137886211329, 11068.778624427547, 11250.437478126061, 11291.887081129165, 39762.0059498512, 40058.79970600158, 40097.0, 40165.39751903724, 40203.79898100491, 3901.200253947934, 4002.3596224604216, 4018.5378681451716, 4053.9594604053877, 4143.6892233082635, 29367.965023451918, 30216.24459388511, 30422.0, 30579.08262752121, 30605.693943060505, 3162.5256211568217, 3302.0424077017638, 3308.338332333519, 3375.206826395795, 3375.6961873431264, 312277.0, 312661.43669281737, 312680.0, 313768.43115784274, 314975.0, 59158.40841591615, 59940.0, 60024.39975600188, 60120.0, 60269.69865150734, 62915.37084629095, 63042.68478657669, 63097.36902630915, 63210.0, 63312.0, 15371.692566148615, 15824.44614438489, 15838.495342942402, 15841.128737919526, 15852.682946341009, 11027.61403350879, 11277.097832173406, 12129.575464858688, 12301.753964920652, 12353.752924941451, 14257.50098746539, 14980.62548436276, 16571.585710357336, 16928.322867085426, 16936.57658558546, 7155.606441645656, 7160.964195178991, 7161.314507059853, 7261.927380726231, 7291.817704557428, 869.5521806269776, 873.4671850171391, 874.8818909447143, 882.2412725056446, 917.5274733512247, 29770.55344169839, 29820.0, 29946.101616951528, 30626.54060189099, 30663.0, 1062.8937106289463, 1088.2327958789035, 1095.7917995580815, 1108.539955918285, 1161.802493576094, 14381.92809035969, 15616.0, 17200.483985480456, 17239.138043097795, 17630.735538966925, 636340.0, 647905.0, 649346.0, 651008.0, 652496.0, 62592.0, 63693.452261909006, 63725.681371592844, 63963.0, 63978.68010659917, 1426.9571912842423, 1461.4738694070127, 1469.992650036743, 1491.0, 1491.7091167222288, 26288.079917202806, 30412.84793576018, 31218.531722024643, 31263.905763299084, 31691.0, 53849.73075134599, 54580.0, 54751.0, 54785.72607136939, 54813.725931371686, 574977.0, 580107.0, 581742.0, 581917.0, 582392.0, 42203.3669494964, 42583.5741642592, 42708.44790177257, 42867.785661071495, 42921.926951826455, 39410.40884386794, 46018.0, 46736.53263467322, 47463.0, 47742.52257477381, 31789.523157153122, 38722.0, 38821.0, 39422.80288598539, 39474.60525394709, 38076.0, 38136.0, 39194.80402597969, 39295.607043929194, 39569.80215098906, 37225.627743722216, 37449.0, 37941.81029094947, 37980.62019379771, 37982.620173797906, 32053.7714712472, 32118.83940580282, 33896.830515847265, 33963.15092122717, 34034.489482657285, 21601.351959441243, 23855.284341469785, 24762.0, 25268.74731252664, 25276.74723252744], "value": 20203.328783837624, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.7082082357114, 374.3392911511182, 390.11185860435467, 395.10902913929084, 420.7980169518631, 15309.923450382747, 15787.28957196926, 18092.276308947643, 20498.89750551249, 20600.0, 21543.892280538614, 21810.454738631543, 22017.33947981559, 22045.228417005408, 22341.44146396337, 5906.320773111082, 5942.6434413935085, 6101.0848372744, 6145.754169833202, 6204.317525072239, 5953.2856057272975, 6062.060380640985, 6071.301800292978, 6071.51427885768, 6075.483583895374, 4203.684723645739, 4416.668749843775, 4422.867313980586, 5779.393163717812, 6792.558483698544, 6567.211934567882, 6793.728250869959, 6796.932030679704, 6800.931990680079, 6815.556988795737, 5795.391483894173, 5881.2354393928945, 5882.764689412418, 5883.499902508292, 5890.087036509348, 13601.591952241448, 22893.397462177654, 24732.258032259062, 25748.356291092685, 27297.181084567495, 2448.865312407817, 2468.7531246875346, 2619.6594442722326, 2639.920802375932, 2655.9734402656113, 10224.182065434747, 10345.068943795095, 10667.86642671461, 10726.463676816127, 12770.744585108247, 1627.170143226951, 1844.880082794614, 1846.8522518198647, 1853.2216469083016, 1861.2275905499203, 135372.0, 144860.27569862083, 145770.0, 146401.99999999892, 150426.24786876104, 11828.290302581872, 11841.171118021828, 11934.582289619822, 11951.402429878472, 12066.336351500577, 15795.447159349367, 15811.762823557656, 15846.76229856553, 15864.841351586567, 15888.285027173804, 6791.4227290679855, 8581.742547723577, 8585.957070214734, 8692.304615630734, 8711.172438618321, 8130.959345203236, 8211.58942052895, 8247.546384948886, 8352.916470835335, 8397.664093436317, 4787.234042553175, 4798.184308667535, 4811.49479304675, 4819.373481447459, 4864.343313652683, 1792.354752289175, 1952.2971730177126, 1962.9607407851763, 2006.287767842424, 2016.3043749906258, 958.9376690515234, 1016.8984596979332, 1019.3486551786006, 1054.7046826888484, 1059.2373491086405, 22351.776482235295, 22796.316110516713, 22831.31506054821, 22896.084156633882, 22939.88530057339, 1010.4190090697878, 1020.3673722292226, 1036.6889933020063, 1062.1661995333625, 1071.7695695425366, 11322.150838687132, 11643.88356116445, 11703.882961170279, 11718.296902185895, 11731.47208375625, 708882.0, 719043.0, 727035.0, 728001.0, 733981.0, 103017.0, 144952.0, 145440.27279863533, 159782.0, 159861.0, 986.4722373530127, 993.6869885985891, 1007.6221416968637, 1008.6620981971122, 1010.5553556435292, 15702.921485392957, 19422.611547768684, 19751.012449377187, 19756.407307780788, 19804.20783168657, 56693.43306566882, 57200.85598287928, 57302.0, 57856.553586158996, 59176.704116479144, 82254.0, 82273.0, 82347.76478352709, 82687.0, 83928.58035709782, 8158.0, 8196.713115040944, 8235.50586964784, 8535.359848011549, 8643.962724473104, 10505.474726263808, 12628.368581571069, 13241.536546221221, 13583.52457663977, 13615.591532253651, 11166.609168679057, 11324.830127547928, 11588.015018723167, 11590.768184636092, 11735.23720958135, 6978.860422791618, 11423.257488263236, 11429.314241145225, 11604.94197529007, 11956.880431195577, 11527.135464840338, 11538.134639901873, 11751.823722643996, 11862.110341724232, 11900.880991190324, 8342.958285208779, 8510.531920744172, 8556.27271681914, 8571.100034496289, 8637.857288006206, 8745.47527148373, 11299.604513842305, 11437.370944598128, 11705.356205408785, 11779.705507362385], "value": 9958.48016827954, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1458.9927050364747, 1478.7338279109756, 1485.628592851787, 1503.9774403383947, 1504.6539295961936, 8062.475939063964, 8466.788330291745, 8468.407211495198, 8490.787730306745, 8509.361797865155, 7495.73764918228, 7709.6916123354995, 7724.7682569523, 8028.197180281969, 8260.958695206531, 2298.609236429807, 2346.9530609387803, 2349.0486353026968, 2349.706286714161, 2354.1289722802585, 2340.2979106268135, 2368.0409434179187, 2369.206315884181, 2372.6915500984846, 2383.916562920298, 2300.907963681451, 2307.8846057697133, 2328.75548067453, 2333.614953532665, 2333.94165145871, 2320.6170981787986, 2336.3107883174503, 2342.23877239896, 2343.203310874311, 2344.6014177589764, 2271.432141964509, 2306.342692332677, 2307.8384513084093, 2309.4226443389152, 2311.3297143828268, 8359.749207523784, 8402.117777633383, 8408.83182336356, 8413.789655258606, 8424.747257582283, 5028.0, 5181.53366197044, 5189.377274727057, 5198.012377648235, 5198.4801519847715, 3334.549835772188, 3501.142220156078, 3539.1859872229275, 3552.6980206682465, 3557.1107223194203, 1188.1326631558925, 1216.604603503856, 1225.3811825028392, 1262.3435813377084, 1264.8418947631546, 10764.946175269151, 10816.945915270373, 10827.783444331148, 10831.025207731263, 10842.078423334026, 4369.825206991684, 4630.0, 4665.440147182359, 4700.929486057712, 4715.882102947454, 9349.626014959462, 9350.0, 9356.485393303297, 9359.204467620328, 9415.246780257563, 5194.6104042196985, 5197.792088316425, 5201.401838788512, 5209.843704688866, 5220.791168353223, 4060.3503439449546, 4605.078984203173, 4778.95221047792, 4787.42550893895, 4847.418309802846, 3755.511783468132, 3788.8295026723863, 3792.962070379316, 3858.7684738915755, 3863.362545180014, 1270.3457719274581, 1273.7006803401193, 1275.5535562553032, 1291.289790615158, 1293.8123972024005, 780.30163004111, 867.492516877621, 894.4722613657843, 900.0864122915247, 929.1498279074706, 5504.91742623861, 5524.558035357162, 5598.2442370280305, 5814.476697097281, 5842.0, 1004.8944860789512, 1009.8182327181178, 1014.3406785589278, 1020.7652239984845, 1036.1658864613908, 3287.161773747727, 3774.8490060397826, 3776.792276424823, 3811.3711237646035, 3912.882613521599, 9703.417794932328, 10111.93824648416, 10131.290809643255, 10139.138173255356, 10152.847707284396, 10077.445740484343, 10082.395056296646, 10091.69724908254, 10096.747581310527, 10097.990200979842, 6646.53474256807, 7757.456978011485, 7819.100803407568, 7914.366850651934, 7927.5243485391065, 5467.781288748564, 5496.725163741877, 5582.106862902078, 5636.1827535008015, 5678.0, 9170.266378689555, 9174.908250917406, 9267.212286955684, 9292.72121836346, 9295.860562091439, 8032.71476563747, 8615.956920215358, 9147.817043659223, 9490.383125096847, 9587.904120958701, 6323.20959880015, 6524.739010439529, 7442.813929651802, 7677.692892284246, 7773.650185741768, 7581.658825352983, 7851.411144164096, 7854.842903142019, 7937.2459616336355, 8054.395920306118, 6875.621840798604, 7046.506744527834, 7108.46686498525, 7197.856042879218, 7224.494285399972, 6844.315568443116, 6860.211075726355, 6922.480813938874, 6953.617551034742, 7055.435565154673, 5898.938191125639, 6045.21412216409, 6263.6241825490615, 6318.6840657965995, 6436.678166091583, 4367.45406824147, 4801.975990120027, 4921.409430868319, 5011.072951503984, 5111.335526381696, 4882.121218180761, 4955.65310428281, 5036.597072234139, 5073.264376665436, 5205.92191117126], "value": 4434.068864397863, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.318333990251, 351.53246182577175, 365.55402409060946, 369.30386221971577, 373.34477991125556, 4792.440768708299, 5346.866328341794, 5363.463653634634, 5387.622866399359, 5419.295491586093, 4323.1137616788565, 4471.374007638933, 4491.1017796440765, 5219.5041471060285, 5239.345081864767, 3611.880317101694, 3910.76535407875, 4119.423280740699, 4191.748495090289, 4226.344916537942, 3225.5645487859074, 3721.5348081489815, 3859.8070096495203, 3902.8876770120523, 4229.344451610005, 3520.806355650438, 3789.2989796887528, 3961.4255932889714, 4174.97912510437, 4200.768957707325, 4138.689598280111, 4163.562825903297, 4173.770442625654, 4180.916381672349, 4201.537830838605, 3307.5038744188323, 3568.6252943440822, 3702.5927148013698, 3703.518542589473, 3709.51776269086, 4485.506594274627, 4508.89532064643, 4880.804767809319, 5152.92270615941, 5210.505002024839, 4193.077522944947, 4411.801468933906, 4790.185668436339, 4857.9028419431415, 4879.365682461293, 5075.249039081511, 5215.348081489814, 5607.439256074401, 5634.408387119375, 5667.971660141714, 3935.311320518898, 4076.634327500291, 4098.446709694213, 4098.754074755524, 4121.8969525762095, 4892.706437613754, 5829.941700582982, 5919.230500035011, 5998.270786791999, 6029.336772954972, 3528.9647103528637, 4105.97947010269, 4216.482066456073, 4228.281192197333, 4231.851885184065, 3847.4228865670375, 4196.601322874322, 4296.419983302277, 4300.376445415397, 4304.7632380218765, 3985.720999530005, 4244.554321796167, 4842.322074909515, 4884.609231261491, 4926.531979462017, 4636.374089497942, 5227.9544091181915, 5556.416576259516, 5688.829335119953, 5733.369329373765, 1249.4315086635559, 1352.4657760184673, 1364.40648317982, 1387.5975966969465, 1388.7777955527067, 813.2599334605599, 890.5217339216869, 896.9461832290084, 949.368669834566, 989.7973961636502, 4366.607005369532, 4373.1472362888935, 4461.776911154429, 5063.480955713271, 5117.5394214520875, 5378.78484860609, 5380.2736630555155, 5479.0959491684225, 5618.269624948731, 5778.335491418541, 4819.325294458778, 4935.580475659537, 5015.489157589702, 5020.51894691067, 5026.924596130988, 2760.3437937237495, 2834.386157649014, 2937.2510009947327, 2937.7649788016906, 2947.0991210669, 4918.942427378131, 5504.697241651667, 5613.803516876889, 5760.107183386582, 5825.533957283407, 5128.948710512922, 5471.534919531884, 5640.153976903415, 5679.744411501492, 5679.858003549862, 5179.736765837498, 5320.563447868976, 5436.700981446058, 5516.648421136847, 5607.074832652566, 4433.135538569948, 5104.9489505104475, 5354.437784032776, 5372.979133964534, 5453.909567824132, 4777.402824646919, 4911.9754401227765, 5003.399592048978, 5381.730913454233, 5546.0, 5186.6369354144845, 5219.791208351623, 5532.28080349552, 5703.971480142573, 5736.877355378537, 3312.3832172584353, 3669.7431179818223, 3814.3706288463204, 3955.545112312121, 3992.5208974923194, 4436.578525040116, 5014.6991180529285, 5182.714950677749, 5510.724463776875, 5518.255035570147, 4392.6705497087205, 4519.0, 5344.396680995608, 5374.892502150014, 5381.48875856793, 4451.777411129366, 4481.663875209307, 4634.173154375895, 4878.219484882402, 5254.343207099113, 4578.633709303314, 4872.634552408655, 4983.825566105169, 5275.920861187008, 5318.484231993863, 4720.881977950578, 4767.4517430495935, 5048.545630893237, 5116.513931176532, 5334.039872822929, 5364.927014597019, 5511.751971161388, 5528.9723551381985, 5544.972275138599, 5561.499465048165], "value": 4034.4735008739353, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.3317464519961, 731.3856360657048, 732.3811379384418, 786.2177133751918, 849.4478588917204, 14841.777373339395, 15693.921530392347, 15716.449924252653, 15742.842571574281, 15796.052236865797, 14520.273986300695, 14596.197209153492, 14621.415143394279, 14627.195504247262, 14652.706945861102, 680.7719413996317, 705.0622671846432, 851.1531026628513, 868.4919322196523, 883.3419102768446, 868.3096937934325, 874.1564390363299, 875.181705105725, 875.605977310213, 877.4954401219293, 896.5562046786818, 915.0483497162943, 940.8682784410188, 944.8960614332418, 947.1948843483053, 940.05994005994, 940.9905900941005, 941.3504681769557, 943.6084025129563, 945.229637845154, 539.6384422436937, 556.3379578301808, 677.4140368581191, 694.6839188169371, 700.5131433653613, 15464.845351546453, 15483.845161548466, 15492.147931863743, 15498.845011549853, 15507.844921550753, 13923.094998825043, 13933.233672148028, 13940.86059139416, 13947.163170209718, 13990.86009139916, 12736.172148810298, 12981.480740770357, 13004.0, 13093.541726039639, 13497.055206135574, 4450.777461126931, 4499.75251361175, 4524.592786649185, 4688.507706690816, 4699.483056863743, 104260.0, 104580.47709761403, 105389.94610053879, 105850.0, 108308.45845770721, 11088.22382433238, 11152.776944461066, 11192.832107518394, 11285.435728213557, 11614.30314181135, 22439.88780056122, 22592.209272675376, 22790.088396463954, 22854.657180142312, 22893.427664308525, 7429.442791790637, 7443.574664229521, 7448.813779655552, 7472.813179670552, 7665.578393188428, 3874.8837534874, 3963.841446342172, 3965.2466031453932, 3965.6629186518894, 3969.503812023497, 3024.561438591392, 3435.604905435857, 3528.4530897710897, 3531.69980551656, 3539.9292014159573, 621.1366200980641, 631.5957787016313, 634.8063840528591, 642.1363266406676, 651.0137142229526, 582.496140838172, 611.4863514647717, 615.7660089166178, 618.3414663383483, 650.5251166648374, 9185.265178785681, 9412.341136120544, 9482.288828337902, 9649.710508684751, 9792.951035244778, 586.9530437564985, 604.9243844519435, 610.1092405088532, 614.0543562913064, 622.6357580815184, 4165.958340416618, 4383.62739167168, 4411.360352748833, 4415.116976604692, 4531.478879928851, 98159.50920245495, 99440.0, 100259.0, 102158.0, 104694.0, 94214.52892735493, 94560.0, 94588.0541194593, 94616.0, 95515.52242238744, 81651.18348816555, 81967.9999999988, 83138.54090640912, 84795.15204848119, 85535.0, 6882.8623427532175, 8387.203215694499, 8530.530820804666, 8703.56482175876, 8901.866472003054, 41147.0, 46129.76935115437, 48065.75967120142, 49778.751106244235, 53793.92412151813, 63400.0, 65347.67326163339, 65760.67119664562, 66996.33003669902, 67770.32229677838, 12478.438470268678, 12707.0, 12715.745685086062, 12729.618111456672, 13102.410391532214, 11415.258008229439, 11927.284362938251, 14276.30002396331, 14657.853421465648, 14688.045277056957, 11555.88444115548, 13274.0, 13283.601491955256, 13292.0, 13333.599992000256, 12600.0, 13131.671708207372, 13135.737285254432, 13357.065005449525, 13713.931430342784, 12537.498500059895, 12863.485460581473, 13048.54330098442, 13061.151025183333, 13333.0, 11012.215883635572, 11016.724581885197, 11221.495032723384, 11464.31214127155, 11551.537938482703, 10748.462576870968, 10853.077488413574, 10890.401027943539, 11276.32342059479, 11524.13568982313], "value": 7434.7125553792275, "unit": "score"}], "name": "dromaeo_css", "value": 7304.768247196268, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 113.0, 113.0, 114.0, 113.0, 113.0, 113.0, 110.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 109.0, 108.0, 145.0, 110.0, 111.0, 108.0, 107.0, 131.0, 110.0], "value": 118.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 187.0, 184.0, 187.0, 184.0, 184.0, 184.0, 185.0, 230.0, 208.0], "value": 191.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 60.0, 87.0, 60.0, 58.0, 59.0, 58.0, 85.0, 59.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 67.0, 85.0, 70.0, 69.0, 69.0, 83.0, 69.0, 67.0, 85.0], "value": 75.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 91.0, 88.0, 90.0, 119.0, 91.0, 88.0, 91.0, 89.0], "value": 92.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 85.0, 88.0, 89.0, 88.0, 87.0, 87.0, 89.0, 86.0], "value": 87.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 53.0, 53.0, 55.0, 55.0, 55.0, 55.0, 55.0, 54.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 75.0, 82.0, 81.0, 74.0, 71.0, 69.0, 73.0, 71.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 86.0, 87.0, 87.0, 86.0, 90.0, 86.0, 86.0, 90.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 150.0, 150.0, 140.0, 139.0, 141.0, 134.0, 137.0, 185.0, 150.0], "value": 147.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 167.0, 176.0, 171.0, 175.0, 174.0, 172.0, 174.0, 172.0, 172.0], "value": 172.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 67.0, 66.0, 67.0, 65.0, 66.0, 65.0, 65.0], "value": 66.4, "unit": "score"}], "name": "kraken", "value": 1441.4, "alertThreshold": 2.0}]} 07:36:25 INFO - Return code: 0 07:36:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:36:25 INFO - # TBPL SUCCESS # 07:36:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:36:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:36:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:36:25 INFO - 2017-01-09 07:36:25.687 screenresolution[1659:10495] starting screenresolution argv=screenresolution get 07:36:25 INFO - 2017-01-09 07:36:25.689 screenresolution[1659:10495] Display 0: 1600x1200x32@60 07:36:25 INFO - 2017-01-09 07:36:25.695 screenresolution[1660:10499] starting screenresolution argv=screenresolution list 07:36:25 INFO - Available Modes on Display 0 07:36:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:36:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:36:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:36:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:36:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:36:25 INFO - Intel Iris: 07:36:25 INFO - Chipset Model: Intel Iris 07:36:25 INFO - Type: GPU 07:36:25 INFO - Bus: Built-In 07:36:25 INFO - VRAM (Dynamic, Max): 1536 MB 07:36:25 INFO - Vendor: Intel (0x8086) 07:36:25 INFO - Device ID: 0x0a2e 07:36:25 INFO - Revision ID: 0x0009 07:36:25 INFO - Displays: 07:36:25 INFO - Display: 07:36:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:36:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:36:25 INFO - Display Serial Number: 1600x1200 60 07:36:25 INFO - Main Display: Yes 07:36:25 INFO - Mirror: Off 07:36:25 INFO - Online: Yes 07:36:25 INFO - Rotation: Supported 07:36:25 INFO - Return code: 0 07:36:25 INFO - Running post-action listener: _resource_record_post_action 07:36:25 INFO - [mozharness: 2017-01-09 15:36:25.931648Z] Finished run-tests step (success) 07:36:25 INFO - Running post-run listener: _resource_record_post_run 07:36:25 INFO - Total resource usage - Wall time: 1201s; CPU: 54.0%; Read bytes: 31378432; Write bytes: 553490432; Read time: 307; Write time: 1663 07:36:25 INFO - TinderboxPrint: CPU usage
54.0% 07:36:25 INFO - TinderboxPrint: I/O read bytes / time
31,378,432 / 307 07:36:25 INFO - TinderboxPrint: I/O write bytes / time
553,490,432 / 1,663 07:36:25 INFO - TinderboxPrint: CPU idle
2,211.7 (46.0%) 07:36:25 INFO - TinderboxPrint: CPU system
966.4 (20.1%) 07:36:25 INFO - TinderboxPrint: CPU user
1,627.6 (33.9%) 07:36:25 INFO - TinderboxPrint: Swap in / out
277,209,088 / 0 07:36:25 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 239645184; Write bytes: 235324928; Read time: 17464; Write time: 505 07:36:26 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 29473792; Write bytes: 298750464; Read time: 285; Write time: 1112 07:36:26 INFO - Running post-run listener: _upload_blobber_files 07:36:26 INFO - Blob upload gear active. 07:36:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:36:26 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 07:36:26 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'] 07:36:26 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 07:36:26 INFO - (blobuploader) - INFO - Open directory for files ... 07:36:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:36:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:36:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:36:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:36:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:36:27 INFO - (blobuploader) - INFO - Done attempting. 07:36:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:36:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:36:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:36:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:36:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:36:28 INFO - (blobuploader) - INFO - Done attempting. 07:36:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:36:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:36:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:36:29 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:36:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:36:29 INFO - (blobuploader) - INFO - Done attempting. 07:36:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:36:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:36:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:36:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:36:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:36:32 INFO - (blobuploader) - INFO - Done attempting. 07:36:32 INFO - (blobuploader) - INFO - Iteration through files over. 07:36:32 INFO - Return code: 0 07:36:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:36:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:36:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6e077018d41e4dc46fdd1000c7b843550b2d3d6c72c4c1618bfcf1a8292080f17bc2c12006efaef5794bbef60c909aa7adc15f383b63d11e3979075fff5bdeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ad3e5473dadc3b061e63259978fcf347d98b7ec2d79ac4f8624306e7189302d6f796351ac7eb11b27664046bbe1d3b121d647a5102453a4a8466b0d0b1c6d10", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c0b41a7c59d16deb14ebc0e62829f064b14e521b1612bfa174685c3ef54664155f5f71b0f74fbf5651d794a65bb0ea060de31f89b7920f9edf438b41540fe82", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/277dfa1fcd291d314ee397c9046db2886534584d4a8c5571c8dd7be458dc6f3aaf72a2551d3f93a76fd0047c4ed8158e55db2e2ced90656958c0b3e3646e316c"} 07:36:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:36:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:36:32 INFO - Contents: 07:36:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6e077018d41e4dc46fdd1000c7b843550b2d3d6c72c4c1618bfcf1a8292080f17bc2c12006efaef5794bbef60c909aa7adc15f383b63d11e3979075fff5bdeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ad3e5473dadc3b061e63259978fcf347d98b7ec2d79ac4f8624306e7189302d6f796351ac7eb11b27664046bbe1d3b121d647a5102453a4a8466b0d0b1c6d10", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c0b41a7c59d16deb14ebc0e62829f064b14e521b1612bfa174685c3ef54664155f5f71b0f74fbf5651d794a65bb0ea060de31f89b7920f9edf438b41540fe82", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/277dfa1fcd291d314ee397c9046db2886534584d4a8c5571c8dd7be458dc6f3aaf72a2551d3f93a76fd0047c4ed8158e55db2e2ced90656958c0b3e3646e316c"} 07:36:32 INFO - Running post-run listener: copy_logs_to_upload_dir 07:36:32 INFO - Copying logs to upload dir... 07:36:32 INFO - mkdir: /builds/slave/test/build/upload/logs 07:36:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.218236 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2017-01-09 07:36:32.963588) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 07:36:32.967169) ========= 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.M9aVhBGul7/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.VTGLgvMVUV/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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/b6e077018d41e4dc46fdd1000c7b843550b2d3d6c72c4c1618bfcf1a8292080f17bc2c12006efaef5794bbef60c909aa7adc15f383b63d11e3979075fff5bdeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ad3e5473dadc3b061e63259978fcf347d98b7ec2d79ac4f8624306e7189302d6f796351ac7eb11b27664046bbe1d3b121d647a5102453a4a8466b0d0b1c6d10", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c0b41a7c59d16deb14ebc0e62829f064b14e521b1612bfa174685c3ef54664155f5f71b0f74fbf5651d794a65bb0ea060de31f89b7920f9edf438b41540fe82", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/277dfa1fcd291d314ee397c9046db2886534584d4a8c5571c8dd7be458dc6f3aaf72a2551d3f93a76fd0047c4ed8158e55db2e2ced90656958c0b3e3646e316c"} build_url:https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010960 build_url: 'https://queue.taskcluster.net/v1/task/SGfc9u-6SDOV3ygFyxpY_w/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/b6e077018d41e4dc46fdd1000c7b843550b2d3d6c72c4c1618bfcf1a8292080f17bc2c12006efaef5794bbef60c909aa7adc15f383b63d11e3979075fff5bdeb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ad3e5473dadc3b061e63259978fcf347d98b7ec2d79ac4f8624306e7189302d6f796351ac7eb11b27664046bbe1d3b121d647a5102453a4a8466b0d0b1c6d10", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c0b41a7c59d16deb14ebc0e62829f064b14e521b1612bfa174685c3ef54664155f5f71b0f74fbf5651d794a65bb0ea060de31f89b7920f9edf438b41540fe82", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/277dfa1fcd291d314ee397c9046db2886534584d4a8c5571c8dd7be458dc6f3aaf72a2551d3f93a76fd0047c4ed8158e55db2e2ced90656958c0b3e3646e316c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 07:36:33.002097) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 07:36:33.002419) ========= 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.M9aVhBGul7/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.VTGLgvMVUV/Listeners TMPDIR=/var/folders/8q/yndjsd4s3gdcrwzkrbfc4fc000000w/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.005238 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 07:36:33.050697) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 07:36:33.051175) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 07:36:33.051950) ========= ========= Total master_lag: 0.19 =========