builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0164 starttime: 1495639204.08 results: success (0) buildid: 20170524064725 builduid: 134f840b30cd46afa7f0d497b43e08f6 revision: c58deb31c649510a264a7593359da8b910b01141 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 08:20:04.082386) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 08:20:04.083055) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 08:20:04.083382) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 08:20:04.121515) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 08:20:04.121840) ========= 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.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:20:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 173M=0s 2017-05-24 08:20:04 (173 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.193735 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 08:20:04.340300) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 08:20:04.340627) ========= 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.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.027351 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 08:20:04.434428) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-24 08:20:04.434832) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c58deb31c649510a264a7593359da8b910b01141 --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 c58deb31c649510a264a7593359da8b910b01141 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:20:04,528 truncating revision to first 12 chars 2017-05-24 08:20:04,528 Setting DEBUG logging. 2017-05-24 08:20:04,528 attempt 1/10 2017-05-24 08:20:04,528 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c58deb31c649?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 08:20:05,780 unpacking tar archive at: mozilla-beta-c58deb31c649/testing/mozharness/ program finished with exit code 0 elapsedTime=1.529289 ========= master_lag: 0.90 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-24 08:20:06.861943) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 08:20:06.862282) ========= script_repo_revision: c58deb31c649510a264a7593359da8b910b01141 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 08:20:06.862707) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 08:20:06.866044) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 08:20:07.399881) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-24 08:20:07.400251) ========= /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.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:20:07 INFO - MultiFileLogger online at 20170524 08:20:07 in /builds/slave/test 08:20:07 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 08:20:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:20:07 INFO - {'append_to_log': False, 08:20:07 INFO - 'base_work_dir': '/builds/slave/test', 08:20:07 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:20:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:20:07 INFO - 'branch': 'Mozilla-Beta', 08:20:07 INFO - 'buildbot_json_path': 'buildprops.json', 08:20:07 INFO - 'config_files': ('talos/mac_config.py',), 08:20:07 INFO - 'default_actions': ('clobber', 08:20:07 INFO - 'read-buildbot-config', 08:20:07 INFO - 'download-and-extract', 08:20:07 INFO - 'populate-webroot', 08:20:07 INFO - 'create-virtualenv', 08:20:07 INFO - 'install', 08:20:07 INFO - 'run-tests'), 08:20:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:20:07 INFO - 'download_minidump_stackwalk': True, 08:20:07 INFO - 'download_symbols': 'ondemand', 08:20:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:20:07 INFO - 'tooltool.py': '/tools/tooltool.py', 08:20:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:20:07 INFO - '/tools/misc-python/virtualenv.py')}, 08:20:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:20:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:20:07 INFO - 'gecko_profile': False, 08:20:07 INFO - 'gecko_profile_interval': 0, 08:20:07 INFO - 'installer_path': 'installer.exe', 08:20:07 INFO - 'log_level': 'info', 08:20:07 INFO - 'log_name': 'talos', 08:20:07 INFO - 'log_to_console': True, 08:20:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:20:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:20:07 INFO - 'opt_config_files': (), 08:20:07 INFO - 'pip_index': False, 08:20:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:20:07 INFO - 'cmd': ('bash', 08:20:07 INFO - '-c', 08:20:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:20:07 INFO - 'enabled': True, 08:20:07 INFO - 'halt_on_failure': False, 08:20:07 INFO - 'name': 'check_screen_resolution'},), 08:20:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:20:07 INFO - 'cmd': ('bash', 08:20:07 INFO - '-c', 08:20:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:20:07 INFO - 'enabled': True, 08:20:07 INFO - 'halt_on_failure': False, 08:20:07 INFO - 'name': 'check_screen_resolution'},), 08:20:07 INFO - 'run_cmd_checks_enabled': True, 08:20:07 INFO - 'suite': 'dromaeojs', 08:20:07 INFO - 'system_bits': '32', 08:20:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:20:07 INFO - 'title': 't-yosemite-r7-0164', 08:20:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:20:07 INFO - 'use_talos_json': True, 08:20:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:20:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:20:07 INFO - 'work_dir': 'build'} 08:20:07 INFO - [mozharness: 2017-05-24 15:20:07.605514Z] Running clobber step. 08:20:07 INFO - Running pre-action listener: _resource_record_pre_action 08:20:07 INFO - Running main action method: clobber 08:20:07 INFO - rmtree: /builds/slave/test/build 08:20:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:20:10 INFO - Running post-action listener: _resource_record_post_action 08:20:10 INFO - [mozharness: 2017-05-24 15:20:10.066931Z] Finished clobber step (success) 08:20:10 INFO - [mozharness: 2017-05-24 15:20:10.067094Z] Running read-buildbot-config step. 08:20:10 INFO - Running pre-action listener: _resource_record_pre_action 08:20:10 INFO - Running main action method: read_buildbot_config 08:20:10 INFO - Using buildbot properties: 08:20:10 INFO - { 08:20:10 INFO - "project": "", 08:20:10 INFO - "product": "firefox", 08:20:10 INFO - "script_repo_revision": "production", 08:20:10 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 08:20:10 INFO - "repository": "", 08:20:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 08:20:10 INFO - "buildid": "20170524064725", 08:20:10 INFO - "pgo_build": "False", 08:20:10 INFO - "basedir": "/builds/slave/test", 08:20:10 INFO - "buildnumber": 1, 08:20:10 INFO - "slavename": "t-yosemite-r7-0164", 08:20:10 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:20:10 INFO - "platform": "yosemite_r7_devedition", 08:20:10 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 08:20:10 INFO - "branch": "mozilla-beta", 08:20:10 INFO - "repo_path": "releases/mozilla-beta", 08:20:10 INFO - "revision": "c58deb31c649510a264a7593359da8b910b01141", 08:20:10 INFO - "stage_platform": "macosx64-devedition", 08:20:10 INFO - "builduid": "134f840b30cd46afa7f0d497b43e08f6", 08:20:10 INFO - "slavebuilddir": "test" 08:20:10 INFO - } 08:20:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 08:20:10 INFO - Running post-action listener: _resource_record_post_action 08:20:10 INFO - [mozharness: 2017-05-24 15:20:10.070374Z] Finished read-buildbot-config step (success) 08:20:10 INFO - [mozharness: 2017-05-24 15:20:10.070511Z] Running download-and-extract step. 08:20:10 INFO - Running pre-action listener: _resource_record_pre_action 08:20:10 INFO - Running main action method: download_and_extract 08:20:10 INFO - mkdir: /builds/slave/test/build/tests 08:20:10 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')]} 08:20:10 INFO - trying https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 08:20:10 INFO - Downloading https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/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 08:20:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/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 08:20:11 INFO - Downloaded 1287 bytes. 08:20:11 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 08:20:11 INFO - Using the following test package requirements: 08:20:11 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:20:11 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 08:20:11 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:20:11 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:20:11 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 08:20:11 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:20:11 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 08:20:11 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:20:11 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:20:11 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 08:20:11 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:20:11 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:20:11 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 08:20:11 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:20:11 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 08:20:11 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:20:11 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 08:20:11 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:20:11 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 08:20:11 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 08:20:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:20:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:20:11 INFO - Fetch https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:20:13 INFO - Expected file size: 51966952 08:20:13 INFO - Obtained file size: 51966952 08:20:28 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 08:20:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:20:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:20:28 INFO - Fetch https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:20:30 INFO - Expected file size: 51966952 08:20:30 INFO - Obtained file size: 51966952 08:20:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 08:20:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 08:20:45 INFO - Fetch https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 08:20:50 INFO - Expected file size: 14404449 08:20:50 INFO - Obtained file size: 14404449 08:20:51 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')]} 08:20:51 INFO - trying https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:20:51 INFO - Downloading https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 08:20:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/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 08:20:53 INFO - Downloaded 74889168 bytes. 08:20:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:20:53 INFO - mkdir: /builds/slave/test/properties 08:20:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:20:53 INFO - Writing to file /builds/slave/test/properties/build_url 08:20:53 INFO - Contents: 08:20:53 INFO - build_url:https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:20:53 INFO - Running post-action listener: _resource_record_post_action 08:20:53 INFO - Running post-action listener: set_extra_try_arguments 08:20:53 INFO - [mozharness: 2017-05-24 15:20:53.920061Z] Finished download-and-extract step (success) 08:20:53 INFO - [mozharness: 2017-05-24 15:20:53.920194Z] Running populate-webroot step. 08:20:53 INFO - Running pre-action listener: _resource_record_pre_action 08:20:53 INFO - Running main action method: populate_webroot 08:20:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:20:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:20:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:20:53 INFO - '--tppagecycles', 08:20:53 INFO - '7'], 08:20:53 INFO - 'tests': ['tsvgm']}}, 08:20:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:20:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:20:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:20:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:20:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:20:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:20:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:20:53 INFO - 'talos_options': ['--disable-e10s'], 08:20:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:20:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:20:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:20:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:20:53 INFO - 'talos_options': ['--disable-e10s'], 08:20:53 INFO - 'tests': ['damp', 'tps']}, 08:20:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:20:53 INFO - 'tests': ['damp', 'tps']}, 08:20:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:20:53 INFO - 'tests': ['dromaeo_dom']}, 08:20:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:20:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:20:53 INFO - 'tests': ['basic_compositor_video']}, 08:20:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:20:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:20:53 INFO - 'tests': ['a11yr', 08:20:53 INFO - 'ts_paint', 08:20:53 INFO - 'tpaint', 08:20:53 INFO - 'sessionrestore', 08:20:53 INFO - 'sessionrestore_no_auto_restore', 08:20:53 INFO - 'tabpaint']}, 08:20:53 INFO - 'other-e10s': {'tests': ['a11yr', 08:20:53 INFO - 'ts_paint', 08:20:53 INFO - 'tpaint', 08:20:53 INFO - 'sessionrestore', 08:20:53 INFO - 'sessionrestore_no_auto_restore', 08:20:53 INFO - 'tabpaint']}, 08:20:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:20:53 INFO - 'ts_paint', 08:20:53 INFO - 'tpaint', 08:20:53 INFO - 'sessionrestore', 08:20:53 INFO - 'sessionrestore_no_auto_restore', 08:20:53 INFO - 'tabpaint']}, 08:20:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:20:53 INFO - 'ts_paint', 08:20:53 INFO - 'tpaint', 08:20:53 INFO - 'sessionrestore', 08:20:53 INFO - 'sessionrestore_no_auto_restore', 08:20:53 INFO - 'tabpaint']}, 08:20:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:20:53 INFO - 'tests': ['a11yr', 08:20:53 INFO - 'ts_paint', 08:20:53 INFO - 'tpaint', 08:20:53 INFO - 'sessionrestore', 08:20:53 INFO - 'sessionrestore_no_auto_restore', 08:20:53 INFO - 'tabpaint']}, 08:20:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:20:53 INFO - 'tests': ['a11yr', 08:20:53 INFO - 'ts_paint', 08:20:53 INFO - 'tpaint', 08:20:53 INFO - 'sessionrestore', 08:20:53 INFO - 'sessionrestore_no_auto_restore', 08:20:53 INFO - 'tabpaint']}, 08:20:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:20:53 INFO - 'tests': ['tsvgx', 08:20:53 INFO - 'tsvgr_opacity', 08:20:53 INFO - 'tart', 08:20:53 INFO - 'tscrollx', 08:20:53 INFO - 'cart', 08:20:53 INFO - 'tsvg_static']}, 08:20:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:20:53 INFO - 'tsvgr_opacity', 08:20:53 INFO - 'tart', 08:20:53 INFO - 'tscrollx', 08:20:53 INFO - 'cart', 08:20:53 INFO - 'tsvg_static']}, 08:20:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:20:53 INFO - 'talos_options': ['--disable-e10s'], 08:20:53 INFO - 'tests': ['tp5o']}, 08:20:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:20:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:20:53 INFO - 'talos_options': ['--disable-e10s', 08:20:53 INFO - '--xperf_path', 08:20:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:20:53 INFO - 'tests': ['tp5n']}, 08:20:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:20:53 INFO - 'talos_options': ['--xperf_path', 08:20:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:20:53 INFO - 'tests': ['tp5n']}}, 08:20:53 INFO - 'talos.zip': {'path': '', 08:20:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:20:53 INFO - Running post-action listener: _resource_record_post_action 08:20:53 INFO - [mozharness: 2017-05-24 15:20:53.938700Z] Finished populate-webroot step (success) 08:20:53 INFO - [mozharness: 2017-05-24 15:20:53.938835Z] Running create-virtualenv step. 08:20:53 INFO - Running pre-action listener: _resource_record_pre_action 08:20:53 INFO - Running main action method: create_virtualenv 08:20:53 INFO - Creating virtualenv /builds/slave/test/build/venv 08:20:53 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 08:20:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:20:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:20:54 INFO - Using real prefix '/tools/python27' 08:20:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:20:54 INFO - Installing distribute.............................................................................................................................................................................................done. 08:20:57 INFO - Installing pip.................done. 08:20:57 INFO - Return code: 0 08:20:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:20:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:20:57 INFO - Reading from file tmpfile_stdout 08:20:57 INFO - Output received: 08:20:57 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) 08:20:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:20:57 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')]} 08:20:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:57 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')]} 08:20:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x100efdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8bc888b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100c02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0be8>, '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.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:20:57 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 08:20:57 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 08:20:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEZ8JWNcIN/Render', 08:20:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:57 INFO - 'HOME': '/Users/cltbld', 08:20:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:57 INFO - 'LOGNAME': 'cltbld', 08:20:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:57 INFO - 'MOZ_NO_REMOTE': '1', 08:20:57 INFO - 'NO_EM_RESTART': '1', 08:20:57 INFO - 'PAGER': '/bin/cat', 08:20:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:57 INFO - 'PWD': '/builds/slave/test', 08:20:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:57 INFO - 'SHELL': '/bin/bash', 08:20:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne4xYCPx2a/Listeners', 08:20:57 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:20:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:57 INFO - 'USER': 'cltbld', 08:20:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:57 INFO - 'XPC_FLAGS': '0x0', 08:20:57 INFO - 'XPC_SERVICE_NAME': '0', 08:20:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:58 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 08:20:58 INFO - Cleaning up... 08:20:58 INFO - Return code: 0 08:20:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:20:58 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')]} 08:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:58 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')]} 08:20:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:58 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 0x100efdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8bc888b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100c02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0be8>, '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.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:20:58 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 08:20:58 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 08:20:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEZ8JWNcIN/Render', 08:20:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:58 INFO - 'HOME': '/Users/cltbld', 08:20:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:58 INFO - 'LOGNAME': 'cltbld', 08:20:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:58 INFO - 'MOZ_NO_REMOTE': '1', 08:20:58 INFO - 'NO_EM_RESTART': '1', 08:20:58 INFO - 'PAGER': '/bin/cat', 08:20:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:58 INFO - 'PWD': '/builds/slave/test', 08:20:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:58 INFO - 'SHELL': '/bin/bash', 08:20:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne4xYCPx2a/Listeners', 08:20:58 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:20:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:58 INFO - 'USER': 'cltbld', 08:20:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:58 INFO - 'XPC_FLAGS': '0x0', 08:20:58 INFO - 'XPC_SERVICE_NAME': '0', 08:20:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:58 INFO - Downloading/unpacking psutil>=3.1.1 08:20:58 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 08:20:58 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 08:20:58 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 08:20:58 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 08:21:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:21:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:21:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:21:00 INFO - Installing collected packages: psutil 08:21:00 INFO - Running setup.py install for psutil 08:21:00 INFO - building 'psutil._psutil_osx' extension 08:21:00 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:21:01 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 08:21:01 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 08:21:01 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 08:21:01 INFO - building 'psutil._psutil_posix' extension 08:21:01 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 08:21:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:21:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:21:01 INFO - ^ 08:21:01 INFO - 1 warning generated. 08:21:01 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 08:21:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:21:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:21:01 INFO - Successfully installed psutil 08:21:01 INFO - Cleaning up... 08:21:01 INFO - Return code: 0 08:21:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:21:01 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')]} 08:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:01 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')]} 08:21:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x100efdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8bc888b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100c02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0be8>, '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.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:21:01 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 08:21:01 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 08:21:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEZ8JWNcIN/Render', 08:21:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:01 INFO - 'HOME': '/Users/cltbld', 08:21:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:01 INFO - 'LOGNAME': 'cltbld', 08:21:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:01 INFO - 'MOZ_NO_REMOTE': '1', 08:21:01 INFO - 'NO_EM_RESTART': '1', 08:21:01 INFO - 'PAGER': '/bin/cat', 08:21:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:01 INFO - 'PWD': '/builds/slave/test', 08:21:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:01 INFO - 'SHELL': '/bin/bash', 08:21:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne4xYCPx2a/Listeners', 08:21:01 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:21:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:01 INFO - 'USER': 'cltbld', 08:21:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:01 INFO - 'XPC_FLAGS': '0x0', 08:21:01 INFO - 'XPC_SERVICE_NAME': '0', 08:21:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:21: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 08:21: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 08:21: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 08:21: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 08:21:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:21:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:21:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:21:03 INFO - Installing collected packages: mozsystemmonitor 08:21:03 INFO - Running setup.py install for mozsystemmonitor 08:21:03 INFO - Successfully installed mozsystemmonitor 08:21:03 INFO - Cleaning up... 08:21:03 INFO - Return code: 0 08:21:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:21:03 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')]} 08:21:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:03 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')]} 08:21:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:03 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 0x100efdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8bc888b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100c02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0be8>, '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.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:21:03 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 08:21:03 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 08:21:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEZ8JWNcIN/Render', 08:21:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:03 INFO - 'HOME': '/Users/cltbld', 08:21:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:03 INFO - 'LOGNAME': 'cltbld', 08:21:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:03 INFO - 'MOZ_NO_REMOTE': '1', 08:21:03 INFO - 'NO_EM_RESTART': '1', 08:21:03 INFO - 'PAGER': '/bin/cat', 08:21:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:03 INFO - 'PWD': '/builds/slave/test', 08:21:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:03 INFO - 'SHELL': '/bin/bash', 08:21:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne4xYCPx2a/Listeners', 08:21:03 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:21:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:03 INFO - 'USER': 'cltbld', 08:21:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:03 INFO - 'XPC_FLAGS': '0x0', 08:21:03 INFO - 'XPC_SERVICE_NAME': '0', 08:21:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:04 INFO - Downloading/unpacking jsonschema==2.5.1 08:21: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 08:21: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 08:21: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 08:21: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 08:21:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:21:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:21: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 08:21: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 08:21: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 08:21: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 08:21:05 INFO - Downloading functools32-3.2.3-2.tar.gz 08:21:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:21:05 INFO - warning: no files found matching '*.txt' 08:21:05 INFO - no previously-included directories found matching 'build' 08:21:05 INFO - no previously-included directories found matching 'dist' 08:21:05 INFO - no previously-included directories found matching '.git*' 08:21:05 INFO - Installing collected packages: jsonschema, functools32 08:21:05 INFO - Running setup.py install for functools32 08:21:06 INFO - warning: no files found matching '*.txt' 08:21:06 INFO - no previously-included directories found matching 'build' 08:21:06 INFO - no previously-included directories found matching 'dist' 08:21:06 INFO - no previously-included directories found matching '.git*' 08:21:06 INFO - Successfully installed jsonschema functools32 08:21:06 INFO - Cleaning up... 08:21:06 INFO - Return code: 0 08:21:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:21: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')]} 08:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21: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')]} 08:21:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:06 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 0x100efdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8bc888b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100c02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0be8>, '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.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:21:06 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 08:21:06 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 08:21:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEZ8JWNcIN/Render', 08:21:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:06 INFO - 'HOME': '/Users/cltbld', 08:21:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:06 INFO - 'LOGNAME': 'cltbld', 08:21:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:06 INFO - 'MOZ_NO_REMOTE': '1', 08:21:06 INFO - 'NO_EM_RESTART': '1', 08:21:06 INFO - 'PAGER': '/bin/cat', 08:21:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:06 INFO - 'PWD': '/builds/slave/test', 08:21:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:06 INFO - 'SHELL': '/bin/bash', 08:21:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne4xYCPx2a/Listeners', 08:21:06 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:21:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:06 INFO - 'USER': 'cltbld', 08:21:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:06 INFO - 'XPC_FLAGS': '0x0', 08:21:06 INFO - 'XPC_SERVICE_NAME': '0', 08:21:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:21:06 INFO - Cleaning up... 08:21:06 INFO - Return code: 0 08:21:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:21: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')]} 08:21:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21: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')]} 08:21:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:06 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 0x100efdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8bc888b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100c02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0be8>, '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.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:21:06 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 08:21:06 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 08:21:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEZ8JWNcIN/Render', 08:21:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:06 INFO - 'HOME': '/Users/cltbld', 08:21:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:06 INFO - 'LOGNAME': 'cltbld', 08:21:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:06 INFO - 'MOZ_NO_REMOTE': '1', 08:21:06 INFO - 'NO_EM_RESTART': '1', 08:21:06 INFO - 'PAGER': '/bin/cat', 08:21:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:06 INFO - 'PWD': '/builds/slave/test', 08:21:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:06 INFO - 'SHELL': '/bin/bash', 08:21:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne4xYCPx2a/Listeners', 08:21:06 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:21:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:06 INFO - 'USER': 'cltbld', 08:21:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:06 INFO - 'XPC_FLAGS': '0x0', 08:21:06 INFO - 'XPC_SERVICE_NAME': '0', 08:21:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:06 INFO - Downloading/unpacking blobuploader==1.2.4 08:21:06 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 08:21:06 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 08:21:06 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 08:21:06 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 08:21:08 INFO - Downloading blobuploader-1.2.4.tar.gz 08:21:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:21:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:21:08 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:09 INFO - Downloading docopt-0.6.1.tar.gz 08:21:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:21:09 INFO - Installing collected packages: blobuploader, requests, docopt 08:21:09 INFO - Running setup.py install for blobuploader 08:21:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:21:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:21:09 INFO - Running setup.py install for requests 08:21:09 INFO - Running setup.py install for docopt 08:21:09 INFO - Successfully installed blobuploader requests docopt 08:21:09 INFO - Cleaning up... 08:21:09 INFO - Return code: 0 08:21:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:21:09 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')]} 08:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:09 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')]} 08:21:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x100efdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8bc888b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100c02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0be8>, '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.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:21:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:21:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:21:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEZ8JWNcIN/Render', 08:21:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:09 INFO - 'HOME': '/Users/cltbld', 08:21:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:09 INFO - 'LOGNAME': 'cltbld', 08:21:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:09 INFO - 'MOZ_NO_REMOTE': '1', 08:21:09 INFO - 'NO_EM_RESTART': '1', 08:21:09 INFO - 'PAGER': '/bin/cat', 08:21:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:09 INFO - 'PWD': '/builds/slave/test', 08:21:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:09 INFO - 'SHELL': '/bin/bash', 08:21:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne4xYCPx2a/Listeners', 08:21:09 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:21:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:09 INFO - 'USER': 'cltbld', 08:21:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:09 INFO - 'XPC_FLAGS': '0x0', 08:21:09 INFO - 'XPC_SERVICE_NAME': '0', 08:21:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:21:10 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-OWdesv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:21:10 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-hntfL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:21:10 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-clzEpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:21:10 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-bLVui0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:21:10 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-g3l6Py-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:21:10 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-rJLt4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:21:10 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-X_sggm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:21:10 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-mB_iki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:21:11 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-8sXSTt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:21:11 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-FPlTwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:21:11 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-j5oNfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:21:11 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-IQa736-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:21:11 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-F2qICA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:21:11 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ZwjeLv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:21:11 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-JXvQ8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:21:11 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-foiTBQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:21:11 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-f2hMzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:21:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:21:11 INFO - Running setup.py install for manifestparser 08:21:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:21:12 INFO - Running setup.py install for mozcrash 08:21:12 INFO - Running setup.py install for mozdebug 08:21:12 INFO - Running setup.py install for mozdevice 08:21:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:21:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:21:12 INFO - Running setup.py install for mozfile 08:21:12 INFO - Running setup.py install for mozhttpd 08:21:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:21:12 INFO - Running setup.py install for mozinfo 08:21:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:21:12 INFO - Running setup.py install for mozInstall 08:21:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:21:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:21:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:21:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:21:13 INFO - Running setup.py install for mozleak 08:21:13 INFO - Running setup.py install for mozlog 08:21:13 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:21:13 INFO - Running setup.py install for moznetwork 08:21:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:21:13 INFO - Running setup.py install for mozprocess 08:21:13 INFO - Running setup.py install for mozprofile 08:21:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:21:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:21:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:21:13 INFO - Running setup.py install for mozrunner 08:21:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:21:14 INFO - Running setup.py install for mozscreenshot 08:21:14 INFO - Running setup.py install for moztest 08:21:14 INFO - Running setup.py install for mozversion 08:21:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:21:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:21:14 INFO - Cleaning up... 08:21:14 INFO - Return code: 0 08:21:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:21:14 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')]} 08:21:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:14 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')]} 08:21:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x100efdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8bc888b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100c02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0be8>, '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.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:21:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:21:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:21:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEZ8JWNcIN/Render', 08:21:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:14 INFO - 'HOME': '/Users/cltbld', 08:21:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:14 INFO - 'LOGNAME': 'cltbld', 08:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:14 INFO - 'MOZ_NO_REMOTE': '1', 08:21:14 INFO - 'NO_EM_RESTART': '1', 08:21:14 INFO - 'PAGER': '/bin/cat', 08:21:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:14 INFO - 'PWD': '/builds/slave/test', 08:21:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:14 INFO - 'SHELL': '/bin/bash', 08:21:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne4xYCPx2a/Listeners', 08:21:14 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:21:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:14 INFO - 'USER': 'cltbld', 08:21:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:14 INFO - 'XPC_FLAGS': '0x0', 08:21:14 INFO - 'XPC_SERVICE_NAME': '0', 08:21:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:21:14 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-QlX6di-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:21:14 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:21:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:21:14 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-PCmX1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:21:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:21:15 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-zmwahl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:21:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:21:15 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-Y51OwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:21:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:21:15 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-gVuFvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:21:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:21:15 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-9eFN6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:21:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:21:15 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-nhzsOe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:21:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:21:15 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-eed1O7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:21:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:21:15 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-VvV_To-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:21:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:21:15 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-AelrXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:21:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:21:15 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-yHLien-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:21:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:21:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:21:15 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-efUF1N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:21:16 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)) 08:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:21:16 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-NRjHwU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:21:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:21:16 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-G_YjFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:21:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:21:16 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-ufi5Ts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:21:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:21:16 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-muzJ57-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:21:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:21:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:21:16 INFO - Running setup.py (path:/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/pip-2Cm902-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:21:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:21:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:21:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:21:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:21:16 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:21:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:21:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:21:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:21:18 INFO - Downloading blessings-1.6.tar.gz 08:21:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:21:18 INFO - Installing collected packages: blessings 08:21:18 INFO - Running setup.py install for blessings 08:21:18 INFO - Successfully installed blessings 08:21:18 INFO - Cleaning up... 08:21:18 INFO - Return code: 0 08:21:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:21:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:21:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:21:18 INFO - Reading from file tmpfile_stdout 08:21:18 INFO - Current package versions: 08:21:18 INFO - blessings == 1.6 08:21:18 INFO - blobuploader == 1.2.4 08:21:18 INFO - docopt == 0.6.1 08:21:18 INFO - functools32 == 3.2.3-2 08:21:18 INFO - jsonschema == 2.5.1 08:21:18 INFO - manifestparser == 1.1 08:21:18 INFO - mozInstall == 1.12 08:21:18 INFO - mozcrash == 1.0 08:21:18 INFO - mozdebug == 0.1 08:21:18 INFO - mozdevice == 0.49 08:21:18 INFO - mozfile == 1.2 08:21:18 INFO - mozhttpd == 0.7 08:21:18 INFO - mozinfo == 0.9 08:21:18 INFO - mozleak == 0.1 08:21:18 INFO - mozlog == 3.4 08:21:18 INFO - moznetwork == 0.27 08:21:18 INFO - mozprocess == 0.25 08:21:18 INFO - mozprofile == 0.28 08:21:18 INFO - mozrunner == 6.13 08:21:18 INFO - mozscreenshot == 0.1 08:21:18 INFO - mozsystemmonitor == 0.3 08:21:18 INFO - moztest == 0.8 08:21:18 INFO - mozversion == 1.4 08:21:18 INFO - psutil == 3.1.1 08:21:18 INFO - requests == 1.2.3 08:21:18 INFO - wsgiref == 0.1.2 08:21:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:21:18 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')]} 08:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:18 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')]} 08:21:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:18 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 0x100efdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8bc888b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100c02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0be8>, '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.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:21:18 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 08:21:18 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 08:21:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEZ8JWNcIN/Render', 08:21:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:18 INFO - 'HOME': '/Users/cltbld', 08:21:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:18 INFO - 'LOGNAME': 'cltbld', 08:21:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:18 INFO - 'MOZ_NO_REMOTE': '1', 08:21:18 INFO - 'NO_EM_RESTART': '1', 08:21:18 INFO - 'PAGER': '/bin/cat', 08:21:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:18 INFO - 'PWD': '/builds/slave/test', 08:21:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:18 INFO - 'SHELL': '/bin/bash', 08:21:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne4xYCPx2a/Listeners', 08:21:18 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:21:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:18 INFO - 'USER': 'cltbld', 08:21:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:18 INFO - 'XPC_FLAGS': '0x0', 08:21:18 INFO - 'XPC_SERVICE_NAME': '0', 08:21:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:18 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)) 08:21:18 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)) 08:21:18 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)) 08:21:18 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)) 08:21:18 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)) 08:21:18 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)) 08:21:18 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)) 08:21:18 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)) 08:21:18 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)) 08:21:18 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)) 08:21:18 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)) 08:21:18 INFO - Cleaning up... 08:21:19 INFO - Return code: 0 08:21:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:21:19 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')]} 08:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:19 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')]} 08:21:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:19 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 0x100efdb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f8bc888b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cdbd30>, '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 0x100c02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0be8>, '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.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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 08:21:19 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 08:21:19 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 08:21:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEZ8JWNcIN/Render', 08:21:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:19 INFO - 'HOME': '/Users/cltbld', 08:21:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:19 INFO - 'LOGNAME': 'cltbld', 08:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:19 INFO - 'MOZ_NO_REMOTE': '1', 08:21:19 INFO - 'NO_EM_RESTART': '1', 08:21:19 INFO - 'PAGER': '/bin/cat', 08:21:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:19 INFO - 'PWD': '/builds/slave/test', 08:21:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:19 INFO - 'SHELL': '/bin/bash', 08:21:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne4xYCPx2a/Listeners', 08:21:19 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:21:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:19 INFO - 'USER': 'cltbld', 08:21:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:19 INFO - 'XPC_FLAGS': '0x0', 08:21:19 INFO - 'XPC_SERVICE_NAME': '0', 08:21:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:21:19 INFO - Cleaning up... 08:21:19 INFO - Return code: 0 08:21:19 INFO - Running post-action listener: _resource_record_post_action 08:21:19 INFO - Running post-action listener: _start_resource_monitoring 08:21:19 INFO - Starting resource monitoring. 08:21:19 INFO - [mozharness: 2017-05-24 15:21:19.281649Z] Finished create-virtualenv step (success) 08:21:19 INFO - [mozharness: 2017-05-24 15:21:19.282601Z] Running install step. 08:21:19 INFO - Running pre-action listener: _resource_record_pre_action 08:21:19 INFO - Running main action method: install 08:21:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:21:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:21:19 INFO - Reading from file tmpfile_stdout 08:21:19 INFO - Detecting whether we're running mozinstall >=1.0... 08:21:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:21:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:21:19 INFO - Reading from file tmpfile_stdout 08:21:19 INFO - Output received: 08:21:19 INFO - Usage: mozinstall [options] installer 08:21:19 INFO - Options: 08:21:19 INFO - -h, --help show this help message and exit 08:21:19 INFO - -d DEST, --destination=DEST 08:21:19 INFO - Directory to install application into. [default: 08:21:19 INFO - "/builds/slave/test"] 08:21:19 INFO - --app=APP Application being installed. [default: firefox] 08:21:19 INFO - mkdir: /builds/slave/test/build/application 08:21:19 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'] 08:21:19 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 08:21:40 INFO - Reading from file tmpfile_stdout 08:21:40 INFO - Output received: 08:21:40 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:21:40 INFO - Running post-action listener: _resource_record_post_action 08:21:40 INFO - [mozharness: 2017-05-24 15:21:40.578502Z] Finished install step (success) 08:21:40 INFO - [mozharness: 2017-05-24 15:21:40.578645Z] Running run-tests step. 08:21:40 INFO - Running pre-action listener: _resource_record_pre_action 08:21:40 INFO - Running main action method: run_tests 08:21:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:21:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:21:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:21:40 INFO - 2017-05-24 08:21:40.592 screenresolution[1626:7617] starting screenresolution argv=screenresolution get 08:21:40 INFO - 2017-05-24 08:21:40.594 screenresolution[1626:7617] Display 0: 1600x1200x32@60 08:21:40 INFO - 2017-05-24 08:21:40.599 screenresolution[1627:7620] starting screenresolution argv=screenresolution list 08:21:40 INFO - Available Modes on Display 0 08:21:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:21:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:21:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:21:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:21:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:21:40 INFO - Intel Iris: 08:21:40 INFO - Chipset Model: Intel Iris 08:21:40 INFO - Type: GPU 08:21:40 INFO - Bus: Built-In 08:21:40 INFO - VRAM (Dynamic, Max): 1536 MB 08:21:40 INFO - Vendor: Intel (0x8086) 08:21:40 INFO - Device ID: 0x0a2e 08:21:40 INFO - Revision ID: 0x0009 08:21:40 INFO - Displays: 08:21:40 INFO - Display: 08:21:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:21:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:21:40 INFO - Display Serial Number: 1600x1200 60 08:21:40 INFO - Main Display: Yes 08:21:40 INFO - Mirror: Off 08:21:40 INFO - Online: Yes 08:21:40 INFO - Rotation: Supported 08:21:40 INFO - Return code: 0 08:21:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:21:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:21:40 INFO - Python 2.7.3 08:21:40 INFO - Return code: 0 08:21:40 INFO - grabbing minidump binary from tooltool 08:21:40 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')]} 08:21:40 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 0x100cdbd30>, '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 0x100c02db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd0be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:21:40 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 08:21:40 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 08:21:40 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 08:21:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:21:40 INFO - Return code: 0 08:21:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:21:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:21:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:21:40 INFO - ENV: RUST_BACKTRACE is now 1 08:21:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:21:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:21:40 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--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 08:21:40 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0164 --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 08:21:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UEZ8JWNcIN/Render', 08:21:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:40 INFO - 'HOME': '/Users/cltbld', 08:21:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:40 INFO - 'LOGNAME': 'cltbld', 08:21:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:21:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:21:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:40 INFO - 'MOZ_NO_REMOTE': '1', 08:21:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:21:40 INFO - 'NO_EM_RESTART': '1', 08:21:40 INFO - 'PAGER': '/bin/cat', 08:21:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:40 INFO - 'PWD': '/builds/slave/test', 08:21:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:21:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:40 INFO - 'RUST_BACKTRACE': '1', 08:21:40 INFO - 'SHELL': '/bin/bash', 08:21:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ne4xYCPx2a/Listeners', 08:21:40 INFO - 'TMPDIR': '/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/', 08:21:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:40 INFO - 'USER': 'cltbld', 08:21:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:40 INFO - 'XPC_FLAGS': '0x0', 08:21:40 INFO - 'XPC_SERVICE_NAME': '0', 08:21:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:40 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0164', '--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 08:21:41 INFO - mozversion application_buildid: 20170524064725 08:21:41 INFO - mozversion application_changeset: c58deb31c649510a264a7593359da8b910b01141 08:21:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:21:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:21:41 INFO - mozversion application_name: Firefox 08:21:41 INFO - mozversion application_remotingname: firefox-dev 08:21:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:21:41 INFO - mozversion application_vendor: Mozilla 08:21:41 INFO - mozversion application_version: 54.0 08:21:41 INFO - mozversion platform_buildid: 20170524064725 08:21:41 INFO - mozversion platform_changeset: c58deb31c649510a264a7593359da8b910b01141 08:21:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:21:41 INFO - mozversion platform_version: 54.0 08:21:41 INFO - using testdate: 1495639301 08:21:41 INFO - actual date: 1495639301 08:21:41 INFO - starting webserver on 'localhost:49224' 08:21:41 INFO - SUITE-START | Running 2 tests 08:21:41 INFO - TEST-START | dromaeo_css 08:21:41 INFO - operating with platform_type : mac_ 08:21:41 INFO - Initialising browser for dromaeo_css test... 08:21:41 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpcYnqTX/profile http://localhost:49224/getInfo.html) 08:21:43 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 08:21:43 INFO - PROCESS | 1637 | colorDepth:24 08:21:43 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 08:21:43 INFO - PROCESS | 1637 | __metrics 08:21:43 INFO - PROCESS | 1637 | 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:21:43 INFO - PROCESS | 1637 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:21:44 INFO - TEST-INFO | 1637: exit 0 08:21:44 INFO - Browser initialized. 08:21:44 INFO - Running cycle 1/1 for dromaeo_css test... 08:21:44 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpcYnqTX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:21:56 INFO - PROCESS | 1640 | RSS: Main: 193540096 08:21:56 INFO - PROCESS | 1640 | 08:25:00 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 08:25:02 INFO - PROCESS | 1640 | RSS: Main: 332763136 08:25:02 INFO - PROCESS | 1640 | 08:28:05 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 08:28:05 INFO - PROCESS | 1640 | RSS: Main: 326709248 08:28:05 INFO - PROCESS | 1640 | 08:31:08 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 08:31:08 INFO - PROCESS | 1640 | RSS: Main: 292954112 08:31:08 INFO - PROCESS | 1640 | 08:34:11 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 08:34:11 INFO - PROCESS | 1640 | RSS: Main: 307601408 08:34:11 INFO - PROCESS | 1640 | 08:37:14 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 08:37:15 INFO - PROCESS | 1640 | RSS: Main: 295723008 08:37:15 INFO - PROCESS | 1640 | 08:40:18 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 08:40:18 INFO - PROCESS | 1640 | RSS: Main: 446320640 08:40:18 INFO - PROCESS | 1640 | 08:40:18 INFO - PROCESS | 1640 | __start_tp_report 08:40:18 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 08:40:18 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 08:40:18 INFO - PROCESS | 1640 | |i|pagename|runs| 08:40:18 INFO - PROCESS | 1640 | |0;dojo.html;3407.046027112408;3424.6232914379416;3429.39985502537;3431.7597768156224;3479.565054368204;4685.273782563704;5895.056790913454;5905.468507834299;5910;5921.082232253997;4880.78036488358;5857.7364018619155;5886.793962211324;5963.522918166547;5969.611975221607;5301.6023798215;5332.493413125766;5339.332583427072;5393.838184854441;5395.136778115502;4520.299353600197;5236.738163091849;5242.6330156889035;5277.102892508264;5514.255575497296;5367.329083864517;5377.247185394048;5382.515573598375;5393.2719082923895;5395.622306438551;5420.91868621971;5434.538064264542;5451.918221226685;5455.536279416255;5457.699826509541;4926.729029903354;5279.260903473517;5336.146216605363;5360.383555891083;5447.6731396115965;5864.296284445851;5871.119332100219;5912;5953.851153721148;5975.014122669754;5380.865478363033;6318.936810631881;6328.65192414417;6340.873182536369;6347.365263473662;6398.488120950313;6436.967815160941;6451.612903225775;6462.224533056016;6462.4183823456115;5872.471477567039;5897.557683173779;6034.788782392595;6083.209182806207;6114.205153330127;5931.961906666316;6269.247690277196;6474.870502589922;6485.189351331083;6530.608163510205;6291.842703932438;6344.904826427607;6359.554831161774;6359.872802543923;6361.2048493938255;6245.750169993149;6298.716557754912;6333.461655759312;6345.302016778151;6417.1657684500715;6198.132261483396;6365.681715914186;6372.776952806629;6374.58565193261;6392.137061496732;6306.054091886253;6313.2424109107205;6320.27316858558;6328.746850125944;6392.296847346788;4867.513248675175;4889;4911.557959783565;4919.532644398776;4919.803207871645;4343.869683909425;4414.426124603781;4425.867223983286;4436.556344365537;4445.044315472189;4994.150994330972;5743.827685169368;5756.366799652035;5763.193152958589;5787.768489260467;6323.810285691437;6332.176817013851;6350.33321501245;6400.199975003125;6430.421262086341;5923.052311630118;5925.73334199962;5939.049752039653;5942.70286485674;5978.521718262528;5516.365617953907;5541.141123125922;5571.972140139354;5577.414371491016;5581.386047534768;6302.1177035215105;6341.365863413622;6470.838229044311;6513.120728701754;6514.609123452608;6416.230052393743;6418.679066046772;6429.935700643121;6432.292447830829;6447.516436267392;6528.37980391862;6554.606723596599;6610.206775187008;6620.735170593122;6623.602583844985;6210.689465526796;6264.404881536247;6296.496280297473;6328.493720502441;6376.426121649074;5250.684958902478;6169;6386.776462823965;6404.103425520524;6407.839804004937;6335.841603959938;6336.651484168231;6373.298937117006;6392.169018027626;6394.136791533084;6120.508083830795;6172.660503672341;6266.060090986389;6288.402601752826;6395.392437718409;6213.2233470816145;6279;6287.119803227643;6292.685365731603;6297.811065668037;6360.014197799349;6368.0766288886925;6368.363163683594;6379.872402552016;6385.457236134795;5932.377100404568;6014.308354539109;6064.02975523914;6115.088229002258;6167.352427994996 08:40:18 INFO - PROCESS | 1640 | |1;ext.html;762.1768490030768;793.4247670438931;807.9636416361265;879.4898958604007;889.7508697564684;28985.855070724643;29359.119226423216;29763.107106786832;41416.585834141646;42333.57666423335;42285;42483.787581062126;42525.362119568155;42545.78727106368;42682.359764603476;9467.479288639122;9631.61473541061;9631.807363852718;9643.566039528238;9646.938836727953;7493.925060749404;7651.273129052731;9430.10414010668;9531.571079301448;11575.305481671085;11633.883661163407;11663.650090497298;11679.7080072998;11688.006519445815;11809.704757381049;11613;11639.418029098595;11651.708707282301;11658.067354611696;11659.300441973424;11297.209195356329;11365.943170284178;11369.237768145951;11374.545018199262;11389.943050284779;39839.20321593552;39989.000274993356;39998;40030.59969400298;40099.59900400988;3848.2852963349683;3873.573906870242;3878.631530004645;3924.4113382992496;3947.5460322063045;27319.45361092767;27763.167104986882;29606.85196574025;29828.850855745583;29845.25386865324;3034.9393012139853;3105.7659349339074;3134.670859559759;3253.1216571525792;3269.623993240784;314621;315310;316504;316757;316895.4155229232;52343;53096.203556946675;53169;53201;53225;56796.716016419654;56898.7155064222;57004.71497642485;57060.57348566319;57074;14731.779023314659;15135.621609459851;15144.924275378553;15159.848401515843;15161.317740701696;12955.287459189647;12967.286799226133;12976.35118244084;12976.740465190644;12999.740005200032;10399.792004159874;10900.890991090146;11089.778204436028;11250.662480125446;12901.096923215473;4757.95242047582;5008.574271186917;5374.871277031789;5566.053770858964;5607.52336051432;1093.4969913839564;1118.5134466507095;1135.1202817816093;1146.931184128955;1181.1908842442954;27103.45793084167;27176.59235111475;27473.31316717098;27775.750091245944;27937.441251174863;1214.5384753793678;1244.661988362514;1262.678017105632;1278.1244847279643;1286.5497076023316;13889.583312500641;14235.868109016;14336.21150836693;14433.711325773636;14446.422143114369;458851;459913;460086;460988;461188;49468.75265623721;50591.494085059414;53201;53250.73374633102;58433.4156658428;1245.7197130645714;1382.3433868912186;1387.9520961673873;1417.0505761140266;1418.2341535570883;23991.28026159218;26991;27642.861785691748;28158.15525534237;28200;42890.49883254071;43086;43150.4897328592;43186.35220471758;43208.70373888788;492368;493952;494322;495147;495261;24640.630390544517;29532.704672952994;30045.399092018473;30099.548506772855;30194.396112078073;29628.555571666013;30770.53844192294;31653.050408487783;39883;40064.34679536035;26588.601170982838;27927.301817453914;28885.566716498848;28955;29069.85465072661;27976;28574;28979;29056.854715726287;29135.980240691475;32241;32919.50620740739;33515.66484335125;33539.83230083834;33555;28250.43499130047;28253.293667658472;28386.290342741595;28620.713792862636;29474.705252948053;22135.889320553295;22718.318450446513;23134;23168.76831231666;23186 08:40:18 INFO - PROCESS | 1640 | |2;jquery.html;405.3514376996804;411.3131071111244;422.4297198781644;430.84274239902436;447.52115236696727;19827.801721982793;21000.579988400223;22521.09915603374;22581.54836903263;22582.98376573054;21964.670529942065;22970.425739356524;23058;23244;23700.88149559254;6663.300353462865;6822.52242343035;7021.052157958661;7072.681729322193;7145.35691787739;6853.760118395857;6906.930930690704;6949.548279361826;6972.1284839395075;7017.508774385769;6584.934150658504;6838.179418469766;6933.1333583302085;6935.375816176543;6937.7224911003495;6518.250401203874;6866.965665171667;6940.583564986117;6950.582965022064;6955.513114082017;6687.665616719145;6755.1556055493065;6774.45804335652;6776.2546119927265;6785.050092986986;25247.873760631264;27672.723272767216;27733.58399624007;28012;28158.577621335695;2987.3875855449796;2993.790434669574;3003.1290925631542;3005.654349749785;3010.142109498804;16183.514494565183;17078.31686732529;17316.393926212648;17316.826831731647;17718.822811771846;2000.6998950157447;2051.3640771360924;2054.845886558514;2056.4139220322136;2071.637463443891;127857.72142278551;129097.35451322683;129776;132874.3356283222;134133;21495.80831737796;21913.780862191175;21991.780082199293;22006.339809805733;22248.33020037606;36268.91193264206;36507;37061.814690926374;37106.8144659275;37186.814065929495;10745.51645175969;10755.623553175601;10865.076468500109;10939.671809845717;11129.054030407506;8919.821603567892;8924.821503569892;8946.463212207289;9115.680951166678;9120.270378369714;8714.825703486022;10205.591776328863;10508.579656813794;10660.733481662865;10670.35977841332;3624.5106910567265;4118.546959834416;4255.170241802819;4265.758023901857;4266.975925777853;1054.2957314488312;1109.7059279290995;1117.4245263689206;1150.5627861412609;1156.8264760286024;17795.73306400405;23586.17448389298;23671.644925326134;23674.881625591763;23676.052957881835;1106.6790630717162;1118.0943435817014;1128.4752590045669;1173.3546549397797;1179.722765150189;12241.326727030098;12375.381230938418;12475;12590.81113783294;12627.5580354689;315989;404662;600071;608329;754639;151157;156583.21708391386;158451;160172;160225;1245.2901845947904;1280.8719128087303;1306.7974463957912;1313.7897936330141;1316.8485624153345;18831.811681883555;19022.858628482336;19145.13846876866;21123.78876211218;21326;52151.73924130355;63534.411639709375;64736.734214302276;65080.37299067372;66238.67522649423;71475.28524714687;84544.57727711322;86509.56745216233;87247;88302.55848720715;8028.31759300466;9310.487923164292;11761.941190293994;12193.329366884907;12291.56979505713;13261.204327740366;13341.733165336833;13522.79715804244;13664.521741738992;13818.378172982038;11331.546738130382;11417.429128543707;11766.352850593299;11801.586944456903;11833.763324733629;10548.947255263674;10567.577296908345;10589.311694739818;10765.3540787553;10784.838227426439;10818.185907271189;11457.312561246352;11606.361650109326;11701.765964680488;11910.58312959077;9492.335536512377;11752.764944700886;11777.470013849226;12025.917667409974;12095.637130886087;10124.493775311352;11371.294305854188;11384.886151138713;11651.378439851305;11797.705057373634 08:40:18 INFO - PROCESS | 1640 | |3;mootools.html;1370.6915943912627;1506.6911283186946;1584.128729198941;1624.626335942734;1625.4067265448116;9152.588133533991;9573.808523829519;10951.5619375225;11229.989300962912;11464.312141271508;11212.551497940072;11305.095592352613;11310.264832785862;11313.69892462367;11341.829872551918;2377.0491803278655;2413.046846495633;2414.541237164942;2415.541047201035;2426.599611064172;2027.2701827342146;2108.2621082621113;2393.150431596784;2469.900894102125;2485.6644353012384;2319.4781174235745;2340.1809366721595;2340.368100612842;2346.0263990443946;2352.5765362234874;2314.5486630107134;2350.3301559055585;2352.5412544553733;2352.8941197646145;2353.976460235393;2319.0375993962484;2322.11759531377;2388.0328466970905;2424.624183251599;2428.562858685436;11454;11554.01790847779;11568.53725850965;11579.88420115805;11584.826227606593;5710.2862142232225;5906.527477801766;5927.673977931212;5935.139404786325;5940.61386009908;3307.834608269577;3451.5340429042008;3547.113221694576;3577.159367548624;3581.552305961755;1105.1490352428666;1151.0733859243305;1152.6023521884936;1186.9347185904771;1205.8854408831146;15533.922330388388;15635.609109772233;15664.765028524582;15704.450344238012;15735.44925927587;3363.764536482423;4594.747288899076;4794.640401969867;4845.3216549683075;4942.579880710108;13029.283389413673;13047.478100875858;13051.543195988095;13061.608151755463;13065;5806.883862322815;5894.734736936848;5912.556558258148;5961.850953726192;5962.731677074456;4487.147441986012;5206.687598744012;5229.555487783581;5232.058229518647;5285.075111855487;3015.8341291228753;3906.648401643866;3957.980210098931;3981.880543583697;4023.4568333274633;1063.9734006649896;1070.844727514506;1096.0847692176962;1099.7140743406612;1111.8109921313396;723.3742812467283;807.1766797866144;828.867381219002;834.9896625083595;838.9832203355899;5917.378675239124;7004;7030.402415794715;7122;7298.489105762652;898.4743924803932;1046.1473898772476;1062.2988827373897;1065.5897479470304;1101.9504122314515;4325.653947684178;4326.956730432718;4390.460111853104;4495.213337665896;4545.659075569345;13724.176549407068;14340.426382944774;14346.784798228035;14358.353874075692;14371.496997605034;13929.442822286996;14040.1575905446;14257.572272831832;14282.143071415749;14315.78526322106;10297.588096476207;10454.947725261325;10461.633842815465;10468.371897686164;10479.056884880398;6652.234992975869;6839.119242208432;6864.176298844171;6865.519413640997;6917.861642767016;12120.393980301125;12391.814122787984;12395.132340736061;12396.628101156979;12417;10303.484825758833;10825.242233043611;12605.369731513205;12636.557720480107;12668.936655316664;8364.3726720495;8473.728940658704;8606.397552171287;8666.869996950178;8671.176238257356;10348.948255258676;10379.948100259451;10491.422971736627;10491.737706557093;10504.579816807242;8747.125287471457;8917.28661707049;9005.864912026456;9015.233705135137;9015.459072455673;7890.329322007694;8738.038815730137;8765.868511972452;8804.955975220082;8835.734927952171;7885.093214280431;8026.317762990211;8054.436189466681;8054.959725201336;8061.758147255592;5871.4715675589405;5991.281046274475;6013.248343957005;6138.693065346625;6151.446369826737;6063.363346848518;6143;6188.845278868064;6216.658083805434;6270.4670103039925 08:40:18 INFO - PROCESS | 1640 | |4;prototype.html;379.66210073034995;381.62981907549687;390.77530420008503;391.85773470333976;395.1563412115135;4499.640028797692;4530.478994915586;4717.4383340577815;4791.872696215051;4883.36516252887;4057.959420405795;4171.937420938689;4302.3331383635505;4482.8655140345845;4989.900201995967;3619.6561326673923;3681.723870709694;4290.892727681818;4303.26844436445;4330.350447432895;4000.659943904772;4219.367094935754;4231.87304380869;4250.298700714378;4283.67872409569;3684.6683798458134;3685.7788532688123;4249.492799248443;4252.746044596353;4316.956830431687;4109.630133287988;4163.4032324008385;4192.945456726213;4197.895052623679;4256.978325201961;3343.4483310253777;3861.227754449121;3862.0731024553907;3878.9806050969846;3920.372740361557;4755.809767609291;4942.382202224722;5112.948870511284;5138.3577052868395;5153.814622636777;4610.2854057621125;4900.264960255954;4918.680285781448;4996.026569505061;5006.924896126561;4581.129585378767;4712.505186955389;5320.866978325535;5321.122014867542;5428.240046393508;4167.874963751093;4352.911772056986;4367.890802729958;4442.489113751928;4484.1928452878465;4912.533309335643;5139.61452891031;5306.548943339821;5493.978311928423;5883.70581470929;3820.8853734388013;4130.979345103256;4507.729536227836;4537.455505339314;4545.250033744395;4103.97948010258;4480.641548676098;4674.532546745366;4702.614117835154;4717.3159891816185;4392.363107349416;4724.456419076774;4859.056377449048;4896.730679812573;4954.8761280968265;4636.420447444069;4665.883352916204;5440.863978400572;5460;5537;1396.7695330270594;1428.4786053090754;1462.7805829125716;1492.6268432891777;1534.5856618713108;1067.9305845120198;1149.8558933860695;1156.6298784388914;1156.7744289863397;1159.2464897816487;4059.654929330981;4403.581659742319;4580.755429839422;4758.95241047592;4778.808847646124;5081.034603425337;5334.639177318265;5372.623916325898;5598.216249725042;5706.514946229534;4570.006645647107;4582.541745825458;4763.21379482694;4976.8755781105765;4977.90044199114;2615.1762194908542;2855.000749737617;2895.5801408795605;2948.5134952732988;2949.0268211490156;4799.880002999953;5280.788768449219;5281.577473802086;5582.853946947913;5662.348829884616;4944.629152813553;4958.752062396866;5373.348908257702;5652.576056795757;5773.336066352339;5239.74690882924;5271.578273738091;5598.356189038232;5599.664020158722;5870.735816888336;5178.197379406198;5597.524210442158;5650.802221922213;5665.433456654301;5734.197212390185;4862.5380588844;5214.113600687968;5261.237120617566;5515.558755299486;5592.664440133605;5094.643374963717;5296.549793267615;5470.058129364178;5888.823335299948;5895.927194989168;4069.2878746219885;4339.956600433955;4488.371627972021;4504.77476126199;4613.515580863961;5291.888703372181;5362.195670649434;5650.604457688087;5697.607005057968;5762.193292938908;5020.924686129638;5253.399567214254;5528.474794894479;5558.388577256579;5560.4161563035295;4595.724256544618;5132.178851383761;5346.893716500655;5365.436629153883;5384.948204436962;4607.815687372467;4607.976960115178;5031.698098114125;5351.411344752152;5360.391882435176;4724.787384567634;5196.298499702643;5210;5497.780088796403;5510.366307874646;4391.824327026883;4890.217565189695;4977.452480227244;4990.451050384528;5341.813036543703 08:40:18 INFO - PROCESS | 1640 | |5;yui.html;735.3675838778651;742.8180095876511;751.8458715963225;783.3967844759535;909.7998440343122;15402.537923862288;15921.44274950374;15959.12224827634;16059.839401605981;16071.919640401811;14704.264786760672;14829.406823727066;14843.480478183239;16534.42129525467;17225.310987560515;812.6140083460353;1002.4035698759227;1002.5488530161421;1003.3628645809927;1003.7239759066242;997.875265591801;999.0808456220259;999.380384161817;1000.8598796168542;1002.3184234720387;1046.0062940206833;1049.8950104989517;1050.9894901051007;1052.6999805055523;1052.7105046112304;1045.3832238979;1045.3988956350054;1045.6078970386104;1048.0567489259688;1048.6749107776614;772.7797577690387;777.2460713108276;777.4713195027379;777.766669998998;780.2275747010419;16409.343626254937;16531.00813951167;16538.007719536745;16564.6687066258;16564.75152872708;12508.74982500345;14104.647383815383;14197.858021419861;14251.287435628177;14260.286985650677;11343.773124537463;11661.766764664744;11729.648110556696;11748.295102293805;12195.14633975622;3567.3400420921953;3675.9081022974374;3847.365184744514;3888.727789054767;3891.5135608048995;109932.4503377486;115676.1080972974;118016;119110.80889191084;119795;10535.736606584896;10684.732881677864;10736.516856741466;11132.05377542918;11142.49858756358;22574.887125564266;23116.53766924652;23262.3021309361;23281;23313.883430582737;7702.576358300235;7725.690972361155;7807.336376407955;7823;7913;3892.9416058759143;4394.978025109854;4407.559244075631;4429.49060857999;4444.933326000112;3006.6842981487066;3422.1273575238156;3501.5272938153025;3524.312759011968;3541.3979623464074;603.3483837455537;612.087988896541;620.950323974081;637.8628594852129;639.6865535887382;672.5561129654404;675.5338816216795;705.7706245470242;707.5860621536459;731;10167;10389.324693895023;10681.893181068246;10754.89245107539;10792.082672972729;657.9276279609239;689.5138927056513;695.9130108736408;712.7790384980673;723.0889079759436;4927.728974906343;4966.528179822911;4975.527324904128;4988.45127036033;4991.425986011655;79746;100722.49638751759;101330.49334753279;103882.48058759658;108482;87694.56152719195;89497;90829.09170908206;92855.53572232231;93708.53145734363;63880;78144;81645.18354816495;82041;82539.58730206311;10633.787324253317;10963.780724385628;11279.605213817476;11447.656570302572;11448.771024579628;46860;48154.51845481501;48357.7910552239;49260.50739492559;49963.50036499588;70452.59094818178;70975.64512177405;71138;72291.91562126677;72505;12623.747525049632;12899.548515801902;13070.281134537692;13436.261005644392;13514.526991555249;14253;14509.92745036268;15175;15383.538493845203;15772.842271577138;13395.866041339463;13436.328183590977;13990.440382384997;14126.929365353108;14153.787693184817;13024.41390137465;13241.602751917459;13886.444542218602;14055.718885622025;14526.636834079232;13283.535076272285;13437;13664.795028074388;13872.4451021958;14378.35297411598;9269.397489163182;9638.084381983701;9741.610335586498;9779.755506112404;10001.849972250568;9241.86137207929;9842.65550705722;10309.917458666732;10569.577216911237;11152 08:40:18 INFO - PROCESS | 1640 | __end_tp_report 08:40:18 INFO - PROCESS | 1640 | __start_cc_report 08:40:18 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,1514 08:40:18 INFO - PROCESS | 1640 | __end_cc_report 08:40:18 INFO - PROCESS | 1640 | __startTimestamp1495640418731__endTimestamp 08:40:18 INFO - PROCESS | 1640 | 08:40:18 INFO - PROCESS | 1640 | ------- Summary: start ------- 08:40:18 INFO - PROCESS | 1640 | Number of tests: 6 08:40:18 INFO - PROCESS | 1640 | 08:40:18 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5865.94 Median:6120.51 stddev:695.61 (11.4%) stddev-sans-first:670.46 08:40:18 INFO - PROCESS | 1640 | Values: 3407.0 3424.6 3429.4 3431.8 3479.6 4685.3 5895.1 5905.5 5910.0 5921.1 4880.8 5857.7 5886.8 5963.5 5969.6 5301.6 5332.5 5339.3 5393.8 5395.1 4520.3 5236.7 5242.6 5277.1 5514.3 5367.3 5377.2 5382.5 5393.3 5395.6 5420.9 5434.5 5451.9 5455.5 5457.7 4926.7 5279.3 5336.1 5360.4 5447.7 5864.3 5871.1 5912.0 5953.9 5975.0 5380.9 6318.9 6328.7 6340.9 6347.4 6398.5 6437.0 6451.6 6462.2 6462.4 5872.5 5897.6 6034.8 6083.2 6114.2 5932.0 6269.2 6474.9 6485.2 6530.6 6291.8 6344.9 6359.6 6359.9 6361.2 6245.8 6298.7 6333.5 6345.3 6417.2 6198.1 6365.7 6372.8 6374.6 6392.1 6306.1 6313.2 6320.3 6328.7 6392.3 4867.5 4889.0 4911.6 4919.5 4919.8 4343.9 4414.4 4425.9 4436.6 4445.0 4994.2 5743.8 5756.4 5763.2 5787.8 6323.8 6332.2 6350.3 6400.2 6430.4 5923.1 5925.7 5939.0 5942.7 5978.5 5516.4 5541.1 5572.0 5577.4 5581.4 6302.1 6341.4 6470.8 6513.1 6514.6 6416.2 6418.7 6429.9 6432.3 6447.5 6528.4 6554.6 6610.2 6620.7 6623.6 6210.7 6264.4 6296.5 6328.5 6376.4 5250.7 6169.0 6386.8 6404.1 6407.8 6335.8 6336.7 6373.3 6392.2 6394.1 6120.5 6172.7 6266.1 6288.4 6395.4 6213.2 6279.0 6287.1 6292.7 6297.8 6360.0 6368.1 6368.4 6379.9 6385.5 5932.4 6014.3 6064.0 6115.1 6167.4 08:40:18 INFO - PROCESS | 1640 | 08:40:18 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:55712.40 Median:29837.05 stddev:114496.47 (383.7%) stddev-sans-first:114743.33 08:40:18 INFO - PROCESS | 1640 | Values: 762.2 793.4 808.0 879.5 889.8 28985.9 29359.1 29763.1 41416.6 42333.6 42285.0 42483.8 42525.4 42545.8 42682.4 9467.5 9631.6 9631.8 9643.6 9646.9 7493.9 7651.3 9430.1 9531.6 11575.3 11633.9 11663.7 11679.7 11688.0 11809.7 11613.0 11639.4 11651.7 11658.1 11659.3 11297.2 11365.9 11369.2 11374.5 11389.9 39839.2 39989.0 39998.0 40030.6 40099.6 3848.3 3873.6 3878.6 3924.4 3947.5 27319.5 27763.2 29606.9 29828.9 29845.3 3034.9 3105.8 3134.7 3253.1 3269.6 314621.0 315310.0 316504.0 316757.0 316895.4 52343.0 53096.2 53169.0 53201.0 53225.0 56796.7 56898.7 57004.7 57060.6 57074.0 14731.8 15135.6 15144.9 15159.8 15161.3 12955.3 12967.3 12976.4 12976.7 12999.7 10399.8 10900.9 11089.8 11250.7 12901.1 4758.0 5008.6 5374.9 5566.1 5607.5 1093.5 1118.5 1135.1 1146.9 1181.2 27103.5 27176.6 27473.3 27775.8 27937.4 1214.5 1244.7 1262.7 1278.1 1286.5 13889.6 14235.9 14336.2 14433.7 14446.4 458851.0 459913.0 460086.0 460988.0 461188.0 49468.8 50591.5 53201.0 53250.7 58433.4 1245.7 1382.3 1388.0 1417.1 1418.2 23991.3 26991.0 27642.9 28158.2 28200.0 42890.5 43086.0 43150.5 43186.4 43208.7 492368.0 493952.0 494322.0 495147.0 495261.0 24640.6 29532.7 30045.4 30099.5 30194.4 29628.6 30770.5 31653.1 39883.0 40064.3 26588.6 27927.3 28885.6 28955.0 29069.9 27976.0 28574.0 28979.0 29056.9 29136.0 32241.0 32919.5 33515.7 33539.8 33555.0 28250.4 28253.3 28386.3 28620.7 29474.7 22135.9 22718.3 23134.0 23168.8 23186.0 08:40:18 INFO - PROCESS | 1640 | 08:40:18 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:36993.21 Median:21939.23 stddev:94933.66 (432.7%) stddev-sans-first:95160.22 08:40:18 INFO - PROCESS | 1640 | Values: 405.4 411.3 422.4 430.8 447.5 19827.8 21000.6 22521.1 22581.5 22583.0 21964.7 22970.4 23058.0 23244.0 23700.9 6663.3 6822.5 7021.1 7072.7 7145.4 6853.8 6906.9 6949.5 6972.1 7017.5 6584.9 6838.2 6933.1 6935.4 6937.7 6518.3 6867.0 6940.6 6950.6 6955.5 6687.7 6755.2 6774.5 6776.3 6785.1 25247.9 27672.7 27733.6 28012.0 28158.6 2987.4 2993.8 3003.1 3005.7 3010.1 16183.5 17078.3 17316.4 17316.8 17718.8 2000.7 2051.4 2054.8 2056.4 2071.6 127857.7 129097.4 129776.0 132874.3 134133.0 21495.8 21913.8 21991.8 22006.3 22248.3 36268.9 36507.0 37061.8 37106.8 37186.8 10745.5 10755.6 10865.1 10939.7 11129.1 8919.8 8924.8 8946.5 9115.7 9120.3 8714.8 10205.6 10508.6 10660.7 10670.4 3624.5 4118.5 4255.2 4265.8 4267.0 1054.3 1109.7 1117.4 1150.6 1156.8 17795.7 23586.2 23671.6 23674.9 23676.1 1106.7 1118.1 1128.5 1173.4 1179.7 12241.3 12375.4 12475.0 12590.8 12627.6 315989.0 404662.0 600071.0 608329.0 754639.0 151157.0 156583.2 158451.0 160172.0 160225.0 1245.3 1280.9 1306.8 1313.8 1316.8 18831.8 19022.9 19145.1 21123.8 21326.0 52151.7 63534.4 64736.7 65080.4 66238.7 71475.3 84544.6 86509.6 87247.0 88302.6 8028.3 9310.5 11761.9 12193.3 12291.6 13261.2 13341.7 13522.8 13664.5 13818.4 11331.5 11417.4 11766.4 11801.6 11833.8 10548.9 10567.6 10589.3 10765.4 10784.8 10818.2 11457.3 11606.4 11701.8 11910.6 9492.3 11752.8 11777.5 12025.9 12095.6 10124.5 11371.3 11384.9 11651.4 11797.7 08:40:18 INFO - PROCESS | 1640 | 08:40:18 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:6832.04 Median:2415.04 stddev:4350.59 (180.1%) stddev-sans-first:4343.44 08:40:18 INFO - PROCESS | 1640 | Values: 1370.7 1506.7 1584.1 1624.6 1625.4 9152.6 9573.8 10951.6 11230.0 11464.3 11212.6 11305.1 11310.3 11313.7 11341.8 2377.0 2413.0 2414.5 2415.5 2426.6 2027.3 2108.3 2393.2 2469.9 2485.7 2319.5 2340.2 2340.4 2346.0 2352.6 2314.5 2350.3 2352.5 2352.9 2354.0 2319.0 2322.1 2388.0 2424.6 2428.6 11454.0 11554.0 11568.5 11579.9 11584.8 5710.3 5906.5 5927.7 5935.1 5940.6 3307.8 3451.5 3547.1 3577.2 3581.6 1105.1 1151.1 1152.6 1186.9 1205.9 15533.9 15635.6 15664.8 15704.5 15735.4 3363.8 4594.7 4794.6 4845.3 4942.6 13029.3 13047.5 13051.5 13061.6 13065.0 5806.9 5894.7 5912.6 5961.9 5962.7 4487.1 5206.7 5229.6 5232.1 5285.1 3015.8 3906.6 3958.0 3981.9 4023.5 1064.0 1070.8 1096.1 1099.7 1111.8 723.4 807.2 828.9 835.0 839.0 5917.4 7004.0 7030.4 7122.0 7298.5 898.5 1046.1 1062.3 1065.6 1102.0 4325.7 4327.0 4390.5 4495.2 4545.7 13724.2 14340.4 14346.8 14358.4 14371.5 13929.4 14040.2 14257.6 14282.1 14315.8 10297.6 10454.9 10461.6 10468.4 10479.1 6652.2 6839.1 6864.2 6865.5 6917.9 12120.4 12391.8 12395.1 12396.6 12417.0 10303.5 10825.2 12605.4 12636.6 12668.9 8364.4 8473.7 8606.4 8666.9 8671.2 10348.9 10379.9 10491.4 10491.7 10504.6 8747.1 8917.3 9005.9 9015.2 9015.5 7890.3 8738.0 8765.9 8805.0 8835.7 7885.1 8026.3 8054.4 8055.0 8061.8 5871.5 5991.3 6013.2 6138.7 6151.4 6063.4 6143.0 6188.8 6216.7 6270.5 08:40:18 INFO - PROCESS | 1640 | 08:40:18 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4486.86 Median:4761.08 stddev:1238.61 (26.0%) stddev-sans-first:1203.11 08:40:18 INFO - PROCESS | 1640 | Values: 379.7 381.6 390.8 391.9 395.2 4499.6 4530.5 4717.4 4791.9 4883.4 4058.0 4171.9 4302.3 4482.9 4989.9 3619.7 3681.7 4290.9 4303.3 4330.4 4000.7 4219.4 4231.9 4250.3 4283.7 3684.7 3685.8 4249.5 4252.7 4317.0 4109.6 4163.4 4192.9 4197.9 4257.0 3343.4 3861.2 3862.1 3879.0 3920.4 4755.8 4942.4 5112.9 5138.4 5153.8 4610.3 4900.3 4918.7 4996.0 5006.9 4581.1 4712.5 5320.9 5321.1 5428.2 4167.9 4352.9 4367.9 4442.5 4484.2 4912.5 5139.6 5306.5 5494.0 5883.7 3820.9 4131.0 4507.7 4537.5 4545.3 4104.0 4480.6 4674.5 4702.6 4717.3 4392.4 4724.5 4859.1 4896.7 4954.9 4636.4 4665.9 5440.9 5460.0 5537.0 1396.8 1428.5 1462.8 1492.6 1534.6 1067.9 1149.9 1156.6 1156.8 1159.2 4059.7 4403.6 4580.8 4759.0 4778.8 5081.0 5334.6 5372.6 5598.2 5706.5 4570.0 4582.5 4763.2 4976.9 4977.9 2615.2 2855.0 2895.6 2948.5 2949.0 4799.9 5280.8 5281.6 5582.9 5662.3 4944.6 4958.8 5373.3 5652.6 5773.3 5239.7 5271.6 5598.4 5599.7 5870.7 5178.2 5597.5 5650.8 5665.4 5734.2 4862.5 5214.1 5261.2 5515.6 5592.7 5094.6 5296.5 5470.1 5888.8 5895.9 4069.3 4340.0 4488.4 4504.8 4613.5 5291.9 5362.2 5650.6 5697.6 5762.2 5020.9 5253.4 5528.5 5558.4 5560.4 4595.7 5132.2 5346.9 5365.4 5384.9 4607.8 4608.0 5031.7 5351.4 5360.4 4724.8 5196.3 5210.0 5497.8 5510.4 4391.8 4890.2 4977.5 4990.5 5341.8 08:40:18 INFO - PROCESS | 1640 | 08:40:18 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:20956.16 Median:16551.34 stddev:30245.07 (182.7%) stddev-sans-first:30291.81 08:40:18 INFO - PROCESS | 1640 | Values: 735.4 742.8 751.8 783.4 909.8 15402.5 15921.4 15959.1 16059.8 16071.9 14704.3 14829.4 14843.5 16534.4 17225.3 812.6 1002.4 1002.5 1003.4 1003.7 997.9 999.1 999.4 1000.9 1002.3 1046.0 1049.9 1051.0 1052.7 1052.7 1045.4 1045.4 1045.6 1048.1 1048.7 772.8 777.2 777.5 777.8 780.2 16409.3 16531.0 16538.0 16564.7 16564.8 12508.7 14104.6 14197.9 14251.3 14260.3 11343.8 11661.8 11729.6 11748.3 12195.1 3567.3 3675.9 3847.4 3888.7 3891.5 109932.5 115676.1 118016.0 119110.8 119795.0 10535.7 10684.7 10736.5 11132.1 11142.5 22574.9 23116.5 23262.3 23281.0 23313.9 7702.6 7725.7 7807.3 7823.0 7913.0 3892.9 4395.0 4407.6 4429.5 4444.9 3006.7 3422.1 3501.5 3524.3 3541.4 603.3 612.1 621.0 637.9 639.7 672.6 675.5 705.8 707.6 731.0 10167.0 10389.3 10681.9 10754.9 10792.1 657.9 689.5 695.9 712.8 723.1 4927.7 4966.5 4975.5 4988.5 4991.4 79746.0 100722.5 101330.5 103882.5 108482.0 87694.6 89497.0 90829.1 92855.5 93708.5 63880.0 78144.0 81645.2 82041.0 82539.6 10633.8 10963.8 11279.6 11447.7 11448.8 46860.0 48154.5 48357.8 49260.5 49963.5 70452.6 70975.6 71138.0 72291.9 72505.0 12623.7 12899.5 13070.3 13436.3 13514.5 14253.0 14509.9 15175.0 15383.5 15772.8 13395.9 13436.3 13990.4 14126.9 14153.8 13024.4 13241.6 13886.4 14055.7 14526.6 13283.5 13437.0 13664.8 13872.4 14378.4 9269.4 9638.1 9741.6 9779.8 10001.8 9241.9 9842.7 10309.9 10569.6 11152.0 08:40:18 INFO - PROCESS | 1640 | -------- Summary: end -------- 08:40:18 INFO - PROCESS | 1640 | 08:40:19 INFO - TEST-INFO | 1640: exit 0 08:40:19 INFO - TEST-OK | dromaeo_css | took 1118016ms 08:40:19 INFO - TEST-START | kraken 08:40:19 INFO - operating with platform_type : mac_ 08:40:19 INFO - Initialising browser for kraken test... 08:40:19 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpW9VAit/profile http://localhost:49224/getInfo.html) 08:40:21 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 08:40:21 INFO - PROCESS | 1649 | colorDepth:24 08:40:21 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 08:40:21 INFO - PROCESS | 1649 | __metrics 08:40:21 INFO - PROCESS | 1649 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:40:21 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:40:22 INFO - TEST-INFO | 1649: exit 0 08:40:22 INFO - Browser initialized. 08:40:22 INFO - Running cycle 1/1 for kraken test... 08:40:22 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/T/tmpW9VAit/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:40:34 INFO - PROCESS | 1651 | RSS: Main: 193933312 08:40:34 INFO - PROCESS | 1651 | 08:40:36 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 08:40:37 INFO - PROCESS | 1651 | RSS: Main: 245587968 08:40:37 INFO - PROCESS | 1651 | 08:40:40 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 08:40:41 INFO - PROCESS | 1651 | RSS: Main: 260722688 08:40:41 INFO - PROCESS | 1651 | 08:40:44 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 08:40:44 INFO - PROCESS | 1651 | RSS: Main: 356794368 08:40:44 INFO - PROCESS | 1651 | 08:40:46 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 08:40:47 INFO - PROCESS | 1651 | RSS: Main: 258002944 08:40:47 INFO - PROCESS | 1651 | 08:40:49 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:40:50 INFO - PROCESS | 1651 | RSS: Main: 261185536 08:40:50 INFO - PROCESS | 1651 | 08:40:53 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 08:40:53 INFO - PROCESS | 1651 | RSS: Main: 301432832 08:40:53 INFO - PROCESS | 1651 | 08:40:56 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 08:40:57 INFO - PROCESS | 1651 | RSS: Main: 301023232 08:40:57 INFO - PROCESS | 1651 | 08:41:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 08:41:00 INFO - PROCESS | 1651 | RSS: Main: 298430464 08:41:00 INFO - PROCESS | 1651 | 08:41:02 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:41:03 INFO - PROCESS | 1651 | RSS: Main: 277688320 08:41:03 INFO - PROCESS | 1651 | 08:41:05 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:41:05 INFO - PROCESS | 1651 | RSS: Main: 282845184 08:41:05 INFO - PROCESS | 1651 | 08:41:07 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:41:08 INFO - PROCESS | 1651 | RSS: Main: 286277632 08:41:08 INFO - PROCESS | 1651 | 08:41:11 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:41:12 INFO - PROCESS | 1651 | RSS: Main: 298999808 08:41:12 INFO - PROCESS | 1651 | 08:41:15 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:41:15 INFO - PROCESS | 1651 | RSS: Main: 292331520 08:41:15 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 08:41:17 INFO - PROCESS | 1651 | RSS: Main: 337195008 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | __start_tp_report 08:41:17 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 08:41:17 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 08:41:17 INFO - PROCESS | 1651 | |i|pagename|runs| 08:41:17 INFO - PROCESS | 1651 | |0;ai-astar;105;115;117;115;110;115;115;117;116;116 08:41:17 INFO - PROCESS | 1651 | |1;audio-beat-detection;137;108;109;141;113;110;107;108;127;108 08:41:17 INFO - PROCESS | 1651 | |2;audio-dft;185;187;185;187;187;196;265;205;187;186 08:41:17 INFO - PROCESS | 1651 | |3;audio-fft;79;59;60;93;64;61;59;60;75;60 08:41:17 INFO - PROCESS | 1651 | |4;audio-oscillator;89;69;87;70;70;69;85;71;69;83 08:41:17 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;88;86;87;90;134;87;88;87;88;87 08:41:17 INFO - PROCESS | 1651 | |6;imaging-darkroom;109;150;95;95;94;94;96;96;95;94 08:41:17 INFO - PROCESS | 1651 | |7;imaging-desaturate;84;83;84;85;86;83;84;84;88;83 08:41:17 INFO - PROCESS | 1651 | |8;json-parse-financial;62;60;59;61;63;63;64;63;62;62 08:41:17 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;54;62;59;67;64;62;57;58;59;57 08:41:17 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;91;98;85;86;81;82;85;85;86;81 08:41:17 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;138;140;137;135;136;136;139;142;187;177 08:41:17 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;165;167;165;165;143;187;219;220;164;165 08:41:17 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;64;64;65;64;65;64;64;63;63;65 08:41:17 INFO - PROCESS | 1651 | __end_tp_report 08:41:17 INFO - PROCESS | 1651 | __start_cc_report 08:41:17 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,2209 08:41:17 INFO - PROCESS | 1651 | __end_cc_report 08:41:17 INFO - PROCESS | 1651 | __startTimestamp1495640477794__endTimestamp 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | ------- Summary: start ------- 08:41:17 INFO - PROCESS | 1651 | Number of tests: 14 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:114.10 Median:115.50 stddev:3.75 (3.3%) stddev-sans-first:2.09 08:41:17 INFO - PROCESS | 1651 | Values: 105.0 115.0 117.0 115.0 110.0 115.0 115.0 117.0 116.0 116.0 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:116.80 Median:111.50 stddev:13.11 (11.8%) stddev-sans-first:11.70 08:41:17 INFO - PROCESS | 1651 | Values: 137.0 108.0 109.0 141.0 113.0 110.0 107.0 108.0 127.0 108.0 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:197.00 Median:187.00 stddev:24.72 (13.2%) stddev-sans-first:25.83 08:41:17 INFO - PROCESS | 1651 | Values: 185.0 187.0 185.0 187.0 187.0 196.0 265.0 205.0 187.0 186.0 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:67.00 Median:62.50 stddev:11.57 (18.5%) stddev-sans-first:11.42 08:41:17 INFO - PROCESS | 1651 | Values: 79.0 59.0 60.0 93.0 64.0 61.0 59.0 60.0 75.0 60.0 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:76.20 Median:77.00 stddev:8.59 (11.2%) stddev-sans-first:7.76 08:41:17 INFO - PROCESS | 1651 | Values: 89.0 69.0 87.0 70.0 70.0 69.0 85.0 71.0 69.0 83.0 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:92.20 Median:87.50 stddev:14.73 (16.8%) stddev-sans-first:15.54 08:41:17 INFO - PROCESS | 1651 | Values: 88.0 86.0 87.0 90.0 134.0 87.0 88.0 87.0 88.0 87.0 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:101.80 Median:95.00 stddev:17.52 (18.4%) stddev-sans-first:18.39 08:41:17 INFO - PROCESS | 1651 | Values: 109.0 150.0 95.0 95.0 94.0 94.0 96.0 96.0 95.0 94.0 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:84.00 stddev:1.58 (1.9%) stddev-sans-first:1.67 08:41:17 INFO - PROCESS | 1651 | Values: 84.0 83.0 84.0 85.0 86.0 83.0 84.0 84.0 88.0 83.0 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:62.50 stddev:1.52 (2.4%) stddev-sans-first:1.62 08:41:17 INFO - PROCESS | 1651 | Values: 62.0 60.0 59.0 61.0 63.0 63.0 64.0 63.0 62.0 62.0 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:59.90 Median:60.50 stddev:3.84 (6.4%) stddev-sans-first:3.43 08:41:17 INFO - PROCESS | 1651 | Values: 54.0 62.0 59.0 67.0 64.0 62.0 57.0 58.0 59.0 57.0 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:85.50 stddev:5.14 (6.0%) stddev-sans-first:5.13 08:41:17 INFO - PROCESS | 1651 | Values: 91.0 98.0 85.0 86.0 81.0 82.0 85.0 85.0 86.0 81.0 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:139.50 stddev:18.87 (13.5%) stddev-sans-first:19.75 08:41:17 INFO - PROCESS | 1651 | Values: 138.0 140.0 137.0 135.0 136.0 136.0 139.0 142.0 187.0 177.0 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.00 Median:166.00 stddev:25.17 (15.2%) stddev-sans-first:26.39 08:41:17 INFO - PROCESS | 1651 | Values: 165.0 167.0 165.0 165.0 143.0 187.0 219.0 220.0 164.0 165.0 08:41:17 INFO - PROCESS | 1651 | 08:41:17 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.10 Median:64.00 stddev:0.74 (1.2%) stddev-sans-first:0.78 08:41:17 INFO - PROCESS | 1651 | Values: 64.0 64.0 65.0 64.0 65.0 64.0 64.0 63.0 63.0 65.0 08:41:17 INFO - PROCESS | 1651 | -------- Summary: end -------- 08:41:17 INFO - PROCESS | 1651 | 08:41:18 INFO - TEST-INFO | 1651: exit 0 08:41:18 INFO - TEST-OK | kraken | took 59310ms 08:41:18 INFO - SUITE-END | took 1177s 08:41:18 INFO - Completed test suite (00:19:37) 08:41:18 INFO - javascript benchmark 08:41:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7723.845833476528, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3407.046027112408, 3424.6232914379416, 3429.39985502537, 3431.7597768156224, 3479.565054368204, 4685.273782563704, 5895.056790913454, 5905.468507834299, 5910.0, 5921.082232253997, 4880.78036488358, 5857.7364018619155, 5886.793962211324, 5963.522918166547, 5969.611975221607, 5301.6023798215, 5332.493413125766, 5339.332583427072, 5393.838184854441, 5395.136778115502, 4520.299353600197, 5236.738163091849, 5242.6330156889035, 5277.102892508264, 5514.255575497296, 5367.329083864517, 5377.247185394048, 5382.515573598375, 5393.2719082923895, 5395.622306438551, 5420.91868621971, 5434.538064264542, 5451.918221226685, 5455.536279416255, 5457.699826509541, 4926.729029903354, 5279.260903473517, 5336.146216605363, 5360.383555891083, 5447.6731396115965, 5864.296284445851, 5871.119332100219, 5912.0, 5953.851153721148, 5975.014122669754, 5380.865478363033, 6318.936810631881, 6328.65192414417, 6340.873182536369, 6347.365263473662, 6398.488120950313, 6436.967815160941, 6451.612903225775, 6462.224533056016, 6462.4183823456115, 5872.471477567039, 5897.557683173779, 6034.788782392595, 6083.209182806207, 6114.205153330127, 5931.961906666316, 6269.247690277196, 6474.870502589922, 6485.189351331083, 6530.608163510205, 6291.842703932438, 6344.904826427607, 6359.554831161774, 6359.872802543923, 6361.2048493938255, 6245.750169993149, 6298.716557754912, 6333.461655759312, 6345.302016778151, 6417.1657684500715, 6198.132261483396, 6365.681715914186, 6372.776952806629, 6374.58565193261, 6392.137061496732, 6306.054091886253, 6313.2424109107205, 6320.27316858558, 6328.746850125944, 6392.296847346788, 4867.513248675175, 4889.0, 4911.557959783565, 4919.532644398776, 4919.803207871645, 4343.869683909425, 4414.426124603781, 4425.867223983286, 4436.556344365537, 4445.044315472189, 4994.150994330972, 5743.827685169368, 5756.366799652035, 5763.193152958589, 5787.768489260467, 6323.810285691437, 6332.176817013851, 6350.33321501245, 6400.199975003125, 6430.421262086341, 5923.052311630118, 5925.73334199962, 5939.049752039653, 5942.70286485674, 5978.521718262528, 5516.365617953907, 5541.141123125922, 5571.972140139354, 5577.414371491016, 5581.386047534768, 6302.1177035215105, 6341.365863413622, 6470.838229044311, 6513.120728701754, 6514.609123452608, 6416.230052393743, 6418.679066046772, 6429.935700643121, 6432.292447830829, 6447.516436267392, 6528.37980391862, 6554.606723596599, 6610.206775187008, 6620.735170593122, 6623.602583844985, 6210.689465526796, 6264.404881536247, 6296.496280297473, 6328.493720502441, 6376.426121649074, 5250.684958902478, 6169.0, 6386.776462823965, 6404.103425520524, 6407.839804004937, 6335.841603959938, 6336.651484168231, 6373.298937117006, 6392.169018027626, 6394.136791533084, 6120.508083830795, 6172.660503672341, 6266.060090986389, 6288.402601752826, 6395.392437718409, 6213.2233470816145, 6279.0, 6287.119803227643, 6292.685365731603, 6297.811065668037, 6360.014197799349, 6368.0766288886925, 6368.363163683594, 6379.872402552016, 6385.457236134795, 5932.377100404568, 6014.308354539109, 6064.02975523914, 6115.088229002258, 6167.352427994996], "value": 5820.64735032806, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.1768490030768, 793.4247670438931, 807.9636416361265, 879.4898958604007, 889.7508697564684, 28985.855070724643, 29359.119226423216, 29763.107106786832, 41416.585834141646, 42333.57666423335, 42285.0, 42483.787581062126, 42525.362119568155, 42545.78727106368, 42682.359764603476, 9467.479288639122, 9631.61473541061, 9631.807363852718, 9643.566039528238, 9646.938836727953, 7493.925060749404, 7651.273129052731, 9430.10414010668, 9531.571079301448, 11575.305481671085, 11633.883661163407, 11663.650090497298, 11679.7080072998, 11688.006519445815, 11809.704757381049, 11613.0, 11639.418029098595, 11651.708707282301, 11658.067354611696, 11659.300441973424, 11297.209195356329, 11365.943170284178, 11369.237768145951, 11374.545018199262, 11389.943050284779, 39839.20321593552, 39989.000274993356, 39998.0, 40030.59969400298, 40099.59900400988, 3848.2852963349683, 3873.573906870242, 3878.631530004645, 3924.4113382992496, 3947.5460322063045, 27319.45361092767, 27763.167104986882, 29606.85196574025, 29828.850855745583, 29845.25386865324, 3034.9393012139853, 3105.7659349339074, 3134.670859559759, 3253.1216571525792, 3269.623993240784, 314621.0, 315310.0, 316504.0, 316757.0, 316895.4155229232, 52343.0, 53096.203556946675, 53169.0, 53201.0, 53225.0, 56796.716016419654, 56898.7155064222, 57004.71497642485, 57060.57348566319, 57074.0, 14731.779023314659, 15135.621609459851, 15144.924275378553, 15159.848401515843, 15161.317740701696, 12955.287459189647, 12967.286799226133, 12976.35118244084, 12976.740465190644, 12999.740005200032, 10399.792004159874, 10900.890991090146, 11089.778204436028, 11250.662480125446, 12901.096923215473, 4757.95242047582, 5008.574271186917, 5374.871277031789, 5566.053770858964, 5607.52336051432, 1093.4969913839564, 1118.5134466507095, 1135.1202817816093, 1146.931184128955, 1181.1908842442954, 27103.45793084167, 27176.59235111475, 27473.31316717098, 27775.750091245944, 27937.441251174863, 1214.5384753793678, 1244.661988362514, 1262.678017105632, 1278.1244847279643, 1286.5497076023316, 13889.583312500641, 14235.868109016, 14336.21150836693, 14433.711325773636, 14446.422143114369, 458851.0, 459913.0, 460086.0, 460988.0, 461188.0, 49468.75265623721, 50591.494085059414, 53201.0, 53250.73374633102, 58433.4156658428, 1245.7197130645714, 1382.3433868912186, 1387.9520961673873, 1417.0505761140266, 1418.2341535570883, 23991.28026159218, 26991.0, 27642.861785691748, 28158.15525534237, 28200.0, 42890.49883254071, 43086.0, 43150.4897328592, 43186.35220471758, 43208.70373888788, 492368.0, 493952.0, 494322.0, 495147.0, 495261.0, 24640.630390544517, 29532.704672952994, 30045.399092018473, 30099.548506772855, 30194.396112078073, 29628.555571666013, 30770.53844192294, 31653.050408487783, 39883.0, 40064.34679536035, 26588.601170982838, 27927.301817453914, 28885.566716498848, 28955.0, 29069.85465072661, 27976.0, 28574.0, 28979.0, 29056.854715726287, 29135.980240691475, 32241.0, 32919.50620740739, 33515.66484335125, 33539.83230083834, 33555.0, 28250.43499130047, 28253.293667658472, 28386.290342741595, 28620.713792862636, 29474.705252948053, 22135.889320553295, 22718.318450446513, 23134.0, 23168.76831231666, 23186.0], "value": 18584.76749977378, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.3514376996804, 411.3131071111244, 422.4297198781644, 430.84274239902436, 447.52115236696727, 19827.801721982793, 21000.579988400223, 22521.09915603374, 22581.54836903263, 22582.98376573054, 21964.670529942065, 22970.425739356524, 23058.0, 23244.0, 23700.88149559254, 6663.300353462865, 6822.52242343035, 7021.052157958661, 7072.681729322193, 7145.35691787739, 6853.760118395857, 6906.930930690704, 6949.548279361826, 6972.1284839395075, 7017.508774385769, 6584.934150658504, 6838.179418469766, 6933.1333583302085, 6935.375816176543, 6937.7224911003495, 6518.250401203874, 6866.965665171667, 6940.583564986117, 6950.582965022064, 6955.513114082017, 6687.665616719145, 6755.1556055493065, 6774.45804335652, 6776.2546119927265, 6785.050092986986, 25247.873760631264, 27672.723272767216, 27733.58399624007, 28012.0, 28158.577621335695, 2987.3875855449796, 2993.790434669574, 3003.1290925631542, 3005.654349749785, 3010.142109498804, 16183.514494565183, 17078.31686732529, 17316.393926212648, 17316.826831731647, 17718.822811771846, 2000.6998950157447, 2051.3640771360924, 2054.845886558514, 2056.4139220322136, 2071.637463443891, 127857.72142278551, 129097.35451322683, 129776.0, 132874.3356283222, 134133.0, 21495.80831737796, 21913.780862191175, 21991.780082199293, 22006.339809805733, 22248.33020037606, 36268.91193264206, 36507.0, 37061.814690926374, 37106.8144659275, 37186.814065929495, 10745.51645175969, 10755.623553175601, 10865.076468500109, 10939.671809845717, 11129.054030407506, 8919.821603567892, 8924.821503569892, 8946.463212207289, 9115.680951166678, 9120.270378369714, 8714.825703486022, 10205.591776328863, 10508.579656813794, 10660.733481662865, 10670.35977841332, 3624.5106910567265, 4118.546959834416, 4255.170241802819, 4265.758023901857, 4266.975925777853, 1054.2957314488312, 1109.7059279290995, 1117.4245263689206, 1150.5627861412609, 1156.8264760286024, 17795.73306400405, 23586.17448389298, 23671.644925326134, 23674.881625591763, 23676.052957881835, 1106.6790630717162, 1118.0943435817014, 1128.4752590045669, 1173.3546549397797, 1179.722765150189, 12241.326727030098, 12375.381230938418, 12475.0, 12590.81113783294, 12627.5580354689, 315989.0, 404662.0, 600071.0, 608329.0, 754639.0, 151157.0, 156583.21708391386, 158451.0, 160172.0, 160225.0, 1245.2901845947904, 1280.8719128087303, 1306.7974463957912, 1313.7897936330141, 1316.8485624153345, 18831.811681883555, 19022.858628482336, 19145.13846876866, 21123.78876211218, 21326.0, 52151.73924130355, 63534.411639709375, 64736.734214302276, 65080.37299067372, 66238.67522649423, 71475.28524714687, 84544.57727711322, 86509.56745216233, 87247.0, 88302.55848720715, 8028.31759300466, 9310.487923164292, 11761.941190293994, 12193.329366884907, 12291.56979505713, 13261.204327740366, 13341.733165336833, 13522.79715804244, 13664.521741738992, 13818.378172982038, 11331.546738130382, 11417.429128543707, 11766.352850593299, 11801.586944456903, 11833.763324733629, 10548.947255263674, 10567.577296908345, 10589.311694739818, 10765.3540787553, 10784.838227426439, 10818.185907271189, 11457.312561246352, 11606.361650109326, 11701.765964680488, 11910.58312959077, 9492.335536512377, 11752.764944700886, 11777.470013849226, 12025.917667409974, 12095.637130886087, 10124.493775311352, 11371.294305854188, 11384.886151138713, 11651.378439851305, 11797.705057373634], "value": 11836.581991347726, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.6915943912627, 1506.6911283186946, 1584.128729198941, 1624.626335942734, 1625.4067265448116, 9152.588133533991, 9573.808523829519, 10951.5619375225, 11229.989300962912, 11464.312141271508, 11212.551497940072, 11305.095592352613, 11310.264832785862, 11313.69892462367, 11341.829872551918, 2377.0491803278655, 2413.046846495633, 2414.541237164942, 2415.541047201035, 2426.599611064172, 2027.2701827342146, 2108.2621082621113, 2393.150431596784, 2469.900894102125, 2485.6644353012384, 2319.4781174235745, 2340.1809366721595, 2340.368100612842, 2346.0263990443946, 2352.5765362234874, 2314.5486630107134, 2350.3301559055585, 2352.5412544553733, 2352.8941197646145, 2353.976460235393, 2319.0375993962484, 2322.11759531377, 2388.0328466970905, 2424.624183251599, 2428.562858685436, 11454.0, 11554.01790847779, 11568.53725850965, 11579.88420115805, 11584.826227606593, 5710.2862142232225, 5906.527477801766, 5927.673977931212, 5935.139404786325, 5940.61386009908, 3307.834608269577, 3451.5340429042008, 3547.113221694576, 3577.159367548624, 3581.552305961755, 1105.1490352428666, 1151.0733859243305, 1152.6023521884936, 1186.9347185904771, 1205.8854408831146, 15533.922330388388, 15635.609109772233, 15664.765028524582, 15704.450344238012, 15735.44925927587, 3363.764536482423, 4594.747288899076, 4794.640401969867, 4845.3216549683075, 4942.579880710108, 13029.283389413673, 13047.478100875858, 13051.543195988095, 13061.608151755463, 13065.0, 5806.883862322815, 5894.734736936848, 5912.556558258148, 5961.850953726192, 5962.731677074456, 4487.147441986012, 5206.687598744012, 5229.555487783581, 5232.058229518647, 5285.075111855487, 3015.8341291228753, 3906.648401643866, 3957.980210098931, 3981.880543583697, 4023.4568333274633, 1063.9734006649896, 1070.844727514506, 1096.0847692176962, 1099.7140743406612, 1111.8109921313396, 723.3742812467283, 807.1766797866144, 828.867381219002, 834.9896625083595, 838.9832203355899, 5917.378675239124, 7004.0, 7030.402415794715, 7122.0, 7298.489105762652, 898.4743924803932, 1046.1473898772476, 1062.2988827373897, 1065.5897479470304, 1101.9504122314515, 4325.653947684178, 4326.956730432718, 4390.460111853104, 4495.213337665896, 4545.659075569345, 13724.176549407068, 14340.426382944774, 14346.784798228035, 14358.353874075692, 14371.496997605034, 13929.442822286996, 14040.1575905446, 14257.572272831832, 14282.143071415749, 14315.78526322106, 10297.588096476207, 10454.947725261325, 10461.633842815465, 10468.371897686164, 10479.056884880398, 6652.234992975869, 6839.119242208432, 6864.176298844171, 6865.519413640997, 6917.861642767016, 12120.393980301125, 12391.814122787984, 12395.132340736061, 12396.628101156979, 12417.0, 10303.484825758833, 10825.242233043611, 12605.369731513205, 12636.557720480107, 12668.936655316664, 8364.3726720495, 8473.728940658704, 8606.397552171287, 8666.869996950178, 8671.176238257356, 10348.948255258676, 10379.948100259451, 10491.422971736627, 10491.737706557093, 10504.579816807242, 8747.125287471457, 8917.28661707049, 9005.864912026456, 9015.233705135137, 9015.459072455673, 7890.329322007694, 8738.038815730137, 8765.868511972452, 8804.955975220082, 8835.734927952171, 7885.093214280431, 8026.317762990211, 8054.436189466681, 8054.959725201336, 8061.758147255592, 5871.4715675589405, 5991.281046274475, 6013.248343957005, 6138.693065346625, 6151.446369826737, 6063.363346848518, 6143.0, 6188.845278868064, 6216.658083805434, 6270.4670103039925], "value": 5159.671917050342, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.66210073034995, 381.62981907549687, 390.77530420008503, 391.85773470333976, 395.1563412115135, 4499.640028797692, 4530.478994915586, 4717.4383340577815, 4791.872696215051, 4883.36516252887, 4057.959420405795, 4171.937420938689, 4302.3331383635505, 4482.8655140345845, 4989.900201995967, 3619.6561326673923, 3681.723870709694, 4290.892727681818, 4303.26844436445, 4330.350447432895, 4000.659943904772, 4219.367094935754, 4231.87304380869, 4250.298700714378, 4283.67872409569, 3684.6683798458134, 3685.7788532688123, 4249.492799248443, 4252.746044596353, 4316.956830431687, 4109.630133287988, 4163.4032324008385, 4192.945456726213, 4197.895052623679, 4256.978325201961, 3343.4483310253777, 3861.227754449121, 3862.0731024553907, 3878.9806050969846, 3920.372740361557, 4755.809767609291, 4942.382202224722, 5112.948870511284, 5138.3577052868395, 5153.814622636777, 4610.2854057621125, 4900.264960255954, 4918.680285781448, 4996.026569505061, 5006.924896126561, 4581.129585378767, 4712.505186955389, 5320.866978325535, 5321.122014867542, 5428.240046393508, 4167.874963751093, 4352.911772056986, 4367.890802729958, 4442.489113751928, 4484.1928452878465, 4912.533309335643, 5139.61452891031, 5306.548943339821, 5493.978311928423, 5883.70581470929, 3820.8853734388013, 4130.979345103256, 4507.729536227836, 4537.455505339314, 4545.250033744395, 4103.97948010258, 4480.641548676098, 4674.532546745366, 4702.614117835154, 4717.3159891816185, 4392.363107349416, 4724.456419076774, 4859.056377449048, 4896.730679812573, 4954.8761280968265, 4636.420447444069, 4665.883352916204, 5440.863978400572, 5460.0, 5537.0, 1396.7695330270594, 1428.4786053090754, 1462.7805829125716, 1492.6268432891777, 1534.5856618713108, 1067.9305845120198, 1149.8558933860695, 1156.6298784388914, 1156.7744289863397, 1159.2464897816487, 4059.654929330981, 4403.581659742319, 4580.755429839422, 4758.95241047592, 4778.808847646124, 5081.034603425337, 5334.639177318265, 5372.623916325898, 5598.216249725042, 5706.514946229534, 4570.006645647107, 4582.541745825458, 4763.21379482694, 4976.8755781105765, 4977.90044199114, 2615.1762194908542, 2855.000749737617, 2895.5801408795605, 2948.5134952732988, 2949.0268211490156, 4799.880002999953, 5280.788768449219, 5281.577473802086, 5582.853946947913, 5662.348829884616, 4944.629152813553, 4958.752062396866, 5373.348908257702, 5652.576056795757, 5773.336066352339, 5239.74690882924, 5271.578273738091, 5598.356189038232, 5599.664020158722, 5870.735816888336, 5178.197379406198, 5597.524210442158, 5650.802221922213, 5665.433456654301, 5734.197212390185, 4862.5380588844, 5214.113600687968, 5261.237120617566, 5515.558755299486, 5592.664440133605, 5094.643374963717, 5296.549793267615, 5470.058129364178, 5888.823335299948, 5895.927194989168, 4069.2878746219885, 4339.956600433955, 4488.371627972021, 4504.77476126199, 4613.515580863961, 5291.888703372181, 5362.195670649434, 5650.604457688087, 5697.607005057968, 5762.193292938908, 5020.924686129638, 5253.399567214254, 5528.474794894479, 5558.388577256579, 5560.4161563035295, 4595.724256544618, 5132.178851383761, 5346.893716500655, 5365.436629153883, 5384.948204436962, 4607.815687372467, 4607.976960115178, 5031.698098114125, 5351.411344752152, 5360.391882435176, 4724.787384567634, 5196.298499702643, 5210.0, 5497.780088796403, 5510.366307874646, 4391.824327026883, 4890.217565189695, 4977.452480227244, 4990.451050384528, 5341.813036543703], "value": 4129.907664363832, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [735.3675838778651, 742.8180095876511, 751.8458715963225, 783.3967844759535, 909.7998440343122, 15402.537923862288, 15921.44274950374, 15959.12224827634, 16059.839401605981, 16071.919640401811, 14704.264786760672, 14829.406823727066, 14843.480478183239, 16534.42129525467, 17225.310987560515, 812.6140083460353, 1002.4035698759227, 1002.5488530161421, 1003.3628645809927, 1003.7239759066242, 997.875265591801, 999.0808456220259, 999.380384161817, 1000.8598796168542, 1002.3184234720387, 1046.0062940206833, 1049.8950104989517, 1050.9894901051007, 1052.6999805055523, 1052.7105046112304, 1045.3832238979, 1045.3988956350054, 1045.6078970386104, 1048.0567489259688, 1048.6749107776614, 772.7797577690387, 777.2460713108276, 777.4713195027379, 777.766669998998, 780.2275747010419, 16409.343626254937, 16531.00813951167, 16538.007719536745, 16564.6687066258, 16564.75152872708, 12508.74982500345, 14104.647383815383, 14197.858021419861, 14251.287435628177, 14260.286985650677, 11343.773124537463, 11661.766764664744, 11729.648110556696, 11748.295102293805, 12195.14633975622, 3567.3400420921953, 3675.9081022974374, 3847.365184744514, 3888.727789054767, 3891.5135608048995, 109932.4503377486, 115676.1080972974, 118016.0, 119110.80889191084, 119795.0, 10535.736606584896, 10684.732881677864, 10736.516856741466, 11132.05377542918, 11142.49858756358, 22574.887125564266, 23116.53766924652, 23262.3021309361, 23281.0, 23313.883430582737, 7702.576358300235, 7725.690972361155, 7807.336376407955, 7823.0, 7913.0, 3892.9416058759143, 4394.978025109854, 4407.559244075631, 4429.49060857999, 4444.933326000112, 3006.6842981487066, 3422.1273575238156, 3501.5272938153025, 3524.312759011968, 3541.3979623464074, 603.3483837455537, 612.087988896541, 620.950323974081, 637.8628594852129, 639.6865535887382, 672.5561129654404, 675.5338816216795, 705.7706245470242, 707.5860621536459, 731.0, 10167.0, 10389.324693895023, 10681.893181068246, 10754.89245107539, 10792.082672972729, 657.9276279609239, 689.5138927056513, 695.9130108736408, 712.7790384980673, 723.0889079759436, 4927.728974906343, 4966.528179822911, 4975.527324904128, 4988.45127036033, 4991.425986011655, 79746.0, 100722.49638751759, 101330.49334753279, 103882.48058759658, 108482.0, 87694.56152719195, 89497.0, 90829.09170908206, 92855.53572232231, 93708.53145734363, 63880.0, 78144.0, 81645.18354816495, 82041.0, 82539.58730206311, 10633.787324253317, 10963.780724385628, 11279.605213817476, 11447.656570302572, 11448.771024579628, 46860.0, 48154.51845481501, 48357.7910552239, 49260.50739492559, 49963.50036499588, 70452.59094818178, 70975.64512177405, 71138.0, 72291.91562126677, 72505.0, 12623.747525049632, 12899.548515801902, 13070.281134537692, 13436.261005644392, 13514.526991555249, 14253.0, 14509.92745036268, 15175.0, 15383.538493845203, 15772.842271577138, 13395.866041339463, 13436.328183590977, 13990.440382384997, 14126.929365353108, 14153.787693184817, 13024.41390137465, 13241.602751917459, 13886.444542218602, 14055.718885622025, 14526.636834079232, 13283.535076272285, 13437.0, 13664.795028074388, 13872.4451021958, 14378.35297411598, 9269.397489163182, 9638.084381983701, 9741.610335586498, 9779.755506112404, 10001.849972250568, 9241.86137207929, 9842.65550705722, 10309.917458666732, 10569.577216911237, 11152.0], "value": 7781.11766532392, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1444.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 117.0, 115.0, 110.0, 115.0, 115.0, 117.0, 116.0, 116.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 108.0, 109.0, 141.0, 113.0, 110.0, 107.0, 108.0, 127.0, 108.0], "value": 116.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 185.0, 187.0, 187.0, 196.0, 265.0, 205.0, 187.0, 186.0], "value": 197.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 60.0, 93.0, 64.0, 61.0, 59.0, 60.0, 75.0, 60.0], "value": 67.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 87.0, 70.0, 70.0, 69.0, 85.0, 71.0, 69.0, 83.0], "value": 76.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 90.0, 134.0, 87.0, 88.0, 87.0, 88.0, 87.0], "value": 92.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 150.0, 95.0, 95.0, 94.0, 94.0, 96.0, 96.0, 95.0, 94.0], "value": 101.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 85.0, 86.0, 83.0, 84.0, 84.0, 88.0, 83.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 59.0, 61.0, 63.0, 63.0, 64.0, 63.0, 62.0, 62.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 62.0, 59.0, 67.0, 64.0, 62.0, 57.0, 58.0, 59.0, 57.0], "value": 59.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 98.0, 85.0, 86.0, 81.0, 82.0, 85.0, 85.0, 86.0, 81.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 140.0, 137.0, 135.0, 136.0, 136.0, 139.0, 142.0, 187.0, 177.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 167.0, 165.0, 165.0, 143.0, 187.0, 219.0, 220.0, 164.0, 165.0], "value": 176.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 64.0, 65.0, 64.0, 64.0, 63.0, 63.0, 65.0], "value": 64.1, "unit": "score"}]}]} 08:41:19 INFO - Return code: 0 08:41:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:41:19 INFO - # TBPL SUCCESS # 08:41:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:41:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:41:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:41:19 INFO - 2017-05-24 08:41:19.108 screenresolution[1655:9935] starting screenresolution argv=screenresolution get 08:41:19 INFO - 2017-05-24 08:41:19.111 screenresolution[1655:9935] Display 0: 1600x1200x32@60 08:41:19 INFO - 2017-05-24 08:41:19.117 screenresolution[1656:9938] starting screenresolution argv=screenresolution list 08:41:19 INFO - Available Modes on Display 0 08:41:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:41:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:41:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:41:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:41:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:41:19 INFO - Intel Iris: 08:41:19 INFO - Chipset Model: Intel Iris 08:41:19 INFO - Type: GPU 08:41:19 INFO - Bus: Built-In 08:41:19 INFO - VRAM (Dynamic, Max): 1536 MB 08:41:19 INFO - Vendor: Intel (0x8086) 08:41:19 INFO - Device ID: 0x0a2e 08:41:19 INFO - Revision ID: 0x0009 08:41:19 INFO - Displays: 08:41:19 INFO - Display: 08:41:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:41:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:41:19 INFO - Display Serial Number: 1600x1200 60 08:41:19 INFO - Main Display: Yes 08:41:19 INFO - Mirror: Off 08:41:19 INFO - Online: Yes 08:41:19 INFO - Rotation: Supported 08:41:19 INFO - Return code: 0 08:41:19 INFO - Running post-action listener: _resource_record_post_action 08:41:19 INFO - [mozharness: 2017-05-24 15:41:19.351546Z] Finished run-tests step (success) 08:41:19 INFO - Running post-run listener: _resource_record_post_run 08:41:19 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 39928832; Write bytes: 509711360; Read time: 389; Write time: 2042 08:41:19 INFO - TinderboxPrint: CPU usage
53.8% 08:41:19 INFO - TinderboxPrint: I/O read bytes / time
39,928,832 / 389 08:41:19 INFO - TinderboxPrint: I/O write bytes / time
509,711,360 / 2,042 08:41:19 INFO - TinderboxPrint: CPU idle
2,218.6 (46.2%) 08:41:19 INFO - TinderboxPrint: CPU system
957.9 (20.0%) 08:41:19 INFO - TinderboxPrint: CPU user
1,621.2 (33.8%) 08:41:19 INFO - TinderboxPrint: Swap in / out
247,885,824 / 0 08:41:19 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 211679744; Write bytes: 199708672; Read time: 16160; Write time: 368 08:41:19 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 32439296; Write bytes: 298931200; Read time: 301; Write time: 1638 08:41:19 INFO - Running post-run listener: _upload_blobber_files 08:41:19 INFO - Blob upload gear active. 08:41:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:41:19 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:41:19 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:41:19 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:41:20 INFO - (blobuploader) - INFO - Open directory for files ... 08:41:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:41:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:41:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:41:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:41:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:41:21 INFO - (blobuploader) - INFO - Done attempting. 08:41:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:41:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:41:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:41:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:41:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:41:22 INFO - (blobuploader) - INFO - Done attempting. 08:41:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 08:41:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:41:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:41:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 08:41:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:41:23 INFO - (blobuploader) - INFO - Done attempting. 08:41:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:41:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:41:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:41:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:41:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:41:25 INFO - (blobuploader) - INFO - Done attempting. 08:41:25 INFO - (blobuploader) - INFO - Iteration through files over. 08:41:25 INFO - Return code: 0 08:41:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:41:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:41:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b33fd212cc81a87249993b414e40df1d7f57bb8dca9d8991f613519185b77a7b525bee6752192a35d1aefa27baa6c5fd57e15ea6b1af76cf28604ef60b6fda58", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0056e49c7f66b3db19f5fdc65e294fddce1312400f1ce3421e63714fd484405147a505e42e842e6810b917571b6761f4983b542b2b83562f1095ecec50046ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2b79b1b1f73dba941d5f6bbde0f9d31a553f259f6a452e3521918215d8b78c4846d0332ef53ec2cb04a6bf880285bf0ee1a5200b0ab366892c3dfad6bd12b8aa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/990cccb1208fb08334fcf3e5b48428f76d031d19a97f1a540bc0b606b5412a1f015b31ace99d695a1b5af327e298ca7ef2a84c5c4c670fcb0d837dd359f67aa9"} 08:41:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:41:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:41:25 INFO - Contents: 08:41:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b33fd212cc81a87249993b414e40df1d7f57bb8dca9d8991f613519185b77a7b525bee6752192a35d1aefa27baa6c5fd57e15ea6b1af76cf28604ef60b6fda58", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0056e49c7f66b3db19f5fdc65e294fddce1312400f1ce3421e63714fd484405147a505e42e842e6810b917571b6761f4983b542b2b83562f1095ecec50046ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2b79b1b1f73dba941d5f6bbde0f9d31a553f259f6a452e3521918215d8b78c4846d0332ef53ec2cb04a6bf880285bf0ee1a5200b0ab366892c3dfad6bd12b8aa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/990cccb1208fb08334fcf3e5b48428f76d031d19a97f1a540bc0b606b5412a1f015b31ace99d695a1b5af327e298ca7ef2a84c5c4c670fcb0d837dd359f67aa9"} 08:41:25 INFO - Running post-run listener: copy_logs_to_upload_dir 08:41:25 INFO - Copying logs to upload dir... 08:41:25 INFO - mkdir: /builds/slave/test/build/upload/logs 08:41:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.203521 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-24 08:41:25.645128) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 08:41:25.648524) ========= 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.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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/b33fd212cc81a87249993b414e40df1d7f57bb8dca9d8991f613519185b77a7b525bee6752192a35d1aefa27baa6c5fd57e15ea6b1af76cf28604ef60b6fda58", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0056e49c7f66b3db19f5fdc65e294fddce1312400f1ce3421e63714fd484405147a505e42e842e6810b917571b6761f4983b542b2b83562f1095ecec50046ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2b79b1b1f73dba941d5f6bbde0f9d31a553f259f6a452e3521918215d8b78c4846d0332ef53ec2cb04a6bf880285bf0ee1a5200b0ab366892c3dfad6bd12b8aa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/990cccb1208fb08334fcf3e5b48428f76d031d19a97f1a540bc0b606b5412a1f015b31ace99d695a1b5af327e298ca7ef2a84c5c4c670fcb0d837dd359f67aa9"} build_url:https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010942 build_url: 'https://queue.taskcluster.net/v1/task/ej2oMrUJQ4Sn2GYq_7IO_w/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/b33fd212cc81a87249993b414e40df1d7f57bb8dca9d8991f613519185b77a7b525bee6752192a35d1aefa27baa6c5fd57e15ea6b1af76cf28604ef60b6fda58", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c0056e49c7f66b3db19f5fdc65e294fddce1312400f1ce3421e63714fd484405147a505e42e842e6810b917571b6761f4983b542b2b83562f1095ecec50046ad", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2b79b1b1f73dba941d5f6bbde0f9d31a553f259f6a452e3521918215d8b78c4846d0332ef53ec2cb04a6bf880285bf0ee1a5200b0ab366892c3dfad6bd12b8aa", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/990cccb1208fb08334fcf3e5b48428f76d031d19a97f1a540bc0b606b5412a1f015b31ace99d695a1b5af327e298ca7ef2a84c5c4c670fcb0d837dd359f67aa9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 08:41:25.682120) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 08:41:25.682556) ========= 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.UEZ8JWNcIN/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.Ne4xYCPx2a/Listeners TMPDIR=/var/folders/t3/b01yfz4j3ln01tl7gpfw3l9000000w/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.004542 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 08:41:25.731952) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 08:41:25.732294) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 08:41:25.732726) ========= ========= Total master_lag: 1.10 =========