builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0034 starttime: 1495637281.63 results: success (0) buildid: 20170524055525 builduid: 3ad9b3dc179e4487b869296170ff7adb revision: 7166b44658df0723b16d43b7f884deb73c99f0ba ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:01.626079) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:01.626676) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:01.627068) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:01.655216) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:01.655980) ========= 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.WCFM0aBFni/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.2RLxaVQMF5/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-05-24 07:48:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.1M=0.001s 2017-05-24 07:48:01 (15.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.135362 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:01.821224) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:01.821984) ========= 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.WCFM0aBFni/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.2RLxaVQMF5/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.030473 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:01.875587) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 07:48:01.876141) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7166b44658df0723b16d43b7f884deb73c99f0ba --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7166b44658df0723b16d43b7f884deb73c99f0ba --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WCFM0aBFni/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.2RLxaVQMF5/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-05-24 07:48:01,977 truncating revision to first 12 chars 2017-05-24 07:48:01,977 Setting DEBUG logging. 2017-05-24 07:48:01,978 attempt 1/10 2017-05-24 07:48:01,978 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/7166b44658df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 07:48:03,017 unpacking tar archive at: mozilla-beta-7166b44658df/testing/mozharness/ program finished with exit code 0 elapsedTime=1.343792 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 07:48:03.249604) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:03.250677) ========= script_repo_revision: 7166b44658df0723b16d43b7f884deb73c99f0ba ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:03.251836) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:03.252844) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 07:48:03.275598) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-24 07:48:03.276752) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WCFM0aBFni/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.2RLxaVQMF5/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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:48:03 INFO - MultiFileLogger online at 20170524 07:48:03 in /builds/slave/test 07:48:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 07:48:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:48:03 INFO - {'append_to_log': False, 07:48:03 INFO - 'base_work_dir': '/builds/slave/test', 07:48:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:48:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:48:03 INFO - 'branch': 'Mozilla-Beta', 07:48:03 INFO - 'buildbot_json_path': 'buildprops.json', 07:48:03 INFO - 'config_files': ('talos/mac_config.py',), 07:48:03 INFO - 'default_actions': ('clobber', 07:48:03 INFO - 'read-buildbot-config', 07:48:03 INFO - 'download-and-extract', 07:48:03 INFO - 'populate-webroot', 07:48:03 INFO - 'create-virtualenv', 07:48:03 INFO - 'install', 07:48:03 INFO - 'run-tests'), 07:48:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:48:03 INFO - 'download_minidump_stackwalk': True, 07:48:03 INFO - 'download_symbols': 'ondemand', 07:48:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:48:03 INFO - 'tooltool.py': '/tools/tooltool.py', 07:48:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:48:03 INFO - '/tools/misc-python/virtualenv.py')}, 07:48:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:48:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:48:03 INFO - 'gecko_profile': False, 07:48:03 INFO - 'gecko_profile_interval': 0, 07:48:03 INFO - 'installer_path': 'installer.exe', 07:48:03 INFO - 'log_level': 'info', 07:48:03 INFO - 'log_name': 'talos', 07:48:03 INFO - 'log_to_console': True, 07:48:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:48:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:48:03 INFO - 'opt_config_files': (), 07:48:03 INFO - 'pip_index': False, 07:48:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:48:03 INFO - 'cmd': ('bash', 07:48:03 INFO - '-c', 07:48:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:48:03 INFO - 'enabled': True, 07:48:03 INFO - 'halt_on_failure': False, 07:48:03 INFO - 'name': 'check_screen_resolution'},), 07:48:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:48:03 INFO - 'cmd': ('bash', 07:48:03 INFO - '-c', 07:48:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:48:03 INFO - 'enabled': True, 07:48:03 INFO - 'halt_on_failure': False, 07:48:03 INFO - 'name': 'check_screen_resolution'},), 07:48:03 INFO - 'run_cmd_checks_enabled': True, 07:48:03 INFO - 'suite': 'dromaeojs', 07:48:03 INFO - 'system_bits': '32', 07:48:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:48:03 INFO - 'title': 't-yosemite-r7-0034', 07:48:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:48:03 INFO - 'use_talos_json': True, 07:48:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:48:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:48:03 INFO - 'work_dir': 'build'} 07:48:03 INFO - [mozharness: 2017-05-24 14:48:03.498648Z] Running clobber step. 07:48:03 INFO - Running pre-action listener: _resource_record_pre_action 07:48:03 INFO - Running main action method: clobber 07:48:03 INFO - rmtree: /builds/slave/test/build 07:48:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:48:06 INFO - Running post-action listener: _resource_record_post_action 07:48:06 INFO - [mozharness: 2017-05-24 14:48:06.113831Z] Finished clobber step (success) 07:48:06 INFO - [mozharness: 2017-05-24 14:48:06.113969Z] Running read-buildbot-config step. 07:48:06 INFO - Running pre-action listener: _resource_record_pre_action 07:48:06 INFO - Running main action method: read_buildbot_config 07:48:06 INFO - Using buildbot properties: 07:48:06 INFO - { 07:48:06 INFO - "project": "", 07:48:06 INFO - "product": "firefox", 07:48:06 INFO - "script_repo_revision": "production", 07:48:06 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 07:48:06 INFO - "repository": "", 07:48:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 07:48:06 INFO - "buildid": "20170524055525", 07:48:06 INFO - "pgo_build": "False", 07:48:06 INFO - "basedir": "/builds/slave/test", 07:48:06 INFO - "buildnumber": 2, 07:48:06 INFO - "slavename": "t-yosemite-r7-0034", 07:48:06 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 07:48:06 INFO - "platform": "yosemite_r7", 07:48:06 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 07:48:06 INFO - "branch": "mozilla-beta", 07:48:06 INFO - "repo_path": "releases/mozilla-beta", 07:48:06 INFO - "revision": "7166b44658df0723b16d43b7f884deb73c99f0ba", 07:48:06 INFO - "stage_platform": "macosx64", 07:48:06 INFO - "builduid": "3ad9b3dc179e4487b869296170ff7adb", 07:48:06 INFO - "slavebuilddir": "test" 07:48:06 INFO - } 07:48:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 07:48:06 INFO - Running post-action listener: _resource_record_post_action 07:48:06 INFO - [mozharness: 2017-05-24 14:48:06.117607Z] Finished read-buildbot-config step (success) 07:48:06 INFO - [mozharness: 2017-05-24 14:48:06.117716Z] Running download-and-extract step. 07:48:06 INFO - Running pre-action listener: _resource_record_pre_action 07:48:06 INFO - Running main action method: download_and_extract 07:48:06 INFO - mkdir: /builds/slave/test/build/tests 07:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:48:06 INFO - trying https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 07:48:06 INFO - Downloading https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 07:48:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 07:48:07 INFO - Downloaded 1287 bytes. 07:48:07 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 07:48:07 INFO - Using the following test package requirements: 07:48:07 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:07 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 07:48:07 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:48:07 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:07 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 07:48:07 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:07 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 07:48:07 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 07:48:07 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:07 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 07:48:07 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:48:07 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:07 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 07:48:07 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:07 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 07:48:07 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:07 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 07:48:07 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:48:07 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 07:48:07 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 07:48:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:48:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:48:07 INFO - Fetch https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:48:09 INFO - Expected file size: 51393390 07:48:09 INFO - Obtained file size: 51393390 07:48:26 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 07:48:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:48:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:48:26 INFO - Fetch https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:48:27 INFO - Expected file size: 51393390 07:48:27 INFO - Obtained file size: 51393390 07:48:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 07:48:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 07:48:43 INFO - Fetch https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 07:48:45 INFO - Expected file size: 14404449 07:48:45 INFO - Obtained file size: 14404449 07:48:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:48:45 INFO - trying https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:48:45 INFO - Downloading https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 07:48:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 07:48:47 INFO - Downloaded 59646197 bytes. 07:48:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:48:47 INFO - mkdir: /builds/slave/test/properties 07:48:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:48:47 INFO - Writing to file /builds/slave/test/properties/build_url 07:48:47 INFO - Contents: 07:48:47 INFO - build_url:https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:48:48 INFO - Running post-action listener: _resource_record_post_action 07:48:48 INFO - Running post-action listener: set_extra_try_arguments 07:48:48 INFO - [mozharness: 2017-05-24 14:48:48.070806Z] Finished download-and-extract step (success) 07:48:48 INFO - [mozharness: 2017-05-24 14:48:48.070945Z] Running populate-webroot step. 07:48:48 INFO - Running pre-action listener: _resource_record_pre_action 07:48:48 INFO - Running main action method: populate_webroot 07:48:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:48:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:48:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:48:48 INFO - '--tppagecycles', 07:48:48 INFO - '7'], 07:48:48 INFO - 'tests': ['tsvgm']}}, 07:48:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:48:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:48:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:48:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:48:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:48:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:48:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:48:48 INFO - 'talos_options': ['--disable-e10s'], 07:48:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:48:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:48:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:48:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:48:48 INFO - 'talos_options': ['--disable-e10s'], 07:48:48 INFO - 'tests': ['damp', 'tps']}, 07:48:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:48:48 INFO - 'tests': ['damp', 'tps']}, 07:48:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:48:48 INFO - 'tests': ['dromaeo_dom']}, 07:48:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:48:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:48:48 INFO - 'tests': ['basic_compositor_video']}, 07:48:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:48:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:48:48 INFO - 'tests': ['a11yr', 07:48:48 INFO - 'ts_paint', 07:48:48 INFO - 'tpaint', 07:48:48 INFO - 'sessionrestore', 07:48:48 INFO - 'sessionrestore_no_auto_restore', 07:48:48 INFO - 'tabpaint']}, 07:48:48 INFO - 'other-e10s': {'tests': ['a11yr', 07:48:48 INFO - 'ts_paint', 07:48:48 INFO - 'tpaint', 07:48:48 INFO - 'sessionrestore', 07:48:48 INFO - 'sessionrestore_no_auto_restore', 07:48:48 INFO - 'tabpaint']}, 07:48:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:48:48 INFO - 'ts_paint', 07:48:48 INFO - 'tpaint', 07:48:48 INFO - 'sessionrestore', 07:48:48 INFO - 'sessionrestore_no_auto_restore', 07:48:48 INFO - 'tabpaint']}, 07:48:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:48:48 INFO - 'ts_paint', 07:48:48 INFO - 'tpaint', 07:48:48 INFO - 'sessionrestore', 07:48:48 INFO - 'sessionrestore_no_auto_restore', 07:48:48 INFO - 'tabpaint']}, 07:48:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:48:48 INFO - 'tests': ['a11yr', 07:48:48 INFO - 'ts_paint', 07:48:48 INFO - 'tpaint', 07:48:48 INFO - 'sessionrestore', 07:48:48 INFO - 'sessionrestore_no_auto_restore', 07:48:48 INFO - 'tabpaint']}, 07:48:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:48:48 INFO - 'tests': ['a11yr', 07:48:48 INFO - 'ts_paint', 07:48:48 INFO - 'tpaint', 07:48:48 INFO - 'sessionrestore', 07:48:48 INFO - 'sessionrestore_no_auto_restore', 07:48:48 INFO - 'tabpaint']}, 07:48:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:48:48 INFO - 'tests': ['tsvgx', 07:48:48 INFO - 'tsvgr_opacity', 07:48:48 INFO - 'tart', 07:48:48 INFO - 'tscrollx', 07:48:48 INFO - 'cart', 07:48:48 INFO - 'tsvg_static']}, 07:48:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:48:48 INFO - 'tsvgr_opacity', 07:48:48 INFO - 'tart', 07:48:48 INFO - 'tscrollx', 07:48:48 INFO - 'cart', 07:48:48 INFO - 'tsvg_static']}, 07:48:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:48:48 INFO - 'talos_options': ['--disable-e10s'], 07:48:48 INFO - 'tests': ['tp5o']}, 07:48:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:48:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:48:48 INFO - 'talos_options': ['--disable-e10s', 07:48:48 INFO - '--xperf_path', 07:48:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:48:48 INFO - 'tests': ['tp5n']}, 07:48:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:48:48 INFO - 'talos_options': ['--xperf_path', 07:48:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:48:48 INFO - 'tests': ['tp5n']}}, 07:48:48 INFO - 'talos.zip': {'path': '', 07:48:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:48:48 INFO - Running post-action listener: _resource_record_post_action 07:48:48 INFO - [mozharness: 2017-05-24 14:48:48.089953Z] Finished populate-webroot step (success) 07:48:48 INFO - [mozharness: 2017-05-24 14:48:48.090093Z] Running create-virtualenv step. 07:48:48 INFO - Running pre-action listener: _resource_record_pre_action 07:48:48 INFO - Running main action method: create_virtualenv 07:48:48 INFO - Creating virtualenv /builds/slave/test/build/venv 07:48:48 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:48:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:48:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:48:48 INFO - Using real prefix '/tools/python27' 07:48:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:48:49 INFO - Installing distribute.............................................................................................................................................................................................done. 07:48:52 INFO - Installing pip.................done. 07:48:52 INFO - Return code: 0 07:48:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:48:52 INFO - Reading from file tmpfile_stdout 07:48:52 INFO - Output received: 07:48:52 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:48:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:48:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x102510b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102513768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78148b200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022eed30>, '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 0x102215db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e3be8>, '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.WCFM0aBFni/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.2RLxaVQMF5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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:48:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:48:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WCFM0aBFni/Render', 07:48:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:52 INFO - 'HOME': '/Users/cltbld', 07:48:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:52 INFO - 'LOGNAME': 'cltbld', 07:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:52 INFO - 'MOZ_NO_REMOTE': '1', 07:48:52 INFO - 'NO_EM_RESTART': '1', 07:48:52 INFO - 'PAGER': '/bin/cat', 07:48:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:52 INFO - 'PWD': '/builds/slave/test', 07:48:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:52 INFO - 'SHELL': '/bin/bash', 07:48:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2RLxaVQMF5/Listeners', 07:48:52 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 07:48:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:52 INFO - 'USER': 'cltbld', 07:48:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:52 INFO - 'XPC_FLAGS': '0x0', 07:48:52 INFO - 'XPC_SERVICE_NAME': '0', 07:48:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:48:52 INFO - Cleaning up... 07:48:52 INFO - Return code: 0 07:48:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:48:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x102510b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102513768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78148b200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022eed30>, '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 0x102215db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e3be8>, '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.WCFM0aBFni/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.2RLxaVQMF5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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:48:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:48:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WCFM0aBFni/Render', 07:48:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:52 INFO - 'HOME': '/Users/cltbld', 07:48:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:52 INFO - 'LOGNAME': 'cltbld', 07:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:52 INFO - 'MOZ_NO_REMOTE': '1', 07:48:52 INFO - 'NO_EM_RESTART': '1', 07:48:52 INFO - 'PAGER': '/bin/cat', 07:48:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:52 INFO - 'PWD': '/builds/slave/test', 07:48:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:52 INFO - 'SHELL': '/bin/bash', 07:48:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2RLxaVQMF5/Listeners', 07:48:52 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 07:48:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:52 INFO - 'USER': 'cltbld', 07:48:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:52 INFO - 'XPC_FLAGS': '0x0', 07:48:52 INFO - 'XPC_SERVICE_NAME': '0', 07:48:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:53 INFO - Downloading/unpacking psutil>=3.1.1 07:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:48:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:48:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:48:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:48:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:48:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:48:54 INFO - Installing collected packages: psutil 07:48:54 INFO - Running setup.py install for psutil 07:48:55 INFO - building 'psutil._psutil_osx' extension 07:48:55 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:48:55 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:48:55 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:48:55 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:48:55 INFO - building 'psutil._psutil_posix' extension 07:48:55 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:48:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:48:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:48:56 INFO - ^ 07:48:56 INFO - 1 warning generated. 07:48:56 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:48:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:48:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:48:56 INFO - Successfully installed psutil 07:48:56 INFO - Cleaning up... 07:48:56 INFO - Return code: 0 07:48:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:48:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:48:56 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/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 0x102510b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102513768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78148b200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022eed30>, '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 0x102215db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e3be8>, '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.WCFM0aBFni/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.2RLxaVQMF5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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:48:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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:48:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:48:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WCFM0aBFni/Render', 07:48:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:48:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:48:56 INFO - 'HOME': '/Users/cltbld', 07:48:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:48:56 INFO - 'LOGNAME': 'cltbld', 07:48:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:48:56 INFO - 'MOZ_NO_REMOTE': '1', 07:48:56 INFO - 'NO_EM_RESTART': '1', 07:48:56 INFO - 'PAGER': '/bin/cat', 07:48:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:48:56 INFO - 'PWD': '/builds/slave/test', 07:48:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:48:56 INFO - 'SHELL': '/bin/bash', 07:48:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2RLxaVQMF5/Listeners', 07:48:56 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 07:48:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:48:56 INFO - 'USER': 'cltbld', 07:48:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:48:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:48:56 INFO - 'XPC_FLAGS': '0x0', 07:48:56 INFO - 'XPC_SERVICE_NAME': '0', 07:48:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:48:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:48:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:48:56 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:48:56 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:48:56 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:48:56 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:48:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:48:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:48:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:48:59 INFO - Installing collected packages: mozsystemmonitor 07:48:59 INFO - Running setup.py install for mozsystemmonitor 07:48:59 INFO - Successfully installed mozsystemmonitor 07:48:59 INFO - Cleaning up... 07:48:59 INFO - Return code: 0 07:48:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:48:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:48:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:48:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x102510b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102513768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78148b200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022eed30>, '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 0x102215db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e3be8>, '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.WCFM0aBFni/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.2RLxaVQMF5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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:49:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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:49:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:49:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WCFM0aBFni/Render', 07:49:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:00 INFO - 'HOME': '/Users/cltbld', 07:49:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:00 INFO - 'LOGNAME': 'cltbld', 07:49:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:00 INFO - 'MOZ_NO_REMOTE': '1', 07:49:00 INFO - 'NO_EM_RESTART': '1', 07:49:00 INFO - 'PAGER': '/bin/cat', 07:49:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:00 INFO - 'PWD': '/builds/slave/test', 07:49:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:00 INFO - 'SHELL': '/bin/bash', 07:49:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2RLxaVQMF5/Listeners', 07:49:00 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 07:49:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:00 INFO - 'USER': 'cltbld', 07:49:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:00 INFO - 'XPC_FLAGS': '0x0', 07:49:00 INFO - 'XPC_SERVICE_NAME': '0', 07:49:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:00 INFO - Downloading/unpacking jsonschema==2.5.1 07:49:00 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:49:00 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:49:00 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:49:00 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:49:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:49:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:02 INFO - Downloading functools32-3.2.3-2.tar.gz 07:49:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:49:02 INFO - warning: no files found matching '*.txt' 07:49:02 INFO - no previously-included directories found matching 'build' 07:49:02 INFO - no previously-included directories found matching 'dist' 07:49:02 INFO - no previously-included directories found matching '.git*' 07:49:02 INFO - Installing collected packages: jsonschema, functools32 07:49:02 INFO - Running setup.py install for functools32 07:49:02 INFO - warning: no files found matching '*.txt' 07:49:02 INFO - no previously-included directories found matching 'build' 07:49:02 INFO - no previously-included directories found matching 'dist' 07:49:02 INFO - no previously-included directories found matching '.git*' 07:49:02 INFO - Successfully installed jsonschema functools32 07:49:02 INFO - Cleaning up... 07:49:02 INFO - Return code: 0 07:49:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:49:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x102510b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102513768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78148b200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022eed30>, '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 0x102215db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e3be8>, '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.WCFM0aBFni/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.2RLxaVQMF5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:49:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WCFM0aBFni/Render', 07:49:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:02 INFO - 'HOME': '/Users/cltbld', 07:49:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:02 INFO - 'LOGNAME': 'cltbld', 07:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:02 INFO - 'MOZ_NO_REMOTE': '1', 07:49:02 INFO - 'NO_EM_RESTART': '1', 07:49:02 INFO - 'PAGER': '/bin/cat', 07:49:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:02 INFO - 'PWD': '/builds/slave/test', 07:49:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:02 INFO - 'SHELL': '/bin/bash', 07:49:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2RLxaVQMF5/Listeners', 07:49:02 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 07:49:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:02 INFO - 'USER': 'cltbld', 07:49:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:02 INFO - 'XPC_FLAGS': '0x0', 07:49:02 INFO - 'XPC_SERVICE_NAME': '0', 07:49:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:49:02 INFO - Cleaning up... 07:49:02 INFO - Return code: 0 07:49:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:49:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:49:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x102510b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102513768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78148b200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022eed30>, '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 0x102215db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e3be8>, '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.WCFM0aBFni/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.2RLxaVQMF5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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:49:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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:49:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:49:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WCFM0aBFni/Render', 07:49:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:02 INFO - 'HOME': '/Users/cltbld', 07:49:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:02 INFO - 'LOGNAME': 'cltbld', 07:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:02 INFO - 'MOZ_NO_REMOTE': '1', 07:49:02 INFO - 'NO_EM_RESTART': '1', 07:49:02 INFO - 'PAGER': '/bin/cat', 07:49:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:02 INFO - 'PWD': '/builds/slave/test', 07:49:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:02 INFO - 'SHELL': '/bin/bash', 07:49:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2RLxaVQMF5/Listeners', 07:49:02 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 07:49:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:02 INFO - 'USER': 'cltbld', 07:49:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:02 INFO - 'XPC_FLAGS': '0x0', 07:49:02 INFO - 'XPC_SERVICE_NAME': '0', 07:49:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:03 INFO - Downloading/unpacking blobuploader==1.2.4 07:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:04 INFO - Downloading blobuploader-1.2.4.tar.gz 07:49:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:49:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:49: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:49: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:49: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:49: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:49:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:49:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:49:05 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:49:05 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:49:05 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:49:05 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:49:05 INFO - Downloading docopt-0.6.1.tar.gz 07:49:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:49:05 INFO - Installing collected packages: blobuploader, requests, docopt 07:49:05 INFO - Running setup.py install for blobuploader 07:49:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:49:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:49:05 INFO - Running setup.py install for requests 07:49:06 INFO - Running setup.py install for docopt 07:49:06 INFO - Successfully installed blobuploader requests docopt 07:49:06 INFO - Cleaning up... 07:49:06 INFO - Return code: 0 07:49:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:49:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:06 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/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 0x102510b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102513768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78148b200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022eed30>, '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 0x102215db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e3be8>, '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.WCFM0aBFni/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.2RLxaVQMF5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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:49:06 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:49:06 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:49:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WCFM0aBFni/Render', 07:49:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:06 INFO - 'HOME': '/Users/cltbld', 07:49:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:06 INFO - 'LOGNAME': 'cltbld', 07:49:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:06 INFO - 'MOZ_NO_REMOTE': '1', 07:49:06 INFO - 'NO_EM_RESTART': '1', 07:49:06 INFO - 'PAGER': '/bin/cat', 07:49:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:06 INFO - 'PWD': '/builds/slave/test', 07:49:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:06 INFO - 'SHELL': '/bin/bash', 07:49:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2RLxaVQMF5/Listeners', 07:49:06 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 07:49:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:06 INFO - 'USER': 'cltbld', 07:49:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:06 INFO - 'XPC_FLAGS': '0x0', 07:49:06 INFO - 'XPC_SERVICE_NAME': '0', 07:49:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:49:06 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-WCdjKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:49:07 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-3R26tD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:49:07 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-lHzTcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:49:07 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-MaxcNk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:49:07 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-6cgLlY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:49:07 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-QRae9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:49:07 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-dNyXSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:49:07 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-4K6UMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:49:07 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-urR0t3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:49:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:49:07 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-xm6BDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:49:08 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-nd_ICn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:49:08 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ttZt53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:49:08 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-SWqgpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:49:08 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-he8sJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:08 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-EZ_4o_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:49:08 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-V5sOGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:49:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:49:08 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-yz_o22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:49:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:49:08 INFO - Running setup.py install for manifestparser 07:49:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:49:08 INFO - Running setup.py install for mozcrash 07:49:09 INFO - Running setup.py install for mozdebug 07:49:09 INFO - Running setup.py install for mozdevice 07:49:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:49:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:49:09 INFO - Running setup.py install for mozfile 07:49:09 INFO - Running setup.py install for mozhttpd 07:49:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:49:09 INFO - Running setup.py install for mozinfo 07:49:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:49:09 INFO - Running setup.py install for mozInstall 07:49:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:49:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:49:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:49:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:49:10 INFO - Running setup.py install for mozleak 07:49:10 INFO - Running setup.py install for mozlog 07:49:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:49:10 INFO - Running setup.py install for moznetwork 07:49:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:49:10 INFO - Running setup.py install for mozprocess 07:49:10 INFO - Running setup.py install for mozprofile 07:49:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:49:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:49:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:49:10 INFO - Running setup.py install for mozrunner 07:49:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:49:11 INFO - Running setup.py install for mozscreenshot 07:49:11 INFO - Running setup.py install for moztest 07:49:11 INFO - Running setup.py install for mozversion 07:49:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:49:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:49:11 INFO - Cleaning up... 07:49:11 INFO - Return code: 0 07:49:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:49:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:49:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:11 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/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 0x102510b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102513768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78148b200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022eed30>, '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 0x102215db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e3be8>, '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.WCFM0aBFni/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.2RLxaVQMF5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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:49:11 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:49:11 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:49:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WCFM0aBFni/Render', 07:49:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:11 INFO - 'HOME': '/Users/cltbld', 07:49:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:11 INFO - 'LOGNAME': 'cltbld', 07:49:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:11 INFO - 'MOZ_NO_REMOTE': '1', 07:49:11 INFO - 'NO_EM_RESTART': '1', 07:49:11 INFO - 'PAGER': '/bin/cat', 07:49:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:11 INFO - 'PWD': '/builds/slave/test', 07:49:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:11 INFO - 'SHELL': '/bin/bash', 07:49:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2RLxaVQMF5/Listeners', 07:49:11 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 07:49:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:11 INFO - 'USER': 'cltbld', 07:49:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:11 INFO - 'XPC_FLAGS': '0x0', 07:49:11 INFO - 'XPC_SERVICE_NAME': '0', 07:49:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:49:11 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-cNJ3iP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:49:11 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:49:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:49:11 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-GuGQUj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:49:12 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-II9pKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:49:12 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-i9FmKd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:49:12 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Oa2xSR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:49:12 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-EpQXkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:49:12 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-EMCMHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:49:12 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-bALsSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:49:12 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-YbHpgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:49:12 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-9oqAiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:49:12 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:49:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:49:12 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-NeNEwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:49:13 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:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:49:13 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-3smyzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:49:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:49:13 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-q861G0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:49:13 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:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:49:13 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-szAmBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:49:13 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:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:13 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-elceib-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:49:13 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:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:49:13 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-vIgJxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:49:13 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:49:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:49:13 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-z4CJ5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:49:13 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:49:13 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:49:13 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:49:13 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:49:13 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:49:13 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:49:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:49:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:49:15 INFO - Downloading blessings-1.6.tar.gz 07:49:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:49:15 INFO - Installing collected packages: blessings 07:49:15 INFO - Running setup.py install for blessings 07:49:15 INFO - Successfully installed blessings 07:49:15 INFO - Cleaning up... 07:49:15 INFO - Return code: 0 07:49:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:49:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:49:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:49:15 INFO - Reading from file tmpfile_stdout 07:49:15 INFO - Current package versions: 07:49:15 INFO - blessings == 1.6 07:49:15 INFO - blobuploader == 1.2.4 07:49:15 INFO - docopt == 0.6.1 07:49:15 INFO - functools32 == 3.2.3-2 07:49:15 INFO - jsonschema == 2.5.1 07:49:15 INFO - manifestparser == 1.1 07:49:15 INFO - mozInstall == 1.12 07:49:15 INFO - mozcrash == 1.0 07:49:15 INFO - mozdebug == 0.1 07:49:15 INFO - mozdevice == 0.49 07:49:15 INFO - mozfile == 1.2 07:49:15 INFO - mozhttpd == 0.7 07:49:15 INFO - mozinfo == 0.9 07:49:15 INFO - mozleak == 0.1 07:49:15 INFO - mozlog == 3.4 07:49:15 INFO - moznetwork == 0.27 07:49:15 INFO - mozprocess == 0.25 07:49:15 INFO - mozprofile == 0.28 07:49:15 INFO - mozrunner == 6.13 07:49:15 INFO - mozscreenshot == 0.1 07:49:15 INFO - mozsystemmonitor == 0.3 07:49:15 INFO - moztest == 0.8 07:49:15 INFO - mozversion == 1.4 07:49:15 INFO - psutil == 3.1.1 07:49:15 INFO - requests == 1.2.3 07:49:15 INFO - wsgiref == 0.1.2 07:49:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:49:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:49:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:49:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:15 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/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 0x102510b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102513768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78148b200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022eed30>, '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 0x102215db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e3be8>, '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.WCFM0aBFni/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.2RLxaVQMF5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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:49:15 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:49:15 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:49:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WCFM0aBFni/Render', 07:49:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:15 INFO - 'HOME': '/Users/cltbld', 07:49:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:15 INFO - 'LOGNAME': 'cltbld', 07:49:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:15 INFO - 'MOZ_NO_REMOTE': '1', 07:49:15 INFO - 'NO_EM_RESTART': '1', 07:49:15 INFO - 'PAGER': '/bin/cat', 07:49:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:15 INFO - 'PWD': '/builds/slave/test', 07:49:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:15 INFO - 'SHELL': '/bin/bash', 07:49:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2RLxaVQMF5/Listeners', 07:49:15 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 07:49:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:15 INFO - 'USER': 'cltbld', 07:49:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:15 INFO - 'XPC_FLAGS': '0x0', 07:49:15 INFO - 'XPC_SERVICE_NAME': '0', 07:49:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:16 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:49:16 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:49:16 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:49:16 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:49:16 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:49:16 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:49:16 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:49:16 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:49:16 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:49:16 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:49:16 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:49:16 INFO - Cleaning up... 07:49:16 INFO - Return code: 0 07:49:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:49:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:49:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:49:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:49:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x102510b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102513768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa78148b200>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1022eed30>, '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 0x102215db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e3be8>, '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.WCFM0aBFni/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.2RLxaVQMF5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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:49:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:49:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WCFM0aBFni/Render', 07:49:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:16 INFO - 'HOME': '/Users/cltbld', 07:49:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:16 INFO - 'LOGNAME': 'cltbld', 07:49:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:16 INFO - 'MOZ_NO_REMOTE': '1', 07:49:16 INFO - 'NO_EM_RESTART': '1', 07:49:16 INFO - 'PAGER': '/bin/cat', 07:49:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:16 INFO - 'PWD': '/builds/slave/test', 07:49:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:16 INFO - 'SHELL': '/bin/bash', 07:49:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2RLxaVQMF5/Listeners', 07:49:16 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 07:49:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:16 INFO - 'USER': 'cltbld', 07:49:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:16 INFO - 'XPC_FLAGS': '0x0', 07:49:16 INFO - 'XPC_SERVICE_NAME': '0', 07:49:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:49:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:49:16 INFO - Cleaning up... 07:49:16 INFO - Return code: 0 07:49:16 INFO - Running post-action listener: _resource_record_post_action 07:49:16 INFO - Running post-action listener: _start_resource_monitoring 07:49:16 INFO - Starting resource monitoring. 07:49:16 INFO - [mozharness: 2017-05-24 14:49:16.470738Z] Finished create-virtualenv step (success) 07:49:16 INFO - [mozharness: 2017-05-24 14:49:16.471800Z] Running install step. 07:49:16 INFO - Running pre-action listener: _resource_record_pre_action 07:49:16 INFO - Running main action method: install 07:49:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:49:16 INFO - Reading from file tmpfile_stdout 07:49:16 INFO - Detecting whether we're running mozinstall >=1.0... 07:49:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:49:16 INFO - Reading from file tmpfile_stdout 07:49:16 INFO - Output received: 07:49:16 INFO - Usage: mozinstall [options] installer 07:49:16 INFO - Options: 07:49:16 INFO - -h, --help show this help message and exit 07:49:16 INFO - -d DEST, --destination=DEST 07:49:16 INFO - Directory to install application into. [default: 07:49:16 INFO - "/builds/slave/test"] 07:49:16 INFO - --app=APP Application being installed. [default: firefox] 07:49:16 INFO - mkdir: /builds/slave/test/build/application 07:49:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:49:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 07:49:32 INFO - Reading from file tmpfile_stdout 07:49:32 INFO - Output received: 07:49:32 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 07:49:32 INFO - Running post-action listener: _resource_record_post_action 07:49:32 INFO - [mozharness: 2017-05-24 14:49:32.082487Z] Finished install step (success) 07:49:32 INFO - [mozharness: 2017-05-24 14:49:32.082640Z] Running run-tests step. 07:49:32 INFO - Running pre-action listener: _resource_record_pre_action 07:49:32 INFO - Running main action method: run_tests 07:49:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:49:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:49:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:49:32 INFO - 2017-05-24 07:49:32.097 screenresolution[1624:7250] starting screenresolution argv=screenresolution get 07:49:32 INFO - 2017-05-24 07:49:32.100 screenresolution[1624:7250] Display 0: 1600x1200x32@60 07:49:32 INFO - 2017-05-24 07:49:32.106 screenresolution[1625:7252] starting screenresolution argv=screenresolution list 07:49:32 INFO - Available Modes on Display 0 07:49:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:49:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:49:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:49:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:49:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:49:32 INFO - Intel Iris: 07:49:32 INFO - Chipset Model: Intel Iris 07:49:32 INFO - Type: GPU 07:49:32 INFO - Bus: Built-In 07:49:32 INFO - VRAM (Dynamic, Max): 1536 MB 07:49:32 INFO - Vendor: Intel (0x8086) 07:49:32 INFO - Device ID: 0x0a2e 07:49:32 INFO - Revision ID: 0x0009 07:49:32 INFO - Displays: 07:49:32 INFO - Display: 07:49:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:49:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:49:32 INFO - Display Serial Number: 1600x1200 60 07:49:32 INFO - Main Display: Yes 07:49:32 INFO - Mirror: Off 07:49:32 INFO - Online: Yes 07:49:32 INFO - Rotation: Supported 07:49:32 INFO - Return code: 0 07:49:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:49:32 INFO - Python 2.7.3 07:49:32 INFO - Return code: 0 07:49:32 INFO - grabbing minidump binary from tooltool 07:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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:49:32 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 0x1022eed30>, '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 0x102215db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1022e3be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:49:32 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:49:32 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:49:32 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:49:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:49:32 INFO - Return code: 0 07:49:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:49:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:49:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:49:32 INFO - ENV: RUST_BACKTRACE is now 1 07:49:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:49:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:49:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0034 --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:49:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WCFM0aBFni/Render', 07:49:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:49:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:49:32 INFO - 'HOME': '/Users/cltbld', 07:49:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:49:32 INFO - 'LOGNAME': 'cltbld', 07:49:32 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:49:32 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:49:32 INFO - 'MOZ_NO_REMOTE': '1', 07:49:32 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:49:32 INFO - 'NO_EM_RESTART': '1', 07:49:32 INFO - 'PAGER': '/bin/cat', 07:49:32 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:49:32 INFO - 'PWD': '/builds/slave/test', 07:49:32 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:49:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:49:32 INFO - 'RUST_BACKTRACE': '1', 07:49:32 INFO - 'SHELL': '/bin/bash', 07:49:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2RLxaVQMF5/Listeners', 07:49:32 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 07:49:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:49:32 INFO - 'USER': 'cltbld', 07:49:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:49:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:49:32 INFO - 'XPC_FLAGS': '0x0', 07:49:32 INFO - 'XPC_SERVICE_NAME': '0', 07:49:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:49:32 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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:49:32 INFO - mozversion application_buildid: 20170524055525 07:49:32 INFO - mozversion application_changeset: 7166b44658df0723b16d43b7f884deb73c99f0ba 07:49:32 INFO - mozversion application_display_name: Firefox 07:49:32 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:49:32 INFO - mozversion application_name: Firefox 07:49:32 INFO - mozversion application_remotingname: firefox 07:49:32 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:49:32 INFO - mozversion application_vendor: Mozilla 07:49:32 INFO - mozversion application_version: 54.0 07:49:32 INFO - mozversion platform_buildid: 20170524055525 07:49:32 INFO - mozversion platform_changeset: 7166b44658df0723b16d43b7f884deb73c99f0ba 07:49:32 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:49:32 INFO - mozversion platform_version: 54.0 07:49:32 INFO - using testdate: 1495637372 07:49:32 INFO - actual date: 1495637372 07:49:32 INFO - starting webserver on 'localhost:49225' 07:49:32 INFO - SUITE-START | Running 2 tests 07:49:32 INFO - TEST-START | dromaeo_css 07:49:32 INFO - operating with platform_type : mac_ 07:49:32 INFO - Initialising browser for dromaeo_css test... 07:49:32 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpjFIy8_/profile http://localhost:49225/getInfo.html) 07:49:35 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 07:49:35 INFO - PROCESS | 1635 | colorDepth:24 07:49:35 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 07:49:35 INFO - PROCESS | 1635 | __metrics 07:49:35 INFO - PROCESS | 1635 | 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:49:35 INFO - PROCESS | 1635 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:49:36 INFO - TEST-INFO | 1635: exit 0 07:49:36 INFO - Browser initialized. 07:49:36 INFO - Running cycle 1/1 for dromaeo_css test... 07:49:36 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpjFIy8_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:49:48 INFO - PROCESS | 1638 | RSS: Main: 169259008 07:49:48 INFO - PROCESS | 1638 | 07:52:50 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49225/tests/dromaeo/cssquery-ext.html) 07:52:53 INFO - PROCESS | 1638 | RSS: Main: 405938176 07:52:53 INFO - PROCESS | 1638 | 07:55:56 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-ext.html (next: http://localhost:49225/tests/dromaeo/cssquery-jquery.html) 07:55:57 INFO - PROCESS | 1638 | RSS: Main: 304676864 07:55:57 INFO - PROCESS | 1638 | 07:58:59 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49225/tests/dromaeo/cssquery-mootools.html) 07:59:00 INFO - PROCESS | 1638 | RSS: Main: 280223744 07:59:00 INFO - PROCESS | 1638 | 08:02:03 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49225/tests/dromaeo/cssquery-prototype.html) 08:02:03 INFO - PROCESS | 1638 | RSS: Main: 273121280 08:02:03 INFO - PROCESS | 1638 | 08:05:07 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49225/tests/dromaeo/cssquery-yui.html) 08:05:07 INFO - PROCESS | 1638 | RSS: Main: 267169792 08:05:07 INFO - PROCESS | 1638 | 08:08:11 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-yui.html (next: http://localhost:49225/tests/dromaeo/cssquery-dojo.html) 08:08:11 INFO - PROCESS | 1638 | RSS: Main: 568827904 08:08:11 INFO - PROCESS | 1638 | 08:08:11 INFO - PROCESS | 1638 | __start_tp_report 08:08:11 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 08:08:11 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 08:08:11 INFO - PROCESS | 1638 | |i|pagename|runs| 08:08:11 INFO - PROCESS | 1638 | |0;dojo.html;2294.300238427281;2967.7180667836556;2973.494505933991;3025.682303358146;3061.892833750819;4633.30500424936;5108.667936584119;5145.125328694122;5150.150225212845;5201.349831271091;5141;5159.226116082589;5173.6119791015635;5190.8442746717565;5243.86890327742;4696.990147118368;4729.692569982949;4738.810447582093;4804.399450068741;4842.685225460352;4460.910781784362;4485.058137791068;4507.526709695484;4776.379070720802;4803.2795080738;4704.223803072489;4735.834245801398;4740.2889566564945;4744.501827308118;4750.667453278272;4723.740194289313;4726.456457507379;4733.5503127202865;4748.145333839908;4749.857504274877;4617.284320930261;4617.653675974316;4632.235681112612;4633.4671512775785;4652.232381657022;4008.7394319369146;5046.369203849519;5063.240513922904;5074.06129865976;5092.134337162691;5499.120140777496;5510.862228444281;5515.503604675599;5523.558115350803;5537.640053396516;5580.581456390787;5636.098224284095;5638.661680299195;5691.373948865621;5704.22992895962;5292.629515933886;5331.306930099062;5342.984832881779;5348.358197016383;5368.275282836845;5413.106837371868;5907.527397808165;5934.19888315072;5946.643201407886;5969.611975221596;5442.047641662693;5452.672839629635;5454.672719636835;5496.340439147248;5590.664560126324;4663.766811659471;5627.212190293362;5670.432956704379;5766.740496677659;5798.420157984167;4619.376384188159;5588.133839254922;5630.577706672016;5633.8309850704545;5678.432156784371;5323.973380133152;5365.973170134124;5506.311711036121;5521.420250873681;5675.971620141873;3980.6616437602547;4304.935425968613;4423.845165419195;4464.732116073046;4473.843415480443;3817.923641527154;3996.4005399190355;4009.258287216874;4017.5178978522763;4120.835166593302;5128;5130.333056702605;5157.381114266312;5157.896842063138;5176.637635365563;5682.261306030189;5768.769249229984;5786.537077033827;5813.331466881363;5816.1566572846705;4890.535399137077;4954.578860796801;5056.898862022739;5091.439941606421;5099.745012749348;5003.974980125149;5016.423111342169;5028.396592408934;5057.089723849669;5063.974680126649;5889.469947704556;5925.318588362394;5927.347991720907;5943.167956486095;5957.16599676037;5433.701146436984;5509.338879334506;5671.97904377216;5759.596828222152;5806.912896306643;5867.149263356875;5891.823245302648;5905.970470147622;5908.379620139824;5916.112583112567;5504.587155963239;5505.03661859181;5519.530839878495;5539.861503462284;5546.861328466821;4581.7709114544805;5471.534919531725;5536.4186760389575;5599.972000139973;5655.1517272409465;5445.618806683494;5467.863303417446;5467.9453205467435;5517.641353312182;5541.362743284574;5597.048501754793;5624.465675760796;5626.212330273846;5627.18405831144;5668.404817494104;5412.864678383072;5418.674879507243;5425.593080518898;5427.674339539641;5441.564674825925;4556.407667003268;5270.631055826056;5579.944200557942;5595.412481689526;5596.048671725898;5281.339832520935;5297.125974214211;5310.442403547727;5423.457654234545;5445.319335083114 08:08:11 INFO - PROCESS | 1638 | |1;ext.html;778.964946577404;797.4497596658307;848.5502683577706;868.2272777228268;884.5311984648139;38912;39638;41573.7921310393;41628.791856040756;41739.74780756582;40776.38835417464;41037;41177.38233926486;41191.58808411915;41386.793066034705;7769.028871391076;9325.393849399785;9488.810223795519;9497.335186536946;9501.95249023754;7839.490433121858;7919.56442395668;7927.960360198191;7938.6427610757655;11621.767564648702;11654.359010254433;11690.473928673187;11710.941445292761;11716.589919352824;11738.41307934604;11571.942140289244;11596.898294661994;11631.941840290745;11640.592579259684;11664.591739289084;9443.291753118543;11349.978501934784;11364.261323013978;11385.829212561735;11389;38433.80783096095;38435;38479.230415391816;38635.03412414684;38635.61364386376;3878.1274213302063;3933.744306620089;3934.5081864766903;3947.1513624570857;3985.123272879962;27251.182464525897;29048.564271535943;29682.851585742148;29718.405631887243;29828.850855745583;3100.829454380008;3190.4735718606403;3193.457112290916;3315.96684033159;3332.7000569948823;336482;341491.9999999975;342119;342176;342635;41666.58333416627;42512.57487425086;44082.118357632666;50435.49564504381;50630.74684626627;51854.48145518572;52411.95176096457;54877.90244195174;54921.725391373584;55007;14972.850271497364;15004.174770387515;15030.32363543643;15050.397984080733;15080.245987700791;12718.364081795873;12757;12771.425285862158;12774.872251277367;12779.23324600527;11922.463489143009;11976.341301228516;11987.580434684744;12059.758804823854;12064.09519286057;4410.360497727812;4616.838410655678;5210.791568337224;5215.869603259949;5252.763625636855;797.5175019113469;859.9398042137116;861.3711990247155;871.1419252036738;874.8469017921839;27090.864545677145;28145.859270703517;28803.279918001797;28847.990320338686;28929.566056509168;975.0834215837091;978.8238117138983;988.4711679251566;1016.8271393863062;1022.4172221833633;12218.755624887452;14733.92633036828;14811.481598144228;15051.623709407353;15097.018693784868;425412;427230;427282;427365;427381;54385.45614543804;54402.45597543974;54442.45557544453;54627.453725463034;54679;1358.7146699193083;1386.8821150201943;1400.8108905297859;1442.949496767608;1450.0284809177876;25675.871620642523;26558;27180;27311.180664580093;27327.180184594494;37311;37451.81274093612;37501.06247343838;37566.43650345302;37579.624203757616;412890;414015;414120;414263;414788;23501.059957601505;23790.167344142872;24352.51294974055;28091.578626321032;28278.85860570684;34799.78200762961;36111.63888361083;37501.62498374981;37572.24855502929;37650.43524347192;27093.322666933484;27111.59332609973;27477.45045099047;27746.722532774413;28034.85982570074;26778.447663896794;26782.866085669448;26975;27754;27924.58113128264;30490.237744056576;31123.377532448772;31511;31557.684423156392;31593.21016974594;23756;24884.751152488243;27467.725322746515;27784.722152778213;27896.58155127715;21098.787722466044;22141.667874981566;22264.998075086984;22298.996545155183;22372.440688982904 08:08:11 INFO - PROCESS | 1638 | |2;jquery.html;357.7942682957299;383.631713554987;391.7943079883062;407.7594219410551;416.9979150104249;20226.494337641547;22320.665190022122;22923;22999.77000229997;23203.999999999978;22724;22909.656355154686;23255.186068487605;23572.174973875917;23598.174063907765;6466.418022377985;6784.253732089466;6876.415504682108;6960.930390696079;6965.233824279325;5966.433188847052;6670.6331151786635;7560.206178351276;7705.306522412981;7712.267334603203;6708.698108585101;7064.82337941553;7677.577733224671;7702.576358300291;7707.267809558083;7431.4797964142535;7664.540127592362;7733.497322674065;7759.9612001940195;7772.883406748903;7326.120865496121;7433.776986690408;7437.814054648623;7458.962705186494;7467.253274672544;25491.872540637363;26489;26545;26991.730082699316;27420.588691169647;2812.7609153222;2848.5157523220896;2866.3550701092086;2966.1398194523563;2989.95515067272;14642.780358294634;16160.272787724463;16606.83393166077;17234.310627574883;17272.740908886375;1981.6928376101728;2098.685197220429;2173.9021744021557;2180.705604743371;2226.154061456636;153142;154969;157244;158925;160148.98882583735;16578.83421165797;19345.226190952486;19729.112189951487;20469.59060818775;20741.377758667266;31839.681603184134;32533;32565.34869302648;32575.51136732951;32716.18209544733;10248.897511024794;10342.689719308433;10554.419506927192;10582.20633452494;10710.94644526788;7677.808054798563;8423.915760842314;8747.693830715894;8756.299496040301;8977.057408972094;9181.770455738526;9268.443893366419;9384.953075234716;9401;9407.90592094084;3690.1697118148204;3761.539383766941;3776.3957766757185;3780.6219378062524;3790.6019867913474;727.9745208917768;804.7827086686679;823.6664151018847;883.7304622090196;888.1163242573551;20316.796832031785;20987.160513579285;21050.315974721987;21057.78942210589;21368.824714640843;932.4218984229685;939.628846605587;946.5929650250458;949.0651708067548;965.4834663455158;10105.545250463747;10959.671209863716;10962.958518940688;10974;10998;698836;707109;711581;762133;767768;83910;84431.82329501938;110933.44533277283;116303;121111;993.4883534979491;1106;1186.9050475961903;1189.916705830601;1198.4367347346908;16506.669866602842;16625.750613741046;19950.60098797987;20334.389968300973;20366.59266814685;58172.70913645405;58352.4164758364;61377.07934381077;61975.38024619696;63075.6846215766;87034.02757699497;88046.55976720332;88727.55636221777;90194;90914.54542727245;9801.705948821547;10759.46202689878;10836.512356943798;11374.033207177483;11497.367644779282;12757.298348590917;12792.680182995127;12800.35998200105;12934.288614126313;13099.41052652652;11102.88897111051;11145.665630031112;11237.381943992832;11560.32375305559;11577.305361678325;10356.17150627963;10408.167346612101;10438.63464778729;10721.731956701145;10747.355158690503;11294.661160165208;11381.943090284827;11675.416229188677;11702.765944681229;11737.943585077384;10509.579616815548;11135.053520450852;11198.944005279922;11361.261518001304;11563.942180289381;11043.723906902393;11093.611723589636;11151.776964460827;11430.971212590906;11468.827967580326 08:08:11 INFO - PROCESS | 1638 | |3;mootools.html;1409.8167238259025;1431.1842249917552;1478.696867142236;1510.886683498738;1571.7249481340762;10467.424291663956;11202.935721106502;11212.27120237184;11213.943930280348;11218.887811121886;10056.497175141232;10115.190784737222;10391.584336626545;10665;10875.401852898107;2279.8290128240365;2329.2779238436055;2330.5804955108074;2333.5682898663717;2333.7082864641834;1875.109323071537;2183.639699449589;2187.639039558471;2190.1567896359857;2262.8459485662265;2104.4844013216707;2114.312848324293;2116.6824976253533;2120.4698825293676;2327.197116994634;1906.809319068089;2260.3386510914465;2274.9090036398525;2282.6233671444193;2290.1869836208075;2229.8662080275235;2273.818094552432;2274.3404412720292;2277.806386457153;2281.942951426211;10955.452227388678;11044.558217671283;11048.447577621087;11076.723081922935;11109.44452777358;5443.129099344086;5634.915476267859;5641.605087643826;5644.943550564483;5647.407022262644;3171.7462602991704;3285.162283617686;3447.9482807757904;3516.7362447816517;3522.23888055972;1019.7909428567126;1101.438266484091;1178.7740749620384;1193.8865807748336;1252.0359323321084;13442.12626179305;13754.31228438584;14162.575122746232;15155.393784248618;15215.467458638894;4003.2193722223888;4364.8433165211245;4399.318105693623;4532.864014079583;4568.817247310137;9311.813763724687;11660.242084264495;11663.950244477868;11670.416479176007;11689.590864319707;4731.526847315241;4889.315495830587;5227.712475813867;5539.9169012464845;5559.499645031886;5076.543111119943;5143.074246635641;5154.587632989427;5155.484451554815;5159.664621799646;3635.4365073413665;3694.538182727159;3756.7370284079852;3800.562935262425;3805.562360328542;972.5234635028785;981.7054883534911;982.9901700983041;985.1380042462845;998.1166667499315;710.3819676881146;814.8818421328992;843.9662413503391;858.4891989266376;887.4985633117268;6143.201383820075;6489.5781774184525;6583.374579414948;6782.796516104525;6870.20992585849;890.4078787606169;919.2508105893676;941.5480569326761;946.0917519181526;979.9755006124904;3724.5344331958504;3912.68698504119;4012.217617564547;4097.057676734368;4107.97946010274;12706.649269885102;13199.274039927896;13231.470741170246;13247.271400072896;13249.205047697169;13159.078864479587;13160.276184809927;13164.539241126515;13179.73640527184;13181.406836692371;9364.672236471692;9663.565139568736;9901.15840153595;9901.752456188653;9909.108180263667;6386.521010924106;6513.348665133638;6566.770163044271;6701.865962680817;6708.597484150967;11025.944870275598;12070.517179312728;12208.938955305166;12289.324087175291;12372.257664540157;9843.284167582055;12016;12274.263544187377;12608.243505389706;12656.6835829105;9734.36726612768;9955.352902061342;9989.250806189419;10178.745531361776;10182.643607473703;9824.950875245579;10068.949655251678;10129.139023183112;10195.7451063724;10226.6420675276;8475.703350382708;8532.957335213285;8617.310615150647;8783.209511144029;8858.778530536789;8398.664053437795;8495.95752021236;8567.4002819802;8587.484750914966;8668.089850565852;7733.38132949374;7819.726309579138;7820.687172513263;7914.604269786603;7928.335049639641;5632.408597097246;5724.398938111438;5812.099124635688;5818.650880947157;6040;5740.971295143498;5819.592628515963;5881.529477641864;5962.55280853929;5994.520438365008 08:08:11 INFO - PROCESS | 1638 | |4;prototype.html;349.22297887200983;352.2409208156422;359.1326945426793;359.7373917040562;360.15902866806005;3916.4712763776884;3959.722819402643;3974.383970484576;3981.7059455676876;3987.983064318602;3235.5146727990755;3821.5032045834073;3893.9026524336905;3896.7856767877756;3910.296146693594;2669.0925085470935;3229.6608856070125;3246.642869284377;3249;3309.7257555840933;3026.2888221267986;3315.6850099240532;3339.382214290352;3345.799252044873;3383.74621903357;3345.5149003394495;3359.5296658467832;3363.5291059251726;3387.44107222308;3414.7438942079443;2894.696056914025;3324.9168770780684;3361.3781450431625;3400.693937545608;3402.2515046689687;3040.9847950760327;3091.3817236552554;3122.5628412022334;3127.2650927032128;3139.3250451153112;3087.938241235163;3657.762245454037;3694.5751238607636;3739.3082279778055;3764.849406023783;3798.867039653599;3846.653801157909;3887.747296425723;3891.922161556753;3914.375534153323;3821;3828.5979972102805;4126.690498212646;4146.771927543984;4158.6465150462245;2977.672456029835;3202.2154572129743;3349.743846057728;3444.621091679913;3470.9305813883834;3833.0834582708644;3942.9605703943166;4089.8159582818844;4149.589139692519;4373.956260437387;3422.9657703423145;3449.2066824629974;3512.5960514540643;3513.6662017108333;3515.683588476998;3338.9499157512655;3355.6644335566734;3554.7689400189424;3563.5901871284614;3589.2313071275057;3275.8689652414114;3458.5849698036395;3571.339302229096;3664.59689434162;3690.741648084608;3381.7970921744773;4076.615180119604;4099.487564054493;4119.958800411958;4146.279294092978;1152.2798251093068;1200.9699757506132;1204.9759004819855;1207.7524107558106;1227.8342423772676;848.3764433141636;875.5228400521731;940.3981451870808;952.5856252530172;956.3974695941512;3652.817359132086;3730.7015438764834;3749.3005036373766;3764.214481283885;3798.6961043116485;3532.894013179609;4178.310578754532;4180.122174343422;4225.950414049184;4230.725002874803;3725.590185079639;3735.402335626287;3749.6250374962287;3794.679655301611;3801.8669346572738;1945.9221631134596;1967.3409407848244;2018.6063717575225;2162.9942076934303;2170.6852506386485;3802.524684414448;4020.8190631421658;4302.118065796504;4336.349547567827;4414;3726.32926073304;4178.937315940263;4303.827846886152;4332.978335108304;4342.415018518282;3714.7585406949;3982.5021872265966;4151.564085771011;4260.5301171095925;4316;3844.6924246060807;4211.220924129046;4223.852165174204;4229.492460904711;4249.46881639795;4123.958760412477;4206.7073444973175;4215.451991241119;4224.767637779829;4232.36514522824;3646.593248456006;4132.2561938851295;4366.956330436782;4368.716033457815;4395.922854002148;3214.8714051437682;3608.404613238786;3700.888973330804;3717.531575027903;3793.3740932745786;4250.426192463978;4332.3068309070395;4362.978185109054;4377.738584754378;4410.5080629444265;3951.1900060488106;4231.2806822839575;4273.124009577967;4283.464566929134;4286.571342865688;3392.5065077168756;3864.6811912852063;3898.947284233298;4165.500139983222;4182.519010313853;3360.6307553175443;3664.500402116026;4048.676105911579;4149.356849688304;4232.724872883253;3579.3557159711504;3780.5066998535976;4123.690723195712;4137.91724165521;4165.375193720966;3449.3963556378035;3577.712023671528;3682.963170368262;3985.900352491211;4082.1223436961195 08:08:11 INFO - PROCESS | 1638 | |5;yui.html;722.9746958856441;734.4785202506217;771.8842173673949;819.6188772220918;847.7922908887324;15364.769528457067;16996.83003169968;17063.976161430324;17074.658506829855;17122.14389280534;15051.56785100088;15704.136272505008;15705.685886282268;15750.448734294301;15874.444394446196;988.5502096546054;1005.557554675944;1018.3533456255257;1020.7244044108087;1022.7238645565693;997.8353571660667;998.151571164508;999.535216124502;1004.1163775877218;1004.7036124343336;1026.179056754597;1026.399556259589;1028.1928685981507;1030.8608337874402;1031.7575369788094;1023.4115383654354;1025.4462590201283;1027.3322340478674;1027.5581499955017;1027.8149933012016;755.5919803306208;758.5638258001671;759.0625577411892;760.0043942435373;763.9579823109726;14481.78277325841;14798.40806367744;14852;14995.250237488082;15061.322240499203;11997.760044799143;12334;12645;13482.528111516149;13699.315034248348;11263.21157518974;11447.313161210272;11597.362145082017;11870.762584748256;12002.69993250167;3754.5494540655022;3762.172322089136;3847.8460861565504;3922.4508568800393;4007.2586571484367;119399;121307.393463033;122269;122593.38703306516;124995.3750231252;10388.428636424918;10449.268551201494;10460.790784184273;10838.891611083945;10847.132229421628;22337.441563960707;23105.191318304114;23109.306720798402;23141.88429057844;23155.3053408398;7909.802254943557;7963.761087167394;8056.39577031725;8067.959660201662;8168.469049511763;3658.835352409148;4456.509783923766;4488.663350248679;4507.57178068083;4521.683482156218;2851.6435445569305;3380.543626610376;3477.3914564951033;3486.389881770731;3488.7383446241633;632.1623848400887;642.4667525953447;646.741303478603;659.5086660438004;662.0730976632657;527.7150338817073;583.7723287917715;629.7134803664321;641.8619996700731;652.9249136349285;9079.591418386188;9103.862017247844;9625.614975401046;10436.73908152287;10850.782984340269;588.2734822494216;617.3671986214076;642.3351830855042;650.847050943028;656.701200953565;4249.46881639795;4557.72653640776;5017.774200161001;5019.021290848323;5020.2720605511995;93465.53267233621;97290.51354743181;98943.50528247312;99913;101976;39136.80431597824;51654.48345516497;73215.26784732085;75421.49157016829;89243.66134507988;71843.28156718366;72892.07525284983;74040.42064571465;74446.62776686082;77464;10797.730056748644;10919.59164604032;11062.446877656246;11150.498227579941;11177.776444471228;38337.84986450299;44520.33219501775;47767.28349074836;48769.756151220434;49611.00777984348;45569.54430455786;61565.69217153885;62193.75612487816;62622.37377626165;62843.42891427751;12151.270923744603;12153.270803751802;12215.328156951098;12272.693182670504;12826.807597886225;14584.97905146672;14591.854081459049;14851.034682745587;14955.177465239516;15209.923950380176;13460.326983650973;13613.38739756733;14344.067635603653;14611.342489588207;14797.482088126864;13148.73702525925;13340.933295333462;14117.2235527047;14287.142771433748;14596.343164557833;12651.430685618985;13047;14108.858911410754;14147.080439771382;14227.786583201054;9661.323707340418;10148.188144948534;10333.431661258403;10353.17174626013;10354.792904141725;9913.950430247802;10817.296875703054;11237.550497980315;11258;11266.324020558794 08:08:11 INFO - PROCESS | 1638 | __end_tp_report 08:08:11 INFO - PROCESS | 1638 | __start_cc_report 08:08:11 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,2820 08:08:11 INFO - PROCESS | 1638 | __end_cc_report 08:08:11 INFO - PROCESS | 1638 | __startTimestamp1495638491514__endTimestamp 08:08:11 INFO - PROCESS | 1638 | 08:08:11 INFO - PROCESS | 1638 | ------- Summary: start ------- 08:08:11 INFO - PROCESS | 1638 | Number of tests: 6 08:08:11 INFO - PROCESS | 1638 | 08:08:11 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5167.52 Median:5348.36 stddev:637.76 (11.9%) stddev-sans-first:598.56 08:08:11 INFO - PROCESS | 1638 | Values: 2294.3 2967.7 2973.5 3025.7 3061.9 4633.3 5108.7 5145.1 5150.2 5201.3 5141.0 5159.2 5173.6 5190.8 5243.9 4697.0 4729.7 4738.8 4804.4 4842.7 4460.9 4485.1 4507.5 4776.4 4803.3 4704.2 4735.8 4740.3 4744.5 4750.7 4723.7 4726.5 4733.6 4748.1 4749.9 4617.3 4617.7 4632.2 4633.5 4652.2 4008.7 5046.4 5063.2 5074.1 5092.1 5499.1 5510.9 5515.5 5523.6 5537.6 5580.6 5636.1 5638.7 5691.4 5704.2 5292.6 5331.3 5343.0 5348.4 5368.3 5413.1 5907.5 5934.2 5946.6 5969.6 5442.0 5452.7 5454.7 5496.3 5590.7 4663.8 5627.2 5670.4 5766.7 5798.4 4619.4 5588.1 5630.6 5633.8 5678.4 5324.0 5366.0 5506.3 5521.4 5676.0 3980.7 4304.9 4423.8 4464.7 4473.8 3817.9 3996.4 4009.3 4017.5 4120.8 5128.0 5130.3 5157.4 5157.9 5176.6 5682.3 5768.8 5786.5 5813.3 5816.2 4890.5 4954.6 5056.9 5091.4 5099.7 5004.0 5016.4 5028.4 5057.1 5064.0 5889.5 5925.3 5927.3 5943.2 5957.2 5433.7 5509.3 5672.0 5759.6 5806.9 5867.1 5891.8 5906.0 5908.4 5916.1 5504.6 5505.0 5519.5 5539.9 5546.9 4581.8 5471.5 5536.4 5600.0 5655.2 5445.6 5467.9 5467.9 5517.6 5541.4 5597.0 5624.5 5626.2 5627.2 5668.4 5412.9 5418.7 5425.6 5427.7 5441.6 4556.4 5270.6 5579.9 5595.4 5596.0 5281.3 5297.1 5310.4 5423.5 5445.3 08:08:11 INFO - PROCESS | 1638 | 08:08:11 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:52704.84 Median:3324.33 stddev:104850.28 (3154.0%) stddev-sans-first:105071.94 08:08:11 INFO - PROCESS | 1638 | Values: 779.0 797.4 848.6 868.2 884.5 38912.0 39638.0 41573.8 41628.8 41739.7 40776.4 41037.0 41177.4 41191.6 41386.8 7769.0 9325.4 9488.8 9497.3 9502.0 7839.5 7919.6 7928.0 7938.6 11621.8 11654.4 11690.5 11710.9 11716.6 11738.4 11571.9 11596.9 11631.9 11640.6 11664.6 9443.3 11350.0 11364.3 11385.8 11389.0 38433.8 38435.0 38479.2 38635.0 38635.6 3878.1 3933.7 3934.5 3947.2 3985.1 27251.2 29048.6 29682.9 29718.4 29828.9 3100.8 3190.5 3193.5 3316.0 3332.7 336482.0 341492.0 342119.0 342176.0 342635.0 41666.6 42512.6 44082.1 50435.5 50630.7 51854.5 52412.0 54877.9 54921.7 55007.0 14972.9 15004.2 15030.3 15050.4 15080.2 12718.4 12757.0 12771.4 12774.9 12779.2 11922.5 11976.3 11987.6 12059.8 12064.1 4410.4 4616.8 5210.8 5215.9 5252.8 797.5 859.9 861.4 871.1 874.8 27090.9 28145.9 28803.3 28848.0 28929.6 975.1 978.8 988.5 1016.8 1022.4 12218.8 14733.9 14811.5 15051.6 15097.0 425412.0 427230.0 427282.0 427365.0 427381.0 54385.5 54402.5 54442.5 54627.5 54679.0 1358.7 1386.9 1400.8 1442.9 1450.0 25675.9 26558.0 27180.0 27311.2 27327.2 37311.0 37451.8 37501.1 37566.4 37579.6 412890.0 414015.0 414120.0 414263.0 414788.0 23501.1 23790.2 24352.5 28091.6 28278.9 34799.8 36111.6 37501.6 37572.2 37650.4 27093.3 27111.6 27477.5 27746.7 28034.9 26778.4 26782.9 26975.0 27754.0 27924.6 30490.2 31123.4 31511.0 31557.7 31593.2 23756.0 24884.8 27467.7 27784.7 27896.6 21098.8 22141.7 22265.0 22299.0 22372.4 08:08:11 INFO - PROCESS | 1638 | 08:08:11 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:41291.56 Median:24545.02 stddev:121053.03 (493.2%) stddev-sans-first:121353.60 08:08:11 INFO - PROCESS | 1638 | Values: 357.8 383.6 391.8 407.8 417.0 20226.5 22320.7 22923.0 22999.8 23204.0 22724.0 22909.7 23255.2 23572.2 23598.2 6466.4 6784.3 6876.4 6960.9 6965.2 5966.4 6670.6 7560.2 7705.3 7712.3 6708.7 7064.8 7677.6 7702.6 7707.3 7431.5 7664.5 7733.5 7760.0 7772.9 7326.1 7433.8 7437.8 7459.0 7467.3 25491.9 26489.0 26545.0 26991.7 27420.6 2812.8 2848.5 2866.4 2966.1 2990.0 14642.8 16160.3 16606.8 17234.3 17272.7 1981.7 2098.7 2173.9 2180.7 2226.2 153142.0 154969.0 157244.0 158925.0 160149.0 16578.8 19345.2 19729.1 20469.6 20741.4 31839.7 32533.0 32565.3 32575.5 32716.2 10248.9 10342.7 10554.4 10582.2 10710.9 7677.8 8423.9 8747.7 8756.3 8977.1 9181.8 9268.4 9385.0 9401.0 9407.9 3690.2 3761.5 3776.4 3780.6 3790.6 728.0 804.8 823.7 883.7 888.1 20316.8 20987.2 21050.3 21057.8 21368.8 932.4 939.6 946.6 949.1 965.5 10105.5 10959.7 10963.0 10974.0 10998.0 698836.0 707109.0 711581.0 762133.0 767768.0 83910.0 84431.8 110933.4 116303.0 121111.0 993.5 1106.0 1186.9 1189.9 1198.4 16506.7 16625.8 19950.6 20334.4 20366.6 58172.7 58352.4 61377.1 61975.4 63075.7 87034.0 88046.6 88727.6 90194.0 90914.5 9801.7 10759.5 10836.5 11374.0 11497.4 12757.3 12792.7 12800.4 12934.3 13099.4 11102.9 11145.7 11237.4 11560.3 11577.3 10356.2 10408.2 10438.6 10721.7 10747.4 11294.7 11381.9 11675.4 11702.8 11737.9 10509.6 11135.1 11198.9 11361.3 11563.9 11043.7 11093.6 11151.8 11431.0 11468.8 08:08:11 INFO - PROCESS | 1638 | 08:08:11 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6550.77 Median:3709.54 stddev:4129.21 (111.3%) stddev-sans-first:4122.72 08:08:11 INFO - PROCESS | 1638 | Values: 1409.8 1431.2 1478.7 1510.9 1571.7 10467.4 11202.9 11212.3 11213.9 11218.9 10056.5 10115.2 10391.6 10665.0 10875.4 2279.8 2329.3 2330.6 2333.6 2333.7 1875.1 2183.6 2187.6 2190.2 2262.8 2104.5 2114.3 2116.7 2120.5 2327.2 1906.8 2260.3 2274.9 2282.6 2290.2 2229.9 2273.8 2274.3 2277.8 2281.9 10955.5 11044.6 11048.4 11076.7 11109.4 5443.1 5634.9 5641.6 5644.9 5647.4 3171.7 3285.2 3447.9 3516.7 3522.2 1019.8 1101.4 1178.8 1193.9 1252.0 13442.1 13754.3 14162.6 15155.4 15215.5 4003.2 4364.8 4399.3 4532.9 4568.8 9311.8 11660.2 11664.0 11670.4 11689.6 4731.5 4889.3 5227.7 5539.9 5559.5 5076.5 5143.1 5154.6 5155.5 5159.7 3635.4 3694.5 3756.7 3800.6 3805.6 972.5 981.7 983.0 985.1 998.1 710.4 814.9 844.0 858.5 887.5 6143.2 6489.6 6583.4 6782.8 6870.2 890.4 919.3 941.5 946.1 980.0 3724.5 3912.7 4012.2 4097.1 4108.0 12706.6 13199.3 13231.5 13247.3 13249.2 13159.1 13160.3 13164.5 13179.7 13181.4 9364.7 9663.6 9901.2 9901.8 9909.1 6386.5 6513.3 6566.8 6701.9 6708.6 11025.9 12070.5 12208.9 12289.3 12372.3 9843.3 12016.0 12274.3 12608.2 12656.7 9734.4 9955.4 9989.3 10178.7 10182.6 9825.0 10068.9 10129.1 10195.7 10226.6 8475.7 8533.0 8617.3 8783.2 8858.8 8398.7 8496.0 8567.4 8587.5 8668.1 7733.4 7819.7 7820.7 7914.6 7928.3 5632.4 5724.4 5812.1 5818.7 6040.0 5741.0 5819.6 5881.5 5962.6 5994.5 08:08:11 INFO - PROCESS | 1638 | 08:08:11 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:3497.85 Median:3794.03 stddev:941.21 (24.8%) stddev-sans-first:913.70 08:08:11 INFO - PROCESS | 1638 | Values: 349.2 352.2 359.1 359.7 360.2 3916.5 3959.7 3974.4 3981.7 3988.0 3235.5 3821.5 3893.9 3896.8 3910.3 2669.1 3229.7 3246.6 3249.0 3309.7 3026.3 3315.7 3339.4 3345.8 3383.7 3345.5 3359.5 3363.5 3387.4 3414.7 2894.7 3324.9 3361.4 3400.7 3402.3 3041.0 3091.4 3122.6 3127.3 3139.3 3087.9 3657.8 3694.6 3739.3 3764.8 3798.9 3846.7 3887.7 3891.9 3914.4 3821.0 3828.6 4126.7 4146.8 4158.6 2977.7 3202.2 3349.7 3444.6 3470.9 3833.1 3943.0 4089.8 4149.6 4374.0 3423.0 3449.2 3512.6 3513.7 3515.7 3338.9 3355.7 3554.8 3563.6 3589.2 3275.9 3458.6 3571.3 3664.6 3690.7 3381.8 4076.6 4099.5 4120.0 4146.3 1152.3 1201.0 1205.0 1207.8 1227.8 848.4 875.5 940.4 952.6 956.4 3652.8 3730.7 3749.3 3764.2 3798.7 3532.9 4178.3 4180.1 4226.0 4230.7 3725.6 3735.4 3749.6 3794.7 3801.9 1945.9 1967.3 2018.6 2163.0 2170.7 3802.5 4020.8 4302.1 4336.3 4414.0 3726.3 4178.9 4303.8 4333.0 4342.4 3714.8 3982.5 4151.6 4260.5 4316.0 3844.7 4211.2 4223.9 4229.5 4249.5 4124.0 4206.7 4215.5 4224.8 4232.4 3646.6 4132.3 4367.0 4368.7 4395.9 3214.9 3608.4 3700.9 3717.5 3793.4 4250.4 4332.3 4363.0 4377.7 4410.5 3951.2 4231.3 4273.1 4283.5 4286.6 3392.5 3864.7 3898.9 4165.5 4182.5 3360.6 3664.5 4048.7 4149.4 4232.7 3579.4 3780.5 4123.7 4137.9 4165.4 3449.4 3577.7 3683.0 3985.9 4082.1 08:08:11 INFO - PROCESS | 1638 | 08:08:11 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:19801.94 Median:17098.40 stddev:28690.89 (167.8%) stddev-sans-first:28735.61 08:08:11 INFO - PROCESS | 1638 | Values: 723.0 734.5 771.9 819.6 847.8 15364.8 16996.8 17064.0 17074.7 17122.1 15051.6 15704.1 15705.7 15750.4 15874.4 988.6 1005.6 1018.4 1020.7 1022.7 997.8 998.2 999.5 1004.1 1004.7 1026.2 1026.4 1028.2 1030.9 1031.8 1023.4 1025.4 1027.3 1027.6 1027.8 755.6 758.6 759.1 760.0 764.0 14481.8 14798.4 14852.0 14995.3 15061.3 11997.8 12334.0 12645.0 13482.5 13699.3 11263.2 11447.3 11597.4 11870.8 12002.7 3754.5 3762.2 3847.8 3922.5 4007.3 119399.0 121307.4 122269.0 122593.4 124995.4 10388.4 10449.3 10460.8 10838.9 10847.1 22337.4 23105.2 23109.3 23141.9 23155.3 7909.8 7963.8 8056.4 8068.0 8168.5 3658.8 4456.5 4488.7 4507.6 4521.7 2851.6 3380.5 3477.4 3486.4 3488.7 632.2 642.5 646.7 659.5 662.1 527.7 583.8 629.7 641.9 652.9 9079.6 9103.9 9625.6 10436.7 10850.8 588.3 617.4 642.3 650.8 656.7 4249.5 4557.7 5017.8 5019.0 5020.3 93465.5 97290.5 98943.5 99913.0 101976.0 39136.8 51654.5 73215.3 75421.5 89243.7 71843.3 72892.1 74040.4 74446.6 77464.0 10797.7 10919.6 11062.4 11150.5 11177.8 38337.8 44520.3 47767.3 48769.8 49611.0 45569.5 61565.7 62193.8 62622.4 62843.4 12151.3 12153.3 12215.3 12272.7 12826.8 14585.0 14591.9 14851.0 14955.2 15209.9 13460.3 13613.4 14344.1 14611.3 14797.5 13148.7 13340.9 14117.2 14287.1 14596.3 12651.4 13047.0 14108.9 14147.1 14227.8 9661.3 10148.2 10333.4 10353.2 10354.8 9914.0 10817.3 11237.6 11258.0 11266.3 08:08:11 INFO - PROCESS | 1638 | -------- Summary: end -------- 08:08:11 INFO - PROCESS | 1638 | 08:08:12 INFO - TEST-INFO | 1638: exit 0 08:08:12 INFO - TEST-OK | dromaeo_css | took 1119471ms 08:08:12 INFO - TEST-START | kraken 08:08:12 INFO - operating with platform_type : mac_ 08:08:12 INFO - Initialising browser for kraken test... 08:08:12 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmp4q844Y/profile http://localhost:49225/getInfo.html) 08:08:14 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 08:08:14 INFO - PROCESS | 1648 | colorDepth:24 08:08:14 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 08:08:14 INFO - PROCESS | 1648 | __metrics 08:08:14 INFO - PROCESS | 1648 | 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" 08:08:14 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:08:15 INFO - TEST-INFO | 1648: exit 0 08:08:15 INFO - Browser initialized. 08:08:15 INFO - Running cycle 1/1 for kraken test... 08:08:15 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmp4q844Y/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:08:27 INFO - PROCESS | 1650 | RSS: Main: 165167104 08:08:27 INFO - PROCESS | 1650 | 08:08:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection) 08:08:31 INFO - PROCESS | 1650 | RSS: Main: 216666112 08:08:31 INFO - PROCESS | 1650 | 08:08:33 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-dft) 08:08:34 INFO - PROCESS | 1650 | RSS: Main: 231649280 08:08:34 INFO - PROCESS | 1650 | 08:08:38 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-fft) 08:08:38 INFO - PROCESS | 1650 | RSS: Main: 322920448 08:08:38 INFO - PROCESS | 1650 | 08:08:40 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator) 08:08:41 INFO - PROCESS | 1650 | RSS: Main: 227639296 08:08:41 INFO - PROCESS | 1650 | 08:08:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:08:44 INFO - PROCESS | 1650 | RSS: Main: 230506496 08:08:44 INFO - PROCESS | 1650 | 08:08:47 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom) 08:08:47 INFO - PROCESS | 1650 | RSS: Main: 271028224 08:08:47 INFO - PROCESS | 1650 | 08:08:50 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate) 08:08:51 INFO - PROCESS | 1650 | RSS: Main: 270381056 08:08:51 INFO - PROCESS | 1650 | 08:08:54 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial) 08:08:55 INFO - PROCESS | 1650 | RSS: Main: 268890112 08:08:55 INFO - PROCESS | 1650 | 08:08:56 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:08:57 INFO - PROCESS | 1650 | RSS: Main: 245825536 08:08:57 INFO - PROCESS | 1650 | 08:08:59 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:09:00 INFO - PROCESS | 1650 | RSS: Main: 248705024 08:09:00 INFO - PROCESS | 1650 | 08:09:02 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:09:03 INFO - PROCESS | 1650 | RSS: Main: 254128128 08:09:03 INFO - PROCESS | 1650 | 08:09:05 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:09:06 INFO - PROCESS | 1650 | RSS: Main: 270880768 08:09:06 INFO - PROCESS | 1650 | 08:09:09 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:09:10 INFO - PROCESS | 1650 | RSS: Main: 264851456 08:09:10 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49225/tests/kraken/driver.html?testName=ai-astar) 08:09:12 INFO - PROCESS | 1650 | RSS: Main: 309202944 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | __start_tp_report 08:09:12 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 08:09:12 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 08:09:12 INFO - PROCESS | 1650 | |i|pagename|runs| 08:09:12 INFO - PROCESS | 1650 | |0;ai-astar;108;126;161;118;119;118;117;119;119;119 08:09:12 INFO - PROCESS | 1650 | |1;audio-beat-detection;148;112;117;178;142;113;112;109;130;111 08:09:12 INFO - PROCESS | 1650 | |2;audio-dft;213;213;213;214;235;234;214;213;213;214 08:09:12 INFO - PROCESS | 1650 | |3;audio-fft;95;62;62;94;63;62;62;62;79;83 08:09:12 INFO - PROCESS | 1650 | |4;audio-oscillator;95;72;89;71;73;70;84;70;70;85 08:09:12 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;90;90;91;90;89;91;90;89;89;89 08:09:12 INFO - PROCESS | 1650 | |6;imaging-darkroom;98;97;99;97;96;97;99;98;98;98 08:09:12 INFO - PROCESS | 1650 | |7;imaging-desaturate;151;163;100;101;101;100;102;100;100;101 08:09:12 INFO - PROCESS | 1650 | |8;json-parse-financial;63;61;61;63;65;65;66;65;65;66 08:09:12 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;71;93;102;101;66;64;64;64;65;64 08:09:12 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;85;85;84;89;88;86;84;86;84;93 08:09:12 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;141;142;135;143;150;145;142;145;188;140 08:09:12 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;170;169;168;170;170;169;169;168;168;169 08:09:12 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;58;75;86;71;67;66;63;66;65;66 08:09:12 INFO - PROCESS | 1650 | __end_tp_report 08:09:12 INFO - PROCESS | 1650 | __start_cc_report 08:09:12 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2208 08:09:12 INFO - PROCESS | 1650 | __end_cc_report 08:09:12 INFO - PROCESS | 1650 | __startTimestamp1495638552395__endTimestamp 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | ------- Summary: start ------- 08:09:12 INFO - PROCESS | 1650 | Number of tests: 14 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:122.40 Median:119.00 stddev:14.24 (12.0%) stddev-sans-first:14.12 08:09:12 INFO - PROCESS | 1650 | Values: 108.0 126.0 161.0 118.0 119.0 118.0 117.0 119.0 119.0 119.0 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:127.20 Median:123.50 stddev:22.61 (18.3%) stddev-sans-first:22.70 08:09:12 INFO - PROCESS | 1650 | Values: 148.0 112.0 117.0 178.0 142.0 113.0 112.0 109.0 130.0 111.0 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:217.60 Median:214.00 stddev:8.92 (4.2%) stddev-sans-first:9.31 08:09:12 INFO - PROCESS | 1650 | Values: 213.0 213.0 213.0 214.0 235.0 234.0 214.0 213.0 213.0 214.0 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:72.40 Median:71.00 stddev:13.99 (19.7%) stddev-sans-first:12.22 08:09:12 INFO - PROCESS | 1650 | Values: 95.0 62.0 62.0 94.0 63.0 62.0 62.0 62.0 79.0 83.0 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:78.50 stddev:9.41 (12.0%) stddev-sans-first:7.68 08:09:12 INFO - PROCESS | 1650 | Values: 95.0 72.0 89.0 71.0 73.0 70.0 84.0 70.0 70.0 85.0 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:90.00 stddev:0.79 (0.9%) stddev-sans-first:0.83 08:09:12 INFO - PROCESS | 1650 | Values: 90.0 90.0 91.0 90.0 89.0 91.0 90.0 89.0 89.0 89.0 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:97.70 Median:98.00 stddev:0.95 (1.0%) stddev-sans-first:1.00 08:09:12 INFO - PROCESS | 1650 | Values: 98.0 97.0 99.0 97.0 96.0 97.0 99.0 98.0 98.0 98.0 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:111.90 Median:101.00 stddev:23.95 (23.7%) stddev-sans-first:20.80 08:09:12 INFO - PROCESS | 1650 | Values: 151.0 163.0 100.0 101.0 101.0 100.0 102.0 100.0 100.0 101.0 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:65.00 stddev:1.89 (2.9%) stddev-sans-first:1.96 08:09:12 INFO - PROCESS | 1650 | Values: 63.0 61.0 61.0 63.0 65.0 65.0 66.0 65.0 65.0 66.0 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:75.40 Median:64.50 stddev:16.36 (25.4%) stddev-sans-first:17.27 08:09:12 INFO - PROCESS | 1650 | Values: 71.0 93.0 102.0 101.0 66.0 64.0 64.0 64.0 65.0 64.0 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:86.00 stddev:2.88 (3.3%) stddev-sans-first:3.00 08:09:12 INFO - PROCESS | 1650 | Values: 85.0 85.0 84.0 89.0 88.0 86.0 84.0 86.0 84.0 93.0 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:144.00 stddev:14.88 (10.3%) stddev-sans-first:15.62 08:09:12 INFO - PROCESS | 1650 | Values: 141.0 142.0 135.0 143.0 150.0 145.0 142.0 145.0 188.0 140.0 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.00 Median:169.00 stddev:0.82 (0.5%) stddev-sans-first:0.78 08:09:12 INFO - PROCESS | 1650 | Values: 170.0 169.0 168.0 170.0 170.0 169.0 169.0 168.0 168.0 169.0 08:09:12 INFO - PROCESS | 1650 | 08:09:12 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:66.50 stddev:7.66 (11.5%) stddev-sans-first:7.16 08:09:12 INFO - PROCESS | 1650 | Values: 58.0 75.0 86.0 71.0 67.0 66.0 63.0 66.0 65.0 66.0 08:09:12 INFO - PROCESS | 1650 | -------- Summary: end -------- 08:09:12 INFO - PROCESS | 1650 | 08:09:13 INFO - TEST-INFO | 1650: exit 0 08:09:13 INFO - TEST-OK | kraken | took 61160ms 08:09:13 INFO - SUITE-END | took 1180s 08:09:13 INFO - Completed test suite (00:19:40) 08:09:13 INFO - javascript benchmark 08:09:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7094.400881087608, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2294.300238427281, 2967.7180667836556, 2973.494505933991, 3025.682303358146, 3061.892833750819, 4633.30500424936, 5108.667936584119, 5145.125328694122, 5150.150225212845, 5201.349831271091, 5141.0, 5159.226116082589, 5173.6119791015635, 5190.8442746717565, 5243.86890327742, 4696.990147118368, 4729.692569982949, 4738.810447582093, 4804.399450068741, 4842.685225460352, 4460.910781784362, 4485.058137791068, 4507.526709695484, 4776.379070720802, 4803.2795080738, 4704.223803072489, 4735.834245801398, 4740.2889566564945, 4744.501827308118, 4750.667453278272, 4723.740194289313, 4726.456457507379, 4733.5503127202865, 4748.145333839908, 4749.857504274877, 4617.284320930261, 4617.653675974316, 4632.235681112612, 4633.4671512775785, 4652.232381657022, 4008.7394319369146, 5046.369203849519, 5063.240513922904, 5074.06129865976, 5092.134337162691, 5499.120140777496, 5510.862228444281, 5515.503604675599, 5523.558115350803, 5537.640053396516, 5580.581456390787, 5636.098224284095, 5638.661680299195, 5691.373948865621, 5704.22992895962, 5292.629515933886, 5331.306930099062, 5342.984832881779, 5348.358197016383, 5368.275282836845, 5413.106837371868, 5907.527397808165, 5934.19888315072, 5946.643201407886, 5969.611975221596, 5442.047641662693, 5452.672839629635, 5454.672719636835, 5496.340439147248, 5590.664560126324, 4663.766811659471, 5627.212190293362, 5670.432956704379, 5766.740496677659, 5798.420157984167, 4619.376384188159, 5588.133839254922, 5630.577706672016, 5633.8309850704545, 5678.432156784371, 5323.973380133152, 5365.973170134124, 5506.311711036121, 5521.420250873681, 5675.971620141873, 3980.6616437602547, 4304.935425968613, 4423.845165419195, 4464.732116073046, 4473.843415480443, 3817.923641527154, 3996.4005399190355, 4009.258287216874, 4017.5178978522763, 4120.835166593302, 5128.0, 5130.333056702605, 5157.381114266312, 5157.896842063138, 5176.637635365563, 5682.261306030189, 5768.769249229984, 5786.537077033827, 5813.331466881363, 5816.1566572846705, 4890.535399137077, 4954.578860796801, 5056.898862022739, 5091.439941606421, 5099.745012749348, 5003.974980125149, 5016.423111342169, 5028.396592408934, 5057.089723849669, 5063.974680126649, 5889.469947704556, 5925.318588362394, 5927.347991720907, 5943.167956486095, 5957.16599676037, 5433.701146436984, 5509.338879334506, 5671.97904377216, 5759.596828222152, 5806.912896306643, 5867.149263356875, 5891.823245302648, 5905.970470147622, 5908.379620139824, 5916.112583112567, 5504.587155963239, 5505.03661859181, 5519.530839878495, 5539.861503462284, 5546.861328466821, 4581.7709114544805, 5471.534919531725, 5536.4186760389575, 5599.972000139973, 5655.1517272409465, 5445.618806683494, 5467.863303417446, 5467.9453205467435, 5517.641353312182, 5541.362743284574, 5597.048501754793, 5624.465675760796, 5626.212330273846, 5627.18405831144, 5668.404817494104, 5412.864678383072, 5418.674879507243, 5425.593080518898, 5427.674339539641, 5441.564674825925, 4556.407667003268, 5270.631055826056, 5579.944200557942, 5595.412481689526, 5596.048671725898, 5281.339832520935, 5297.125974214211, 5310.442403547727, 5423.457654234545, 5445.319335083114], "value": 5124.348263280509, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.964946577404, 797.4497596658307, 848.5502683577706, 868.2272777228268, 884.5311984648139, 38912.0, 39638.0, 41573.7921310393, 41628.791856040756, 41739.74780756582, 40776.38835417464, 41037.0, 41177.38233926486, 41191.58808411915, 41386.793066034705, 7769.028871391076, 9325.393849399785, 9488.810223795519, 9497.335186536946, 9501.95249023754, 7839.490433121858, 7919.56442395668, 7927.960360198191, 7938.6427610757655, 11621.767564648702, 11654.359010254433, 11690.473928673187, 11710.941445292761, 11716.589919352824, 11738.41307934604, 11571.942140289244, 11596.898294661994, 11631.941840290745, 11640.592579259684, 11664.591739289084, 9443.291753118543, 11349.978501934784, 11364.261323013978, 11385.829212561735, 11389.0, 38433.80783096095, 38435.0, 38479.230415391816, 38635.03412414684, 38635.61364386376, 3878.1274213302063, 3933.744306620089, 3934.5081864766903, 3947.1513624570857, 3985.123272879962, 27251.182464525897, 29048.564271535943, 29682.851585742148, 29718.405631887243, 29828.850855745583, 3100.829454380008, 3190.4735718606403, 3193.457112290916, 3315.96684033159, 3332.7000569948823, 336482.0, 341491.9999999975, 342119.0, 342176.0, 342635.0, 41666.58333416627, 42512.57487425086, 44082.118357632666, 50435.49564504381, 50630.74684626627, 51854.48145518572, 52411.95176096457, 54877.90244195174, 54921.725391373584, 55007.0, 14972.850271497364, 15004.174770387515, 15030.32363543643, 15050.397984080733, 15080.245987700791, 12718.364081795873, 12757.0, 12771.425285862158, 12774.872251277367, 12779.23324600527, 11922.463489143009, 11976.341301228516, 11987.580434684744, 12059.758804823854, 12064.09519286057, 4410.360497727812, 4616.838410655678, 5210.791568337224, 5215.869603259949, 5252.763625636855, 797.5175019113469, 859.9398042137116, 861.3711990247155, 871.1419252036738, 874.8469017921839, 27090.864545677145, 28145.859270703517, 28803.279918001797, 28847.990320338686, 28929.566056509168, 975.0834215837091, 978.8238117138983, 988.4711679251566, 1016.8271393863062, 1022.4172221833633, 12218.755624887452, 14733.92633036828, 14811.481598144228, 15051.623709407353, 15097.018693784868, 425412.0, 427230.0, 427282.0, 427365.0, 427381.0, 54385.45614543804, 54402.45597543974, 54442.45557544453, 54627.453725463034, 54679.0, 1358.7146699193083, 1386.8821150201943, 1400.8108905297859, 1442.949496767608, 1450.0284809177876, 25675.871620642523, 26558.0, 27180.0, 27311.180664580093, 27327.180184594494, 37311.0, 37451.81274093612, 37501.06247343838, 37566.43650345302, 37579.624203757616, 412890.0, 414015.0, 414120.0, 414263.0, 414788.0, 23501.059957601505, 23790.167344142872, 24352.51294974055, 28091.578626321032, 28278.85860570684, 34799.78200762961, 36111.63888361083, 37501.62498374981, 37572.24855502929, 37650.43524347192, 27093.322666933484, 27111.59332609973, 27477.45045099047, 27746.722532774413, 28034.85982570074, 26778.447663896794, 26782.866085669448, 26975.0, 27754.0, 27924.58113128264, 30490.237744056576, 31123.377532448772, 31511.0, 31557.684423156392, 31593.21016974594, 23756.0, 24884.751152488243, 27467.725322746515, 27784.722152778213, 27896.58155127715, 21098.787722466044, 22141.667874981566, 22264.998075086984, 22298.996545155183, 22372.440688982904], "value": 17936.02199090546, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.7942682957299, 383.631713554987, 391.7943079883062, 407.7594219410551, 416.9979150104249, 20226.494337641547, 22320.665190022122, 22923.0, 22999.77000229997, 23203.999999999978, 22724.0, 22909.656355154686, 23255.186068487605, 23572.174973875917, 23598.174063907765, 6466.418022377985, 6784.253732089466, 6876.415504682108, 6960.930390696079, 6965.233824279325, 5966.433188847052, 6670.6331151786635, 7560.206178351276, 7705.306522412981, 7712.267334603203, 6708.698108585101, 7064.82337941553, 7677.577733224671, 7702.576358300291, 7707.267809558083, 7431.4797964142535, 7664.540127592362, 7733.497322674065, 7759.9612001940195, 7772.883406748903, 7326.120865496121, 7433.776986690408, 7437.814054648623, 7458.962705186494, 7467.253274672544, 25491.872540637363, 26489.0, 26545.0, 26991.730082699316, 27420.588691169647, 2812.7609153222, 2848.5157523220896, 2866.3550701092086, 2966.1398194523563, 2989.95515067272, 14642.780358294634, 16160.272787724463, 16606.83393166077, 17234.310627574883, 17272.740908886375, 1981.6928376101728, 2098.685197220429, 2173.9021744021557, 2180.705604743371, 2226.154061456636, 153142.0, 154969.0, 157244.0, 158925.0, 160148.98882583735, 16578.83421165797, 19345.226190952486, 19729.112189951487, 20469.59060818775, 20741.377758667266, 31839.681603184134, 32533.0, 32565.34869302648, 32575.51136732951, 32716.18209544733, 10248.897511024794, 10342.689719308433, 10554.419506927192, 10582.20633452494, 10710.94644526788, 7677.808054798563, 8423.915760842314, 8747.693830715894, 8756.299496040301, 8977.057408972094, 9181.770455738526, 9268.443893366419, 9384.953075234716, 9401.0, 9407.90592094084, 3690.1697118148204, 3761.539383766941, 3776.3957766757185, 3780.6219378062524, 3790.6019867913474, 727.9745208917768, 804.7827086686679, 823.6664151018847, 883.7304622090196, 888.1163242573551, 20316.796832031785, 20987.160513579285, 21050.315974721987, 21057.78942210589, 21368.824714640843, 932.4218984229685, 939.628846605587, 946.5929650250458, 949.0651708067548, 965.4834663455158, 10105.545250463747, 10959.671209863716, 10962.958518940688, 10974.0, 10998.0, 698836.0, 707109.0, 711581.0, 762133.0, 767768.0, 83910.0, 84431.82329501938, 110933.44533277283, 116303.0, 121111.0, 993.4883534979491, 1106.0, 1186.9050475961903, 1189.916705830601, 1198.4367347346908, 16506.669866602842, 16625.750613741046, 19950.60098797987, 20334.389968300973, 20366.59266814685, 58172.70913645405, 58352.4164758364, 61377.07934381077, 61975.38024619696, 63075.6846215766, 87034.02757699497, 88046.55976720332, 88727.55636221777, 90194.0, 90914.54542727245, 9801.705948821547, 10759.46202689878, 10836.512356943798, 11374.033207177483, 11497.367644779282, 12757.298348590917, 12792.680182995127, 12800.35998200105, 12934.288614126313, 13099.41052652652, 11102.88897111051, 11145.665630031112, 11237.381943992832, 11560.32375305559, 11577.305361678325, 10356.17150627963, 10408.167346612101, 10438.63464778729, 10721.731956701145, 10747.355158690503, 11294.661160165208, 11381.943090284827, 11675.416229188677, 11702.765944681229, 11737.943585077384, 10509.579616815548, 11135.053520450852, 11198.944005279922, 11361.261518001304, 11563.942180289381, 11043.723906902393, 11093.611723589636, 11151.776964460827, 11430.971212590906, 11468.827967580326], "value": 11442.453296451129, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1409.8167238259025, 1431.1842249917552, 1478.696867142236, 1510.886683498738, 1571.7249481340762, 10467.424291663956, 11202.935721106502, 11212.27120237184, 11213.943930280348, 11218.887811121886, 10056.497175141232, 10115.190784737222, 10391.584336626545, 10665.0, 10875.401852898107, 2279.8290128240365, 2329.2779238436055, 2330.5804955108074, 2333.5682898663717, 2333.7082864641834, 1875.109323071537, 2183.639699449589, 2187.639039558471, 2190.1567896359857, 2262.8459485662265, 2104.4844013216707, 2114.312848324293, 2116.6824976253533, 2120.4698825293676, 2327.197116994634, 1906.809319068089, 2260.3386510914465, 2274.9090036398525, 2282.6233671444193, 2290.1869836208075, 2229.8662080275235, 2273.818094552432, 2274.3404412720292, 2277.806386457153, 2281.942951426211, 10955.452227388678, 11044.558217671283, 11048.447577621087, 11076.723081922935, 11109.44452777358, 5443.129099344086, 5634.915476267859, 5641.605087643826, 5644.943550564483, 5647.407022262644, 3171.7462602991704, 3285.162283617686, 3447.9482807757904, 3516.7362447816517, 3522.23888055972, 1019.7909428567126, 1101.438266484091, 1178.7740749620384, 1193.8865807748336, 1252.0359323321084, 13442.12626179305, 13754.31228438584, 14162.575122746232, 15155.393784248618, 15215.467458638894, 4003.2193722223888, 4364.8433165211245, 4399.318105693623, 4532.864014079583, 4568.817247310137, 9311.813763724687, 11660.242084264495, 11663.950244477868, 11670.416479176007, 11689.590864319707, 4731.526847315241, 4889.315495830587, 5227.712475813867, 5539.9169012464845, 5559.499645031886, 5076.543111119943, 5143.074246635641, 5154.587632989427, 5155.484451554815, 5159.664621799646, 3635.4365073413665, 3694.538182727159, 3756.7370284079852, 3800.562935262425, 3805.562360328542, 972.5234635028785, 981.7054883534911, 982.9901700983041, 985.1380042462845, 998.1166667499315, 710.3819676881146, 814.8818421328992, 843.9662413503391, 858.4891989266376, 887.4985633117268, 6143.201383820075, 6489.5781774184525, 6583.374579414948, 6782.796516104525, 6870.20992585849, 890.4078787606169, 919.2508105893676, 941.5480569326761, 946.0917519181526, 979.9755006124904, 3724.5344331958504, 3912.68698504119, 4012.217617564547, 4097.057676734368, 4107.97946010274, 12706.649269885102, 13199.274039927896, 13231.470741170246, 13247.271400072896, 13249.205047697169, 13159.078864479587, 13160.276184809927, 13164.539241126515, 13179.73640527184, 13181.406836692371, 9364.672236471692, 9663.565139568736, 9901.15840153595, 9901.752456188653, 9909.108180263667, 6386.521010924106, 6513.348665133638, 6566.770163044271, 6701.865962680817, 6708.597484150967, 11025.944870275598, 12070.517179312728, 12208.938955305166, 12289.324087175291, 12372.257664540157, 9843.284167582055, 12016.0, 12274.263544187377, 12608.243505389706, 12656.6835829105, 9734.36726612768, 9955.352902061342, 9989.250806189419, 10178.745531361776, 10182.643607473703, 9824.950875245579, 10068.949655251678, 10129.139023183112, 10195.7451063724, 10226.6420675276, 8475.703350382708, 8532.957335213285, 8617.310615150647, 8783.209511144029, 8858.778530536789, 8398.664053437795, 8495.95752021236, 8567.4002819802, 8587.484750914966, 8668.089850565852, 7733.38132949374, 7819.726309579138, 7820.687172513263, 7914.604269786603, 7928.335049639641, 5632.408597097246, 5724.398938111438, 5812.099124635688, 5818.650880947157, 6040.0, 5740.971295143498, 5819.592628515963, 5881.529477641864, 5962.55280853929, 5994.520438365008], "value": 4943.374437712856, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.22297887200983, 352.2409208156422, 359.1326945426793, 359.7373917040562, 360.15902866806005, 3916.4712763776884, 3959.722819402643, 3974.383970484576, 3981.7059455676876, 3987.983064318602, 3235.5146727990755, 3821.5032045834073, 3893.9026524336905, 3896.7856767877756, 3910.296146693594, 2669.0925085470935, 3229.6608856070125, 3246.642869284377, 3249.0, 3309.7257555840933, 3026.2888221267986, 3315.6850099240532, 3339.382214290352, 3345.799252044873, 3383.74621903357, 3345.5149003394495, 3359.5296658467832, 3363.5291059251726, 3387.44107222308, 3414.7438942079443, 2894.696056914025, 3324.9168770780684, 3361.3781450431625, 3400.693937545608, 3402.2515046689687, 3040.9847950760327, 3091.3817236552554, 3122.5628412022334, 3127.2650927032128, 3139.3250451153112, 3087.938241235163, 3657.762245454037, 3694.5751238607636, 3739.3082279778055, 3764.849406023783, 3798.867039653599, 3846.653801157909, 3887.747296425723, 3891.922161556753, 3914.375534153323, 3821.0, 3828.5979972102805, 4126.690498212646, 4146.771927543984, 4158.6465150462245, 2977.672456029835, 3202.2154572129743, 3349.743846057728, 3444.621091679913, 3470.9305813883834, 3833.0834582708644, 3942.9605703943166, 4089.8159582818844, 4149.589139692519, 4373.956260437387, 3422.9657703423145, 3449.2066824629974, 3512.5960514540643, 3513.6662017108333, 3515.683588476998, 3338.9499157512655, 3355.6644335566734, 3554.7689400189424, 3563.5901871284614, 3589.2313071275057, 3275.8689652414114, 3458.5849698036395, 3571.339302229096, 3664.59689434162, 3690.741648084608, 3381.7970921744773, 4076.615180119604, 4099.487564054493, 4119.958800411958, 4146.279294092978, 1152.2798251093068, 1200.9699757506132, 1204.9759004819855, 1207.7524107558106, 1227.8342423772676, 848.3764433141636, 875.5228400521731, 940.3981451870808, 952.5856252530172, 956.3974695941512, 3652.817359132086, 3730.7015438764834, 3749.3005036373766, 3764.214481283885, 3798.6961043116485, 3532.894013179609, 4178.310578754532, 4180.122174343422, 4225.950414049184, 4230.725002874803, 3725.590185079639, 3735.402335626287, 3749.6250374962287, 3794.679655301611, 3801.8669346572738, 1945.9221631134596, 1967.3409407848244, 2018.6063717575225, 2162.9942076934303, 2170.6852506386485, 3802.524684414448, 4020.8190631421658, 4302.118065796504, 4336.349547567827, 4414.0, 3726.32926073304, 4178.937315940263, 4303.827846886152, 4332.978335108304, 4342.415018518282, 3714.7585406949, 3982.5021872265966, 4151.564085771011, 4260.5301171095925, 4316.0, 3844.6924246060807, 4211.220924129046, 4223.852165174204, 4229.492460904711, 4249.46881639795, 4123.958760412477, 4206.7073444973175, 4215.451991241119, 4224.767637779829, 4232.36514522824, 3646.593248456006, 4132.2561938851295, 4366.956330436782, 4368.716033457815, 4395.922854002148, 3214.8714051437682, 3608.404613238786, 3700.888973330804, 3717.531575027903, 3793.3740932745786, 4250.426192463978, 4332.3068309070395, 4362.978185109054, 4377.738584754378, 4410.5080629444265, 3951.1900060488106, 4231.2806822839575, 4273.124009577967, 4283.464566929134, 4286.571342865688, 3392.5065077168756, 3864.6811912852063, 3898.947284233298, 4165.500139983222, 4182.519010313853, 3360.6307553175443, 3664.500402116026, 4048.676105911579, 4149.356849688304, 4232.724872883253, 3579.3557159711504, 3780.5066998535976, 4123.690723195712, 4137.91724165521, 4165.375193720966, 3449.3963556378035, 3577.712023671528, 3682.963170368262, 3985.900352491211, 4082.1223436961195], "value": 3239.1717853529162, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [722.9746958856441, 734.4785202506217, 771.8842173673949, 819.6188772220918, 847.7922908887324, 15364.769528457067, 16996.83003169968, 17063.976161430324, 17074.658506829855, 17122.14389280534, 15051.56785100088, 15704.136272505008, 15705.685886282268, 15750.448734294301, 15874.444394446196, 988.5502096546054, 1005.557554675944, 1018.3533456255257, 1020.7244044108087, 1022.7238645565693, 997.8353571660667, 998.151571164508, 999.535216124502, 1004.1163775877218, 1004.7036124343336, 1026.179056754597, 1026.399556259589, 1028.1928685981507, 1030.8608337874402, 1031.7575369788094, 1023.4115383654354, 1025.4462590201283, 1027.3322340478674, 1027.5581499955017, 1027.8149933012016, 755.5919803306208, 758.5638258001671, 759.0625577411892, 760.0043942435373, 763.9579823109726, 14481.78277325841, 14798.40806367744, 14852.0, 14995.250237488082, 15061.322240499203, 11997.760044799143, 12334.0, 12645.0, 13482.528111516149, 13699.315034248348, 11263.21157518974, 11447.313161210272, 11597.362145082017, 11870.762584748256, 12002.69993250167, 3754.5494540655022, 3762.172322089136, 3847.8460861565504, 3922.4508568800393, 4007.2586571484367, 119399.0, 121307.393463033, 122269.0, 122593.38703306516, 124995.3750231252, 10388.428636424918, 10449.268551201494, 10460.790784184273, 10838.891611083945, 10847.132229421628, 22337.441563960707, 23105.191318304114, 23109.306720798402, 23141.88429057844, 23155.3053408398, 7909.802254943557, 7963.761087167394, 8056.39577031725, 8067.959660201662, 8168.469049511763, 3658.835352409148, 4456.509783923766, 4488.663350248679, 4507.57178068083, 4521.683482156218, 2851.6435445569305, 3380.543626610376, 3477.3914564951033, 3486.389881770731, 3488.7383446241633, 632.1623848400887, 642.4667525953447, 646.741303478603, 659.5086660438004, 662.0730976632657, 527.7150338817073, 583.7723287917715, 629.7134803664321, 641.8619996700731, 652.9249136349285, 9079.591418386188, 9103.862017247844, 9625.614975401046, 10436.73908152287, 10850.782984340269, 588.2734822494216, 617.3671986214076, 642.3351830855042, 650.847050943028, 656.701200953565, 4249.46881639795, 4557.72653640776, 5017.774200161001, 5019.021290848323, 5020.2720605511995, 93465.53267233621, 97290.51354743181, 98943.50528247312, 99913.0, 101976.0, 39136.80431597824, 51654.48345516497, 73215.26784732085, 75421.49157016829, 89243.66134507988, 71843.28156718366, 72892.07525284983, 74040.42064571465, 74446.62776686082, 77464.0, 10797.730056748644, 10919.59164604032, 11062.446877656246, 11150.498227579941, 11177.776444471228, 38337.84986450299, 44520.33219501775, 47767.28349074836, 48769.756151220434, 49611.00777984348, 45569.54430455786, 61565.69217153885, 62193.75612487816, 62622.37377626165, 62843.42891427751, 12151.270923744603, 12153.270803751802, 12215.328156951098, 12272.693182670504, 12826.807597886225, 14584.97905146672, 14591.854081459049, 14851.034682745587, 14955.177465239516, 15209.923950380176, 13460.326983650973, 13613.38739756733, 14344.067635603653, 14611.342489588207, 14797.482088126864, 13148.73702525925, 13340.933295333462, 14117.2235527047, 14287.142771433748, 14596.343164557833, 12651.430685618985, 13047.0, 14108.858911410754, 14147.080439771382, 14227.786583201054, 9661.323707340418, 10148.188144948534, 10333.431661258403, 10353.17174626013, 10354.792904141725, 9913.950430247802, 10817.296875703054, 11237.550497980315, 11258.0, 11266.324020558794], "value": 7569.968063658178, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1527.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 126.0, 161.0, 118.0, 119.0, 118.0, 117.0, 119.0, 119.0, 119.0], "value": 122.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 112.0, 117.0, 178.0, 142.0, 113.0, 112.0, 109.0, 130.0, 111.0], "value": 127.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [213.0, 213.0, 213.0, 214.0, 235.0, 234.0, 214.0, 213.0, 213.0, 214.0], "value": 217.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 62.0, 62.0, 94.0, 63.0, 62.0, 62.0, 62.0, 79.0, 83.0], "value": 72.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 72.0, 89.0, 71.0, 73.0, 70.0, 84.0, 70.0, 70.0, 85.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 91.0, 90.0, 89.0, 91.0, 90.0, 89.0, 89.0, 89.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 99.0, 97.0, 96.0, 97.0, 99.0, 98.0, 98.0, 98.0], "value": 97.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 163.0, 100.0, 101.0, 101.0, 100.0, 102.0, 100.0, 100.0, 101.0], "value": 111.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 61.0, 63.0, 65.0, 65.0, 66.0, 65.0, 65.0, 66.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 93.0, 102.0, 101.0, 66.0, 64.0, 64.0, 64.0, 65.0, 64.0], "value": 75.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 89.0, 88.0, 86.0, 84.0, 86.0, 84.0, 93.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 142.0, 135.0, 143.0, 150.0, 145.0, 142.0, 145.0, 188.0, 140.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 169.0, 168.0, 170.0, 170.0, 169.0, 169.0, 168.0, 168.0, 169.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 75.0, 86.0, 71.0, 67.0, 66.0, 63.0, 66.0, 65.0, 66.0], "value": 68.3, "unit": "score"}]}]} 08:09:13 INFO - Return code: 0 08:09:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:09:13 INFO - # TBPL SUCCESS # 08:09:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:09:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:09:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:09:13 INFO - 2017-05-24 08:09:13.752 screenresolution[1654:9905] starting screenresolution argv=screenresolution get 08:09:13 INFO - 2017-05-24 08:09:13.754 screenresolution[1654:9905] Display 0: 1600x1200x32@60 08:09:13 INFO - 2017-05-24 08:09:13.761 screenresolution[1655:9908] starting screenresolution argv=screenresolution list 08:09:13 INFO - Available Modes on Display 0 08:09:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:09:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:09:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:09:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:09:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:09:13 INFO - Intel Iris: 08:09:13 INFO - Chipset Model: Intel Iris 08:09:13 INFO - Type: GPU 08:09:13 INFO - Bus: Built-In 08:09:13 INFO - VRAM (Dynamic, Max): 1536 MB 08:09:13 INFO - Vendor: Intel (0x8086) 08:09:13 INFO - Device ID: 0x0a2e 08:09:13 INFO - Revision ID: 0x0009 08:09:13 INFO - Displays: 08:09:13 INFO - Display: 08:09:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:09:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:09:13 INFO - Display Serial Number: 1600x1200 60 08:09:13 INFO - Main Display: Yes 08:09:13 INFO - Mirror: Off 08:09:13 INFO - Online: Yes 08:09:13 INFO - Rotation: Supported 08:09:14 INFO - Return code: 0 08:09:14 INFO - Running post-action listener: _resource_record_post_action 08:09:14 INFO - [mozharness: 2017-05-24 15:09:14.002728Z] Finished run-tests step (success) 08:09:14 INFO - Running post-run listener: _resource_record_post_run 08:09:14 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 39027712; Write bytes: 420638208; Read time: 388; Write time: 1533 08:09:14 INFO - TinderboxPrint: CPU usage
53.8% 08:09:14 INFO - TinderboxPrint: I/O read bytes / time
39,027,712 / 388 08:09:14 INFO - TinderboxPrint: I/O write bytes / time
420,638,208 / 1,533 08:09:14 INFO - TinderboxPrint: CPU idle
2,212.6 (46.2%) 08:09:14 INFO - TinderboxPrint: CPU system
956.6 (20.0%) 08:09:14 INFO - TinderboxPrint: CPU user
1,620.7 (33.8%) 08:09:14 INFO - TinderboxPrint: Swap in / out
161,361,920 / 0 08:09:14 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 115716608; Write bytes: 114501632; Read time: 10738; Write time: 319 08:09:14 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 37094400; Write bytes: 295343616; Read time: 361; Write time: 1180 08:09:14 INFO - Running post-run listener: _upload_blobber_files 08:09:14 INFO - Blob upload gear active. 08:09:14 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:09:14 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 08:09:14 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:09:14 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:09:14 INFO - (blobuploader) - INFO - Open directory for files ... 08:09:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:09:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:09:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:15 INFO - (blobuploader) - INFO - Done attempting. 08:09:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:09:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:09:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:16 INFO - (blobuploader) - INFO - Done attempting. 08:09:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:09:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:09:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:17 INFO - (blobuploader) - INFO - Done attempting. 08:09:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:09:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:09:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:09:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:09:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:09:19 INFO - (blobuploader) - INFO - Done attempting. 08:09:19 INFO - (blobuploader) - INFO - Iteration through files over. 08:09:19 INFO - Return code: 0 08:09:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:09:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:09:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cbd77fcd1a8cef78825f466b1eaf82c9d443904bbc475c7a4374f84296c6dc2c382f41e4f024320f64fd214c71934ebb9ee9619c036ef6f121d208995dc5df01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/702c4516a6e4879bb9f0a2c176db28163f63da627790097c6fb589c521a7c9f1ae6630519cde187803edd9af6ec2a7d8d48835df4382c90b577365edb4ada118", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/87664380d0f38f5ecdb1a1459c00d8f2cde2dc1f0930eb3d51409da2c9c8e1ca0f0d10230144946a2268784ff3d8dbe8bb9c7df62577e03bcf5d1c4ba1eeeaed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97f4cd2d3ee996c07b75780babacd84baf1a3e3b4023facd553595e842c494d7788cde06f074571cbd14a966c1509bce3dbfcda85edfdb445cc121e62dc10652"} 08:09:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:09:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:09:19 INFO - Contents: 08:09:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cbd77fcd1a8cef78825f466b1eaf82c9d443904bbc475c7a4374f84296c6dc2c382f41e4f024320f64fd214c71934ebb9ee9619c036ef6f121d208995dc5df01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/702c4516a6e4879bb9f0a2c176db28163f63da627790097c6fb589c521a7c9f1ae6630519cde187803edd9af6ec2a7d8d48835df4382c90b577365edb4ada118", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/87664380d0f38f5ecdb1a1459c00d8f2cde2dc1f0930eb3d51409da2c9c8e1ca0f0d10230144946a2268784ff3d8dbe8bb9c7df62577e03bcf5d1c4ba1eeeaed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97f4cd2d3ee996c07b75780babacd84baf1a3e3b4023facd553595e842c494d7788cde06f074571cbd14a966c1509bce3dbfcda85edfdb445cc121e62dc10652"} 08:09:19 INFO - Running post-run listener: copy_logs_to_upload_dir 08:09:19 INFO - Copying logs to upload dir... 08:09:19 INFO - mkdir: /builds/slave/test/build/upload/logs 08:09:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.251102 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-24 08:09:19.571812) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 08:09:19.575548) ========= 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.WCFM0aBFni/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.2RLxaVQMF5/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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/Mozilla-Beta/sha512/cbd77fcd1a8cef78825f466b1eaf82c9d443904bbc475c7a4374f84296c6dc2c382f41e4f024320f64fd214c71934ebb9ee9619c036ef6f121d208995dc5df01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/702c4516a6e4879bb9f0a2c176db28163f63da627790097c6fb589c521a7c9f1ae6630519cde187803edd9af6ec2a7d8d48835df4382c90b577365edb4ada118", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/87664380d0f38f5ecdb1a1459c00d8f2cde2dc1f0930eb3d51409da2c9c8e1ca0f0d10230144946a2268784ff3d8dbe8bb9c7df62577e03bcf5d1c4ba1eeeaed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97f4cd2d3ee996c07b75780babacd84baf1a3e3b4023facd553595e842c494d7788cde06f074571cbd14a966c1509bce3dbfcda85edfdb445cc121e62dc10652"} build_url:https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011963 build_url: 'https://queue.taskcluster.net/v1/task/MlPVMMTzRFGX293XG4vbhA/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cbd77fcd1a8cef78825f466b1eaf82c9d443904bbc475c7a4374f84296c6dc2c382f41e4f024320f64fd214c71934ebb9ee9619c036ef6f121d208995dc5df01", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/702c4516a6e4879bb9f0a2c176db28163f63da627790097c6fb589c521a7c9f1ae6630519cde187803edd9af6ec2a7d8d48835df4382c90b577365edb4ada118", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/87664380d0f38f5ecdb1a1459c00d8f2cde2dc1f0930eb3d51409da2c9c8e1ca0f0d10230144946a2268784ff3d8dbe8bb9c7df62577e03bcf5d1c4ba1eeeaed", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97f4cd2d3ee996c07b75780babacd84baf1a3e3b4023facd553595e842c494d7788cde06f074571cbd14a966c1509bce3dbfcda85edfdb445cc121e62dc10652"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 08:09:19.610961) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 08:09:19.611368) ========= 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.WCFM0aBFni/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.2RLxaVQMF5/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.005663 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 08:09:19.663232) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 08:09:19.664052) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 08:09:19.664497) ========= ========= Total master_lag: 0.20 =========