builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0263 starttime: 1495554236.21 results: success (0) buildid: 20170523074624 builduid: 7048be335ba440a8b20ef3333c0f1328 revision: 279acccff1c12f047565b465286fcbcc180c5ab0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:56.208246) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:56.208654) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:56.208961) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:56.233790) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:56.234122) ========= 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.2OllZD6rYa/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.4s2wnBuUGD/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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-23 08:43:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 166M=0s 2017-05-23 08:43:56 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132577 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:56.389114) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:56.389443) ========= 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.2OllZD6rYa/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.4s2wnBuUGD/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.026837 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:56.437503) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-23 08:43:56.437860) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 279acccff1c12f047565b465286fcbcc180c5ab0 --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 279acccff1c12f047565b465286fcbcc180c5ab0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.2OllZD6rYa/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.4s2wnBuUGD/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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-23 08:43:56,544 truncating revision to first 12 chars 2017-05-23 08:43:56,545 Setting DEBUG logging. 2017-05-23 08:43:56,545 attempt 1/10 2017-05-23 08:43:56,545 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/279acccff1c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-23 08:43:58,221 unpacking tar archive at: mozilla-beta-279acccff1c1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.957716 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-23 08:43:58.417221) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:58.417540) ========= script_repo_revision: 279acccff1c12f047565b465286fcbcc180c5ab0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:58.417929) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:58.418193) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-23 08:43:58.436921) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-05-23 08:43:58.437309) ========= /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.2OllZD6rYa/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.4s2wnBuUGD/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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:43:58 INFO - MultiFileLogger online at 20170523 08:43:58 in /builds/slave/test 08:43:58 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:43:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:43:58 INFO - {'append_to_log': False, 08:43:58 INFO - 'base_work_dir': '/builds/slave/test', 08:43:58 INFO - 'blob_upload_branch': 'Mozilla-Beta', 08:43:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:43:58 INFO - 'branch': 'Mozilla-Beta', 08:43:58 INFO - 'buildbot_json_path': 'buildprops.json', 08:43:58 INFO - 'config_files': ('talos/mac_config.py',), 08:43:58 INFO - 'default_actions': ('clobber', 08:43:58 INFO - 'read-buildbot-config', 08:43:58 INFO - 'download-and-extract', 08:43:58 INFO - 'populate-webroot', 08:43:58 INFO - 'create-virtualenv', 08:43:58 INFO - 'install', 08:43:58 INFO - 'run-tests'), 08:43:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:43:58 INFO - 'download_minidump_stackwalk': True, 08:43:58 INFO - 'download_symbols': 'ondemand', 08:43:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:43:58 INFO - 'tooltool.py': '/tools/tooltool.py', 08:43:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:43:58 INFO - '/tools/misc-python/virtualenv.py')}, 08:43:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:43:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:43:58 INFO - 'gecko_profile': False, 08:43:58 INFO - 'gecko_profile_interval': 0, 08:43:58 INFO - 'installer_path': 'installer.exe', 08:43:58 INFO - 'log_level': 'info', 08:43:58 INFO - 'log_name': 'talos', 08:43:58 INFO - 'log_to_console': True, 08:43:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:43:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:43:58 INFO - 'opt_config_files': (), 08:43:58 INFO - 'pip_index': False, 08:43:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:58 INFO - 'cmd': ('bash', 08:43:58 INFO - '-c', 08:43:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:58 INFO - 'enabled': True, 08:43:58 INFO - 'halt_on_failure': False, 08:43:58 INFO - 'name': 'check_screen_resolution'},), 08:43:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:43:58 INFO - 'cmd': ('bash', 08:43:58 INFO - '-c', 08:43:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:43:58 INFO - 'enabled': True, 08:43:58 INFO - 'halt_on_failure': False, 08:43:58 INFO - 'name': 'check_screen_resolution'},), 08:43:58 INFO - 'run_cmd_checks_enabled': True, 08:43:58 INFO - 'suite': 'dromaeojs', 08:43:58 INFO - 'system_bits': '32', 08:43:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:43:58 INFO - 'title': 't-yosemite-r7-0263', 08:43:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:43:58 INFO - 'use_talos_json': True, 08:43:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:43:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:43:58 INFO - 'work_dir': 'build'} 08:43:58 INFO - [mozharness: 2017-05-23 15:43:58.653579Z] Running clobber step. 08:43:58 INFO - Running pre-action listener: _resource_record_pre_action 08:43:58 INFO - Running main action method: clobber 08:43:58 INFO - rmtree: /builds/slave/test/build 08:43:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:44:03 INFO - Running post-action listener: _resource_record_post_action 08:44:03 INFO - [mozharness: 2017-05-23 15:44:03.166998Z] Finished clobber step (success) 08:44:03 INFO - [mozharness: 2017-05-23 15:44:03.167158Z] Running read-buildbot-config step. 08:44:03 INFO - Running pre-action listener: _resource_record_pre_action 08:44:03 INFO - Running main action method: read_buildbot_config 08:44:03 INFO - Using buildbot properties: 08:44:03 INFO - { 08:44:03 INFO - "project": "", 08:44:03 INFO - "product": "firefox", 08:44:03 INFO - "script_repo_revision": "production", 08:44:03 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 08:44:03 INFO - "repository": "", 08:44:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 08:44:03 INFO - "buildid": "20170523074624", 08:44:03 INFO - "pgo_build": "False", 08:44:03 INFO - "basedir": "/builds/slave/test", 08:44:03 INFO - "buildnumber": 1, 08:44:03 INFO - "slavename": "t-yosemite-r7-0263", 08:44:03 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:44:03 INFO - "platform": "yosemite_r7", 08:44:03 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 08:44:03 INFO - "branch": "mozilla-beta", 08:44:03 INFO - "repo_path": "releases/mozilla-beta", 08:44:03 INFO - "revision": "279acccff1c12f047565b465286fcbcc180c5ab0", 08:44:03 INFO - "stage_platform": "macosx64", 08:44:03 INFO - "builduid": "7048be335ba440a8b20ef3333c0f1328", 08:44:03 INFO - "slavebuilddir": "test" 08:44:03 INFO - } 08:44:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 08:44:03 INFO - Running post-action listener: _resource_record_post_action 08:44:03 INFO - [mozharness: 2017-05-23 15:44:03.170471Z] Finished read-buildbot-config step (success) 08:44:03 INFO - [mozharness: 2017-05-23 15:44:03.170569Z] Running download-and-extract step. 08:44:03 INFO - Running pre-action listener: _resource_record_pre_action 08:44:03 INFO - Running main action method: download_and_extract 08:44:03 INFO - mkdir: /builds/slave/test/build/tests 08:44: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:44:03 INFO - trying https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 08:44:03 INFO - Downloading https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/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:44:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/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:44:04 INFO - Downloaded 1287 bytes. 08:44:04 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 08:44:04 INFO - Using the following test package requirements: 08:44:04 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:44:04 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 08:44:04 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:44:04 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:44:04 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 08:44:04 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:44:04 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 08:44:04 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 08:44:04 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:44:04 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 08:44:04 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 08:44:04 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:44:04 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 08:44:04 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:44:04 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 08:44:04 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:44:04 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 08:44:04 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 08:44:04 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 08:44:04 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 08:44:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:44:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:44:04 INFO - Fetch https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:44:08 INFO - Expected file size: 51393535 08:44:08 INFO - Obtained file size: 51393535 08:44:24 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:44:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 08:44:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 08:44:24 INFO - Fetch https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 08:44:32 INFO - Expected file size: 51393535 08:44:32 INFO - Obtained file size: 51393535 08:44:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 08:44:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 08:44:47 INFO - Fetch https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 08:44:49 INFO - Expected file size: 14404449 08:44:49 INFO - Obtained file size: 14404449 08:44:49 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:44:49 INFO - trying https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:44:49 INFO - Downloading https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 08:44:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/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:44:54 INFO - Downloaded 59649381 bytes. 08:44:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:44:54 INFO - mkdir: /builds/slave/test/properties 08:44:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:44:54 INFO - Writing to file /builds/slave/test/properties/build_url 08:44:54 INFO - Contents: 08:44:54 INFO - build_url:https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 08:44:55 INFO - Running post-action listener: _resource_record_post_action 08:44:55 INFO - Running post-action listener: set_extra_try_arguments 08:44:55 INFO - [mozharness: 2017-05-23 15:44:55.053506Z] Finished download-and-extract step (success) 08:44:55 INFO - [mozharness: 2017-05-23 15:44:55.053652Z] Running populate-webroot step. 08:44:55 INFO - Running pre-action listener: _resource_record_pre_action 08:44:55 INFO - Running main action method: populate_webroot 08:44:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:44:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:44:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:44:55 INFO - '--tppagecycles', 08:44:55 INFO - '7'], 08:44:55 INFO - 'tests': ['tsvgm']}}, 08:44:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:44:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:44:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:44:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:44:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:44:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:44:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:44:55 INFO - 'talos_options': ['--disable-e10s'], 08:44:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:44:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:44:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:44:55 INFO - 'talos_options': ['--disable-e10s'], 08:44:55 INFO - 'tests': ['damp', 'tps']}, 08:44:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:44:55 INFO - 'tests': ['damp', 'tps']}, 08:44:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:44:55 INFO - 'tests': ['dromaeo_dom']}, 08:44:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:44:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:44:55 INFO - 'tests': ['basic_compositor_video']}, 08:44:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:44:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:44:55 INFO - 'tests': ['a11yr', 08:44:55 INFO - 'ts_paint', 08:44:55 INFO - 'tpaint', 08:44:55 INFO - 'sessionrestore', 08:44:55 INFO - 'sessionrestore_no_auto_restore', 08:44:55 INFO - 'tabpaint']}, 08:44:55 INFO - 'other-e10s': {'tests': ['a11yr', 08:44:55 INFO - 'ts_paint', 08:44:55 INFO - 'tpaint', 08:44:55 INFO - 'sessionrestore', 08:44:55 INFO - 'sessionrestore_no_auto_restore', 08:44:55 INFO - 'tabpaint']}, 08:44:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:44:55 INFO - 'ts_paint', 08:44:55 INFO - 'tpaint', 08:44:55 INFO - 'sessionrestore', 08:44:55 INFO - 'sessionrestore_no_auto_restore', 08:44:55 INFO - 'tabpaint']}, 08:44:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:44:55 INFO - 'ts_paint', 08:44:55 INFO - 'tpaint', 08:44:55 INFO - 'sessionrestore', 08:44:55 INFO - 'sessionrestore_no_auto_restore', 08:44:55 INFO - 'tabpaint']}, 08:44:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:44:55 INFO - 'tests': ['a11yr', 08:44:55 INFO - 'ts_paint', 08:44:55 INFO - 'tpaint', 08:44:55 INFO - 'sessionrestore', 08:44:55 INFO - 'sessionrestore_no_auto_restore', 08:44:55 INFO - 'tabpaint']}, 08:44:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:44:55 INFO - 'tests': ['a11yr', 08:44:55 INFO - 'ts_paint', 08:44:55 INFO - 'tpaint', 08:44:55 INFO - 'sessionrestore', 08:44:55 INFO - 'sessionrestore_no_auto_restore', 08:44:55 INFO - 'tabpaint']}, 08:44:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:44:55 INFO - 'tests': ['tsvgx', 08:44:55 INFO - 'tsvgr_opacity', 08:44:55 INFO - 'tart', 08:44:55 INFO - 'tscrollx', 08:44:55 INFO - 'cart', 08:44:55 INFO - 'tsvg_static']}, 08:44:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:44:55 INFO - 'tsvgr_opacity', 08:44:55 INFO - 'tart', 08:44:55 INFO - 'tscrollx', 08:44:55 INFO - 'cart', 08:44:55 INFO - 'tsvg_static']}, 08:44:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:44:55 INFO - 'talos_options': ['--disable-e10s'], 08:44:55 INFO - 'tests': ['tp5o']}, 08:44:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:44:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:44:55 INFO - 'talos_options': ['--disable-e10s', 08:44:55 INFO - '--xperf_path', 08:44:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:55 INFO - 'tests': ['tp5n']}, 08:44:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:44:55 INFO - 'talos_options': ['--xperf_path', 08:44:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:44:55 INFO - 'tests': ['tp5n']}}, 08:44:55 INFO - 'talos.zip': {'path': '', 08:44:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:44:55 INFO - Running post-action listener: _resource_record_post_action 08:44:55 INFO - [mozharness: 2017-05-23 15:44:55.072438Z] Finished populate-webroot step (success) 08:44:55 INFO - [mozharness: 2017-05-23 15:44:55.072563Z] Running create-virtualenv step. 08:44:55 INFO - Running pre-action listener: _resource_record_pre_action 08:44:55 INFO - Running main action method: create_virtualenv 08:44:55 INFO - Creating virtualenv /builds/slave/test/build/venv 08:44:55 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:44:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:44:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:44:55 INFO - Using real prefix '/tools/python27' 08:44:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:44:55 INFO - Installing distribute.............................................................................................................................................................................................done. 08:44:58 INFO - Installing pip.................done. 08:44:58 INFO - Return code: 0 08:44:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:44:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:44:59 INFO - Reading from file tmpfile_stdout 08:44:59 INFO - Output received: 08:44:59 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:44:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:44:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:44:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:44:59 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 0x1076b4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076b7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda15d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492d30>, '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 0x1073b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107487be8>, '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.2OllZD6rYa/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.4s2wnBuUGD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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:44:59 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:44:59 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:44:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2OllZD6rYa/Render', 08:44:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:44:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:44:59 INFO - 'HOME': '/Users/cltbld', 08:44:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:44:59 INFO - 'LOGNAME': 'cltbld', 08:44:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:44:59 INFO - 'MOZ_NO_REMOTE': '1', 08:44:59 INFO - 'NO_EM_RESTART': '1', 08:44:59 INFO - 'PAGER': '/bin/cat', 08:44:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:44:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:44:59 INFO - 'PWD': '/builds/slave/test', 08:44:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:44:59 INFO - 'SHELL': '/bin/bash', 08:44:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2wnBuUGD/Listeners', 08:44:59 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:44:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:44:59 INFO - 'USER': 'cltbld', 08:44:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:44:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:44:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:44:59 INFO - 'XPC_FLAGS': '0x0', 08:44:59 INFO - 'XPC_SERVICE_NAME': '0', 08:44:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:00 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:45:00 INFO - Cleaning up... 08:45:00 INFO - Return code: 0 08:45:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:45:00 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:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:00 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:45:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1076b4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076b7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda15d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492d30>, '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 0x1073b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107487be8>, '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.2OllZD6rYa/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.4s2wnBuUGD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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:45:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:45:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:45:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2OllZD6rYa/Render', 08:45:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:00 INFO - 'HOME': '/Users/cltbld', 08:45:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:00 INFO - 'LOGNAME': 'cltbld', 08:45:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:00 INFO - 'MOZ_NO_REMOTE': '1', 08:45:00 INFO - 'NO_EM_RESTART': '1', 08:45:00 INFO - 'PAGER': '/bin/cat', 08:45:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:00 INFO - 'PWD': '/builds/slave/test', 08:45:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:00 INFO - 'SHELL': '/bin/bash', 08:45:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2wnBuUGD/Listeners', 08:45:00 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:45:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:00 INFO - 'USER': 'cltbld', 08:45:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:00 INFO - 'XPC_FLAGS': '0x0', 08:45:00 INFO - 'XPC_SERVICE_NAME': '0', 08:45:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:00 INFO - Downloading/unpacking psutil>=3.1.1 08:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:45:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:45:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:45:03 INFO - Installing collected packages: psutil 08:45:03 INFO - Running setup.py install for psutil 08:45:03 INFO - building 'psutil._psutil_osx' extension 08:45:03 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:45:03 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:45:03 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:45:03 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:45:03 INFO - building 'psutil._psutil_posix' extension 08:45:03 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:45:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:45:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:45:04 INFO - ^ 08:45:04 INFO - 1 warning generated. 08:45:04 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:45:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:45:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:45:04 INFO - Successfully installed psutil 08:45:04 INFO - Cleaning up... 08:45:04 INFO - Return code: 0 08:45:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:45:04 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:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:04 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:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1076b4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076b7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda15d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492d30>, '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 0x1073b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107487be8>, '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.2OllZD6rYa/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.4s2wnBuUGD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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:45:04 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:45:04 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:45:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2OllZD6rYa/Render', 08:45:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:04 INFO - 'HOME': '/Users/cltbld', 08:45:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:04 INFO - 'LOGNAME': 'cltbld', 08:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:04 INFO - 'MOZ_NO_REMOTE': '1', 08:45:04 INFO - 'NO_EM_RESTART': '1', 08:45:04 INFO - 'PAGER': '/bin/cat', 08:45:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:04 INFO - 'PWD': '/builds/slave/test', 08:45:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:04 INFO - 'SHELL': '/bin/bash', 08:45:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2wnBuUGD/Listeners', 08:45:04 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:45:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:04 INFO - 'USER': 'cltbld', 08:45:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:04 INFO - 'XPC_FLAGS': '0x0', 08:45:04 INFO - 'XPC_SERVICE_NAME': '0', 08:45:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:45: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:45: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:45: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:45: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:45:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:45:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:45:06 INFO - Installing collected packages: mozsystemmonitor 08:45:06 INFO - Running setup.py install for mozsystemmonitor 08:45:06 INFO - Successfully installed mozsystemmonitor 08:45:06 INFO - Cleaning up... 08:45:06 INFO - Return code: 0 08:45:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:45: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:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45: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:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45: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', '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 0x1076b4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076b7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda15d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492d30>, '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 0x1073b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107487be8>, '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.2OllZD6rYa/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.4s2wnBuUGD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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:45: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:45: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 jsonschema==2.5.1 08:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2OllZD6rYa/Render', 08:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:06 INFO - 'HOME': '/Users/cltbld', 08:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:06 INFO - 'LOGNAME': 'cltbld', 08:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:06 INFO - 'MOZ_NO_REMOTE': '1', 08:45:06 INFO - 'NO_EM_RESTART': '1', 08:45:06 INFO - 'PAGER': '/bin/cat', 08:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:06 INFO - 'PWD': '/builds/slave/test', 08:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:06 INFO - 'SHELL': '/bin/bash', 08:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2wnBuUGD/Listeners', 08:45:06 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:06 INFO - 'USER': 'cltbld', 08:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:06 INFO - 'XPC_FLAGS': '0x0', 08:45:06 INFO - 'XPC_SERVICE_NAME': '0', 08:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:06 INFO - Downloading/unpacking jsonschema==2.5.1 08:45: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:45: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:45: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:45: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:45:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:45:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:08 INFO - Downloading functools32-3.2.3-2.tar.gz 08:45:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:45:08 INFO - warning: no files found matching '*.txt' 08:45:08 INFO - no previously-included directories found matching 'build' 08:45:08 INFO - no previously-included directories found matching 'dist' 08:45:08 INFO - no previously-included directories found matching '.git*' 08:45:08 INFO - Installing collected packages: jsonschema, functools32 08:45:08 INFO - Running setup.py install for functools32 08:45:08 INFO - warning: no files found matching '*.txt' 08:45:08 INFO - no previously-included directories found matching 'build' 08:45:08 INFO - no previously-included directories found matching 'dist' 08:45:08 INFO - no previously-included directories found matching '.git*' 08:45:08 INFO - Successfully installed jsonschema functools32 08:45:08 INFO - Cleaning up... 08:45:08 INFO - Return code: 0 08:45:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:45:08 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:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:08 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:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1076b4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076b7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda15d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492d30>, '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 0x1073b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107487be8>, '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.2OllZD6rYa/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.4s2wnBuUGD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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:45:08 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:45:08 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:45:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2OllZD6rYa/Render', 08:45:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:08 INFO - 'HOME': '/Users/cltbld', 08:45:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:08 INFO - 'LOGNAME': 'cltbld', 08:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:08 INFO - 'MOZ_NO_REMOTE': '1', 08:45:08 INFO - 'NO_EM_RESTART': '1', 08:45:08 INFO - 'PAGER': '/bin/cat', 08:45:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:08 INFO - 'PWD': '/builds/slave/test', 08:45:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:08 INFO - 'SHELL': '/bin/bash', 08:45:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2wnBuUGD/Listeners', 08:45:08 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:45:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:08 INFO - 'USER': 'cltbld', 08:45:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:08 INFO - 'XPC_FLAGS': '0x0', 08:45:08 INFO - 'XPC_SERVICE_NAME': '0', 08:45:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:45:08 INFO - Cleaning up... 08:45:08 INFO - Return code: 0 08:45:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:45:08 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:45:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:08 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:45:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1076b4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076b7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda15d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492d30>, '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 0x1073b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107487be8>, '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.2OllZD6rYa/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.4s2wnBuUGD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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:45:08 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:45:08 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:45:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2OllZD6rYa/Render', 08:45:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:08 INFO - 'HOME': '/Users/cltbld', 08:45:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:08 INFO - 'LOGNAME': 'cltbld', 08:45:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:08 INFO - 'MOZ_NO_REMOTE': '1', 08:45:08 INFO - 'NO_EM_RESTART': '1', 08:45:08 INFO - 'PAGER': '/bin/cat', 08:45:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:08 INFO - 'PWD': '/builds/slave/test', 08:45:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:08 INFO - 'SHELL': '/bin/bash', 08:45:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2wnBuUGD/Listeners', 08:45:08 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:45:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:08 INFO - 'USER': 'cltbld', 08:45:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:08 INFO - 'XPC_FLAGS': '0x0', 08:45:08 INFO - 'XPC_SERVICE_NAME': '0', 08:45:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:09 INFO - Downloading/unpacking blobuploader==1.2.4 08:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:10 INFO - Downloading blobuploader-1.2.4.tar.gz 08:45:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:45:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:45:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:11 INFO - Downloading docopt-0.6.1.tar.gz 08:45:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:45:11 INFO - Installing collected packages: blobuploader, requests, docopt 08:45:11 INFO - Running setup.py install for blobuploader 08:45:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:45:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:45:11 INFO - Running setup.py install for requests 08:45:12 INFO - Running setup.py install for docopt 08:45:12 INFO - Successfully installed blobuploader requests docopt 08:45:12 INFO - Cleaning up... 08:45:12 INFO - Return code: 0 08:45:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:12 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:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:12 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:45:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:12 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 0x1076b4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076b7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda15d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492d30>, '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 0x1073b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107487be8>, '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.2OllZD6rYa/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.4s2wnBuUGD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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:45:12 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:45:12 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:45:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2OllZD6rYa/Render', 08:45:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:12 INFO - 'HOME': '/Users/cltbld', 08:45:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:12 INFO - 'LOGNAME': 'cltbld', 08:45:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:12 INFO - 'MOZ_NO_REMOTE': '1', 08:45:12 INFO - 'NO_EM_RESTART': '1', 08:45:12 INFO - 'PAGER': '/bin/cat', 08:45:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:12 INFO - 'PWD': '/builds/slave/test', 08:45:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:12 INFO - 'SHELL': '/bin/bash', 08:45:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2wnBuUGD/Listeners', 08:45:12 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:45:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:12 INFO - 'USER': 'cltbld', 08:45:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:12 INFO - 'XPC_FLAGS': '0x0', 08:45:12 INFO - 'XPC_SERVICE_NAME': '0', 08:45:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:45:12 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-Knar40-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:45:12 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-7eUZsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:45:12 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-EgmQ0i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:45:12 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-GveNlK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:45:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:45:12 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-PJy3PF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:45:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-IkIz5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:45:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-gSMhWO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:45:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-BjR2hO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:45:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-LPSONf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:45:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-uhfyck-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:45:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-evttL_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:45:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-mskU1C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:45:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-LGr1jX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:45:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-bfN4b8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:13 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-nF_TcC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:45:14 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-SsfnhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:45:14 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-obRGF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:45:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:45:14 INFO - Running setup.py install for manifestparser 08:45:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:45:14 INFO - Running setup.py install for mozcrash 08:45:14 INFO - Running setup.py install for mozdebug 08:45:14 INFO - Running setup.py install for mozdevice 08:45:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:45:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:45:14 INFO - Running setup.py install for mozfile 08:45:15 INFO - Running setup.py install for mozhttpd 08:45:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:45:15 INFO - Running setup.py install for mozinfo 08:45:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:45:15 INFO - Running setup.py install for mozInstall 08:45:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:45:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:45:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:45:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:45:15 INFO - Running setup.py install for mozleak 08:45:15 INFO - Running setup.py install for mozlog 08:45:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:45:15 INFO - Running setup.py install for moznetwork 08:45:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:45:15 INFO - Running setup.py install for mozprocess 08:45:16 INFO - Running setup.py install for mozprofile 08:45:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:45:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:45:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:45:16 INFO - Running setup.py install for mozrunner 08:45:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:45:16 INFO - Running setup.py install for mozscreenshot 08:45:16 INFO - Running setup.py install for moztest 08:45:16 INFO - Running setup.py install for mozversion 08:45:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:45:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:45:16 INFO - Cleaning up... 08:45:16 INFO - Return code: 0 08:45:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:45:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:16 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 0x1076b4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076b7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda15d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492d30>, '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 0x1073b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107487be8>, '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.2OllZD6rYa/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.4s2wnBuUGD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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:45:16 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:45:16 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:45:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2OllZD6rYa/Render', 08:45:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:16 INFO - 'HOME': '/Users/cltbld', 08:45:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:16 INFO - 'LOGNAME': 'cltbld', 08:45:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:16 INFO - 'MOZ_NO_REMOTE': '1', 08:45:16 INFO - 'NO_EM_RESTART': '1', 08:45:16 INFO - 'PAGER': '/bin/cat', 08:45:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:16 INFO - 'PWD': '/builds/slave/test', 08:45:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:16 INFO - 'SHELL': '/bin/bash', 08:45:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2wnBuUGD/Listeners', 08:45:16 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:45:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:16 INFO - 'USER': 'cltbld', 08:45:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:16 INFO - 'XPC_FLAGS': '0x0', 08:45:16 INFO - 'XPC_SERVICE_NAME': '0', 08:45:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:45:17 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-X_28p7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:45:17 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:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:45:17 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-QBKXD3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:45:17 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:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:45:17 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-GXhgYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:45:17 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:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:45:17 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-3rM1qe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:45:17 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:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:45:17 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-P5Ger5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:45:17 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:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:45:17 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-LU6gzu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:45:17 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:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:45:17 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-MboadU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:45:17 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:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:45:17 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-_bwAcO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:45:18 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:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:45:18 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-M64eMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:45:18 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:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:45:18 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-HpDnYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:45:18 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:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:45:18 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-xv4b7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:45:18 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:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:45:18 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-2sYOYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:45:18 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:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:45:18 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-BFHcjP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:45:18 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:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:45:18 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-TkotSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:45:18 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:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:18 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-E7cjek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:45:18 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:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:45:18 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-7k_2ub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:45:18 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:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:45:18 INFO - Running setup.py (path:/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/pip-AM6xyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:45:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:45:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:45:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:45:20 INFO - Downloading blessings-1.6.tar.gz 08:45:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:45:20 INFO - Installing collected packages: blessings 08:45:20 INFO - Running setup.py install for blessings 08:45:20 INFO - Successfully installed blessings 08:45:20 INFO - Cleaning up... 08:45:20 INFO - Return code: 0 08:45:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:45:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:21 INFO - Reading from file tmpfile_stdout 08:45:21 INFO - Current package versions: 08:45:21 INFO - blessings == 1.6 08:45:21 INFO - blobuploader == 1.2.4 08:45:21 INFO - docopt == 0.6.1 08:45:21 INFO - functools32 == 3.2.3-2 08:45:21 INFO - jsonschema == 2.5.1 08:45:21 INFO - manifestparser == 1.1 08:45:21 INFO - mozInstall == 1.12 08:45:21 INFO - mozcrash == 1.0 08:45:21 INFO - mozdebug == 0.1 08:45:21 INFO - mozdevice == 0.49 08:45:21 INFO - mozfile == 1.2 08:45:21 INFO - mozhttpd == 0.7 08:45:21 INFO - mozinfo == 0.9 08:45:21 INFO - mozleak == 0.1 08:45:21 INFO - mozlog == 3.4 08:45:21 INFO - moznetwork == 0.27 08:45:21 INFO - mozprocess == 0.25 08:45:21 INFO - mozprofile == 0.28 08:45:21 INFO - mozrunner == 6.13 08:45:21 INFO - mozscreenshot == 0.1 08:45:21 INFO - mozsystemmonitor == 0.3 08:45:21 INFO - moztest == 0.8 08:45:21 INFO - mozversion == 1.4 08:45:21 INFO - psutil == 3.1.1 08:45:21 INFO - requests == 1.2.3 08:45:21 INFO - wsgiref == 0.1.2 08:45:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:45:21 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:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:21 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:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:21 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 0x1076b4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076b7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda15d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492d30>, '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 0x1073b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107487be8>, '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.2OllZD6rYa/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.4s2wnBuUGD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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:45:21 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:45:21 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:45:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2OllZD6rYa/Render', 08:45:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:21 INFO - 'HOME': '/Users/cltbld', 08:45:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:21 INFO - 'LOGNAME': 'cltbld', 08:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:21 INFO - 'MOZ_NO_REMOTE': '1', 08:45:21 INFO - 'NO_EM_RESTART': '1', 08:45:21 INFO - 'PAGER': '/bin/cat', 08:45:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:21 INFO - 'PWD': '/builds/slave/test', 08:45:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:21 INFO - 'SHELL': '/bin/bash', 08:45:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2wnBuUGD/Listeners', 08:45:21 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:45:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:21 INFO - 'USER': 'cltbld', 08:45:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:21 INFO - 'XPC_FLAGS': '0x0', 08:45:21 INFO - 'XPC_SERVICE_NAME': '0', 08:45:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:21 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:45:21 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:45:21 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:45:21 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:45:21 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:45:21 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:45:21 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:45:21 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:45:21 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:45:21 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:45:21 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:45:21 INFO - Cleaning up... 08:45:21 INFO - Return code: 0 08:45:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:45:21 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:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:45:21 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:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:45:21 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 0x1076b4b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1076b7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffda15d9f20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107492d30>, '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 0x1073b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107487be8>, '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.2OllZD6rYa/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.4s2wnBuUGD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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:45:21 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:45:21 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:45:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2OllZD6rYa/Render', 08:45:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:21 INFO - 'HOME': '/Users/cltbld', 08:45:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:21 INFO - 'LOGNAME': 'cltbld', 08:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:21 INFO - 'MOZ_NO_REMOTE': '1', 08:45:21 INFO - 'NO_EM_RESTART': '1', 08:45:21 INFO - 'PAGER': '/bin/cat', 08:45:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:21 INFO - 'PWD': '/builds/slave/test', 08:45:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:21 INFO - 'SHELL': '/bin/bash', 08:45:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2wnBuUGD/Listeners', 08:45:21 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:45:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:21 INFO - 'USER': 'cltbld', 08:45:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:21 INFO - 'XPC_FLAGS': '0x0', 08:45:21 INFO - 'XPC_SERVICE_NAME': '0', 08:45:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:45:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:45:21 INFO - Cleaning up... 08:45:21 INFO - Return code: 0 08:45:21 INFO - Running post-action listener: _resource_record_post_action 08:45:21 INFO - Running post-action listener: _start_resource_monitoring 08:45:21 INFO - Starting resource monitoring. 08:45:21 INFO - [mozharness: 2017-05-23 15:45:21.701679Z] Finished create-virtualenv step (success) 08:45:21 INFO - [mozharness: 2017-05-23 15:45:21.702679Z] Running install step. 08:45:21 INFO - Running pre-action listener: _resource_record_pre_action 08:45:21 INFO - Running main action method: install 08:45:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:45:21 INFO - Reading from file tmpfile_stdout 08:45:21 INFO - Detecting whether we're running mozinstall >=1.0... 08:45:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:45:22 INFO - Reading from file tmpfile_stdout 08:45:22 INFO - Output received: 08:45:22 INFO - Usage: mozinstall [options] installer 08:45:22 INFO - Options: 08:45:22 INFO - -h, --help show this help message and exit 08:45:22 INFO - -d DEST, --destination=DEST 08:45:22 INFO - Directory to install application into. [default: 08:45:22 INFO - "/builds/slave/test"] 08:45:22 INFO - --app=APP Application being installed. [default: firefox] 08:45:22 INFO - mkdir: /builds/slave/test/build/application 08:45:22 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:45:22 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:45:36 INFO - Reading from file tmpfile_stdout 08:45:36 INFO - Output received: 08:45:36 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 08:45:36 INFO - Running post-action listener: _resource_record_post_action 08:45:36 INFO - [mozharness: 2017-05-23 15:45:36.872463Z] Finished install step (success) 08:45:36 INFO - [mozharness: 2017-05-23 15:45:36.872597Z] Running run-tests step. 08:45:36 INFO - Running pre-action listener: _resource_record_pre_action 08:45:36 INFO - Running main action method: run_tests 08:45:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:45:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:45:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:45:36 INFO - 2017-05-23 08:45:36.886 screenresolution[1628:7313] starting screenresolution argv=screenresolution get 08:45:36 INFO - 2017-05-23 08:45:36.889 screenresolution[1628:7313] Display 0: 1600x1200x32@60 08:45:36 INFO - 2017-05-23 08:45:36.895 screenresolution[1629:7315] starting screenresolution argv=screenresolution list 08:45:36 INFO - Available Modes on Display 0 08:45:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:45:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:45:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:45:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:45:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:45:37 INFO - Intel Iris: 08:45:37 INFO - Chipset Model: Intel Iris 08:45:37 INFO - Type: GPU 08:45:37 INFO - Bus: Built-In 08:45:37 INFO - VRAM (Dynamic, Max): 1536 MB 08:45:37 INFO - Vendor: Intel (0x8086) 08:45:37 INFO - Device ID: 0x0a2e 08:45:37 INFO - Revision ID: 0x0009 08:45:37 INFO - Displays: 08:45:37 INFO - Display: 08:45:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:45:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:45:37 INFO - Display Serial Number: 1600x1200 60 08:45:37 INFO - Main Display: Yes 08:45:37 INFO - Mirror: Off 08:45:37 INFO - Online: Yes 08:45:37 INFO - Rotation: Supported 08:45:37 INFO - Return code: 0 08:45:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:45:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:45:37 INFO - Python 2.7.3 08:45:37 INFO - Return code: 0 08:45:37 INFO - grabbing minidump binary from tooltool 08:45:37 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:45:37 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 0x107492d30>, '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 0x1073b9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107487be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:45:37 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:45:37 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:45:37 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:45:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:45:37 INFO - Return code: 0 08:45:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:45:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:45:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:45:37 INFO - ENV: RUST_BACKTRACE is now 1 08:45:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:45:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:45:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--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:45:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0263 --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:45:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.2OllZD6rYa/Render', 08:45:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:45:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:45:37 INFO - 'HOME': '/Users/cltbld', 08:45:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:45:37 INFO - 'LOGNAME': 'cltbld', 08:45:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:45:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:45:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:45:37 INFO - 'MOZ_NO_REMOTE': '1', 08:45:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:45:37 INFO - 'NO_EM_RESTART': '1', 08:45:37 INFO - 'PAGER': '/bin/cat', 08:45:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:45:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:45:37 INFO - 'PWD': '/builds/slave/test', 08:45:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:45:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:45:37 INFO - 'RUST_BACKTRACE': '1', 08:45:37 INFO - 'SHELL': '/bin/bash', 08:45:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4s2wnBuUGD/Listeners', 08:45:37 INFO - 'TMPDIR': '/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/', 08:45:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:45:37 INFO - 'USER': 'cltbld', 08:45:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:45:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:45:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:45:37 INFO - 'XPC_FLAGS': '0x0', 08:45:37 INFO - 'XPC_SERVICE_NAME': '0', 08:45:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:45:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0263', '--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:45:37 INFO - mozversion application_buildid: 20170523074624 08:45:37 INFO - mozversion application_changeset: 279acccff1c12f047565b465286fcbcc180c5ab0 08:45:37 INFO - mozversion application_display_name: Firefox 08:45:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:45:37 INFO - mozversion application_name: Firefox 08:45:37 INFO - mozversion application_remotingname: firefox 08:45:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 08:45:37 INFO - mozversion application_vendor: Mozilla 08:45:37 INFO - mozversion application_version: 54.0 08:45:37 INFO - mozversion platform_buildid: 20170523074624 08:45:37 INFO - mozversion platform_changeset: 279acccff1c12f047565b465286fcbcc180c5ab0 08:45:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 08:45:37 INFO - mozversion platform_version: 54.0 08:45:37 INFO - using testdate: 1495554337 08:45:37 INFO - actual date: 1495554337 08:45:37 INFO - starting webserver on 'localhost:49221' 08:45:37 INFO - SUITE-START | Running 2 tests 08:45:37 INFO - TEST-START | dromaeo_css 08:45:37 INFO - operating with platform_type : mac_ 08:45:37 INFO - Initialising browser for dromaeo_css test... 08:45:37 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmp7PwLpF/profile http://localhost:49221/getInfo.html) 08:45:39 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 08:45:39 INFO - PROCESS | 1639 | colorDepth:24 08:45:39 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 08:45:39 INFO - PROCESS | 1639 | __metrics 08:45:40 INFO - PROCESS | 1639 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:45:40 INFO - PROCESS | 1639 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:45:40 INFO - TEST-INFO | 1639: exit 0 08:45:40 INFO - Browser initialized. 08:45:40 INFO - Running cycle 1/1 for dromaeo_css test... 08:45:40 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmp7PwLpF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:45:52 INFO - PROCESS | 1642 | RSS: Main: 164978688 08:45:52 INFO - PROCESS | 1642 | 08:48:58 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49221/tests/dromaeo/cssquery-ext.html) 08:48:59 INFO - PROCESS | 1642 | RSS: Main: 285097984 08:48:59 INFO - PROCESS | 1642 | 08:52:02 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-ext.html (next: http://localhost:49221/tests/dromaeo/cssquery-jquery.html) 08:52:03 INFO - PROCESS | 1642 | RSS: Main: 301932544 08:52:03 INFO - PROCESS | 1642 | 08:55:05 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49221/tests/dromaeo/cssquery-mootools.html) 08:55:06 INFO - PROCESS | 1642 | RSS: Main: 280530944 08:55:06 INFO - PROCESS | 1642 | 08:58:08 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49221/tests/dromaeo/cssquery-prototype.html) 08:58:08 INFO - PROCESS | 1642 | RSS: Main: 296251392 08:58:08 INFO - PROCESS | 1642 | 09:01:12 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49221/tests/dromaeo/cssquery-yui.html) 09:01:13 INFO - PROCESS | 1642 | RSS: Main: 269336576 09:01:13 INFO - PROCESS | 1642 | 09:04:16 INFO - PROCESS | 1642 | Cycle 1(1): loaded http://localhost:49221/tests/dromaeo/cssquery-yui.html (next: http://localhost:49221/tests/dromaeo/cssquery-dojo.html) 09:04:16 INFO - PROCESS | 1642 | RSS: Main: 416751616 09:04:16 INFO - PROCESS | 1642 | 09:04:16 INFO - PROCESS | 1642 | __start_tp_report 09:04:16 INFO - PROCESS | 1642 | _x_x_mozilla_page_load 09:04:16 INFO - PROCESS | 1642 | _x_x_mozilla_page_load_details 09:04:16 INFO - PROCESS | 1642 | |i|pagename|runs| 09:04:16 INFO - PROCESS | 1642 | |0;dojo.html;3523.929521409572;3545.574531056273;3545.858165673372;3582.1940063485736;3607.801570913599;6045.03279475284;6082.604630699001;6087.847803804907;6117.357677443865;6133.50931925446;6086;6130.693465326727;6138.263408390988;6155.784547540837;6158.0455029470395;5483.862903427416;5516.806911758089;5524.806631767889;5548.55611551077;5643.717814109278;5383.703896285722;5399.028174928511;5454.890902181954;5619.719014049281;5629.774809007635;5487.807926722566;5506.119020956648;5506.339239291291;5523.59148417153;5526.557875369981;5484.533814625762;5495.6427832191175;5508.862278443031;5517.917231241495;5518.2826232589705;5328.973355133199;5446.2647542582035;5455.18172274158;5461.15352120422;5467.152591348347;6100.3389628629675;6110.92330025654;6116.609690437446;6142.1093941378695;6144.84637884052;6359.236891573041;6423.4861211103;6439.420452159281;6540.6729663516635;6550.344965503459;6615.569987950816;6618;6624.370684784938;6624.53628246023;6631.635260060695;6214.316425193225;6245.406686364788;6249.125122482856;6255.343188965138;6274.090256912722;6442.581232219891;6475.935240647627;6479.319671434526;6484.481241500669;6493;6158.291796443376;6303.590266632654;6328.936710632835;6349.904751428732;6354.9514330135935;6600.339966003457;6618.238902526271;6626.503012274099;6626.801195964129;6638.834029149213;6491.318411566717;6501;6508.251551071593;6513.088167656532;6513.869722605521;5373.596980226499;6255.56211065221;6453.580517266362;6511.641859697762;6602.834929126714;5190.11767999441;5197.272381866542;5203.843884683465;5207.505286997729;5207.921881171786;4634.629229661619;4645.651576131736;4662.207424737802;4664.55686709762;4686.086213188463;6081.817545473643;6109.404666050326;6115.877682446326;6125.418085281891;6134.9079763803575;6520.5435619507125;6570.474362051024;6580.341965803477;6581.407673309425;6595.901061484082;6191.133241346215;6203.31763506014;6226.377362263737;6233.594816337014;6236.656983865841;5560.749766260528;5569.136783798518;5653.152027195872;5696.686682232517;5727.3699893011735;6602.636854973022;6616.106825578581;6624.867502650017;6625.105610742488;6629.005649152666;5728.455796699307;6377.840553986188;6391.96804015977;6398.90401643985;6494.9025764612625;6819.659017049028;6819.829504262235;6839.6580170990255;6851.828704282433;6854.383105520527;6457.773977910751;6477.805665830033;6479.870402591828;6495.317991610813;6534.346565343428;6115.94015414234;6339.429451349401;6571.34286571339;6583;6596.076549283201;5358.22305765654;6371.904421433589;6372.458341041063;6376.585521941059;6391.77628783009;6308.968455157695;6315.684215789284;6371.96814015927;6387.904181437375;6399.072134540559;6351.587146835625;6362.809115726535;6388.041793730792;6389.201349831271;6397.424231818973;5295.841124766263;6283.8114856554375;6328.335524770017;6368.745250189941;6426.935730642634;6356.872862542631;6378.48972082223;6379.138816259746;6389.297177310399;6390.808275751735 09:04:16 INFO - PROCESS | 1642 | |1;ext.html;581.569638467534;673.6665350651429;713.0445203427408;782.636074225485;786.8819677048446;27810.443791124166;42123.73628791137;42742.358864617054;42795.57204427955;42827;43557.56442435575;43903.78048109763;43913.34129988053;43928.56071439285;44071.77964110183;8099;9574.904250957488;9733.172680322146;9737.172340351079;9742.610295588169;8091.757247282591;12042.63872083839;12249.203801752901;12263.448144833459;12278.877211227862;12029.917307442329;12060.819087713691;12072.517099316017;12079.758404831899;12085.395730213499;11970.880291197063;12015.519379224821;12028.398580071005;12031.51873925042;12043.458044388024;10657.253992220547;10880.238383313172;11645.883541164565;11697.3566453845;11718.062554995666;41299.793501032604;41357.586424135974;41387;41495.7925210375;41512.37731434031;4025.75845449274;4088.223237584879;4099.344104943225;4113.465249517573;4135.834566617332;28631.14106576806;29347.853260733773;30681;30974.380512389853;31326.843365783254;2903.3902880395144;3325.334933013383;3418.3334249821296;3434;3436.381451338758;332494;337350;338620;339707;342902;53997.1900421494;54408.45591544113;54427;54482;54546.727266363414;56421;56533.434665652814;56561.434385655615;56566.434335656115;56636.43363566312;15570;15633.983791053544;15662.686746265012;15665.530034098996;15665.843341566666;10897.291676041032;10995.285306455055;13218.933905330412;13246.801297980537;13265;11041.558337666564;11058.889411105947;11059.281146725438;11059.72350691239;11081.667549973514;4658.6825211693795;5794.768209271666;5796.724720561469;5800.738966746507;5817;1137.2323681514945;1146.3465824479965;1153.233099988515;1161.5760247509597;1193.3078814287692;27113.32216694559;27998.86000569984;28158.43683126326;28362.71637283642;28380.71619283822;1273.1342686972948;1281;1283.4224598930518;1287.2774082814967;1288.3300683644452;14433;14508.274586270643;14744.041637293541;14746.705065898623;14777;448464.75767620956;450091;450169;450505;452219;48920.51079489231;49845.25232121521;58786.70606647025;60044;63702.681486593196;1394.8186735724494;1449.7097583151003;1464.2239613005124;1507.0855887425753;1520.665453600184;29521.85239073791;29704;30594.694053059186;30737.231569210948;31277.8436107818;49632.75183624059;49684.50315496943;49724.751376242886;49779.75110124426;49803.75098124486;464404;465983;466081;466133;467260;35924.82037589795;36479.635203647624;36539.817300913324;36712.08219794527;36713.44929826001;40402.59597403988;42459.575404246796;45757.31364029609;46692;47434.28848567343;31398.52902206423;31517.52723709192;31983.680163199002;32507.512387313735;32522.861699840392;31326.903558375347;31350.372992540477;31415;31893.68106319;32370.83814580912;37273.25453490969;38003.239935201695;38040.239195215385;38072.61927380801;38074.80962595169;30605.540916886708;31507.68492315139;31673.8416307917;32537.511937321433;32788.34423311472;24212.273631791075;25463.745362546877;25531.617025744257;25646;25956.740432595434 09:04:16 INFO - PROCESS | 1642 | |2;jquery.html;431.6546762589927;435.3491530162408;440.5682431217407;447.3915474954063;448.47528391781634;21108.788912110875;21412.89293553532;22045.779542204575;22159.781212033333;22213.2225372112;22241.55516889661;22969.770302296973;22972;22972.08111675531;23241.883790581065;6780.55926364786;6998.195207551146;7048.1542214934025;7119.466040046991;7152;6655.134832471772;6967.442604591621;6968.616726080064;6981.82545436363;6991.930080699179;6903.551269167488;6973.058637084005;7053.858922821541;7124.323189297009;7134;6978.895316570255;7037.437005039585;7073.292670732912;7073.823154421129;7160.749373771893;6920.5847649141215;6926.064981227521;6935.479839012094;6935.479839012094;6935.757248496278;27401.86299068512;27407;27591;27651.893924243006;27890;3003.2642002709476;3049.786514943955;3055.129288152888;3089.212250876035;3206.5350524173864;16376.508704738877;16869;17342.219600118024;17811.910940445345;18239.088045597666;2125.7555381131215;2192.725909261342;2217.3569664797187;2227.3206671965045;2290.2900100968754;139622.60377396198;141101.2944935279;141238.29380853134;143793;146731;17969.910150449163;18367;20744.340452763743;20933.79066209349;20999.37001889946;32579.837100814817;33183.834080829925;33220.66779332224;33222.16944576405;33266.66733332684;10275.332103413255;10364.274500785023;10598.629047983284;10611.77152797921;10777.083947864363;7457.85084298311;8501.914980850112;8854.734357969272;8966.193042626064;8984.820303593891;9456.73038470762;9519.428834269966;9584.712458626253;9626.807463850684;9662.178714809179;3033.6511301200203;3418.5555877735737;3520.894373168809;3670.541182352206;3950.696270230817;919.1735413603063;925.9490728010024;937.3485427627734;940.8259471997702;979.3536266064506;16396.26216820222;19519.90240048819;22457.10171593118;22507.861753133428;22693.659595106088;859.7850537365658;889.6174644902754;925.1812146250617;960.0879164793334;961.7884065505577;11177.44112794357;11450.599229026944;11582.131340149523;11655.417229138679;11682.415879206006;777376.1131194308;785164;788323;799557;818124;159287;160086;160672;161299.32204067794;161702;1242.6520574239228;1294.6633875192517;1340.7251513439917;1345.4550906882728;1345.5021641992525;19562.60874782468;20043.699344509554;20573.485662858548;20582;20597.279095231595;67320;67814.64370712459;67863;68615.65692171507;69305.65347173232;98102.5094874521;98504;98671;98757;101898.49050754699;11666.183367164216;11669.76660466803;11679.591214307458;11771.941140294244;12023.098267630137;13024.674383140497;13435.798463022868;13848.446062157402;14087;14278.42886284537;11624.709382265512;11827.82258266144;11880.405979700807;12170;12214.755704885674;8776.605052772513;10854.131669466266;10995.835062473909;11291.983721465107;11327.433628318717;11833.940830295793;12176.391180440765;12186.817197741864;12246.877531224574;12321.568745093879;11543.71140721455;11770.82343764827;11865.347405892759;12131.75736485283;12182.512699491921;11660;11691.298522088702;11710.648680539256;11992.340421276913;12094.939525302669 09:04:16 INFO - PROCESS | 1642 | |3;mootools.html;1591.9681606367872;1614.5237155039263;1625.6911186874493;1647.1681800690649;1663.2931004323168;10956.726081847957;11685.59100431484;11689.532418703242;11695.181337306392;11711.12166587505;11087.390193539351;11401.543938242481;11419.54321827126;11433.371164585966;11438.313701177934;2477.4549599088173;2520.7479252074736;2527.1028784781415;2529.3929456930387;2531.2659328794625;2453.815963802722;2464.3099932019063;2513.3339664988794;2513.8240323177383;2517.3958250019955;2473.196211231348;2477.380654836291;2499.5375855466796;2516.3960649444093;2533.505966336565;2421.225207933462;2466.099873546161;2468.456939473313;2480.1071614218868;2481.8759062047006;2444.4255599934;2448.7673671001226;2467.580511313081;2479.3305807431893;2483.540544999181;11528.193026488148;11606.187566870323;11621.76756464866;11635.883641163564;11636;6009.729562169713;6189.783357582508;6191.752329906798;6205.565610407273;6209.813705588839;3632.219072899339;3638.6725194732603;3707.5180226570374;3777.112378591029;3856.6529012388737;1268.5940499040312;1894.232835701543;1925.8748181368167;1958.7943265957012;1961.774395944456;14341.78487322691;14570;15001.249937503082;15988;16140.273687684083;4695.859124226278;4850.544836549021;4890.413150421901;4915.778789954389;4917.385326834145;12285.938570307091;12302.446389912475;12330.81503777444;12331.69170770738;12336.50653973831;4370.475542934804;4979.800807967713;5172.922406163911;6023.397660234029;6027;4764.880877978078;5432.91850622241;5559.610827242135;5562.833115006556;5607.186957891083;3633.800140992271;3867;3984.521857377133;4022.557518672944;4080.816363263601;1078.8651418572679;1079.7246702091074;1080.1089101491302;1096.6107032003524;1098.1215027977587;929.5305870535336;958.3722661656626;974.181687382602;985.669800616787;998.9950050249702;5722.828315150552;6411.935880641227;6750.0212469192675;6776.72893084281;6904.378605925491;800.8998875140608;961.8313748795108;998.9400635961865;1020.5101551255439;1056.5140035583563;3855.575886652466;4271.658267338606;4325.502567204749;4335.523092459827;4406.361077643788;13696.315184240748;14495.492657757139;14511.492097776738;14511.927440362731;14513.419463221353;13492.59522214335;13741.862581374258;14417.279136043157;14430.206338651475;14438.855611443962;9508.762280942894;9740.269479789044;9754.317197796086;9818.361806482555;9824.705258842247;6598.208215014229;6820.590764554143;6985.720571177096;7017.333353331425;7027.086478757933;12306.07704422154;12779.36103194855;12784.936075319563;12789.360531973178;12796.552120676104;12740.362981851056;12885.904698100767;13052.934735326262;13062.301900752842;13205.405756740778;9188.035256297993;9387.202087822612;9522.714318570177;9533.952330238582;9546.761330966781;10353.119984801377;10437.843432348369;10495.212859035755;10541.31481453703;10628.309159904582;8971.820563588823;9021.458712477272;9089.318301127574;9114.27085833145;9228.723138305862;8690.78273043179;8794.648214071365;8862.556872156238;8867.98018227912;8942.955285223792;8034.87947680797;8139.267465928095;8238.588070596566;8246.793830154294;8255.917440825515;4998.3002379667605;5756.913646295393;5927;6034.853377858193;6116.5718399711595;5995.16067750524;6015.39846015395;6108.08378743192;6159.23009623797;6269.623822570478 09:04:16 INFO - PROCESS | 1642 | |4;prototype.html;349.51766562144235;376.8002958432032;391.5164771507188;393.221421585261;398.33478091587045;4989.825356112537;5390.380106287778;5396.057419329043;5399.595030372718;5499.450054994499;4844.903101937959;4917.229797272978;5193.909623773849;5246.501582349671;5293;4429.60133587977;4442.777861106948;4443.844735303375;4468.865934021985;4474.507804141542;3848.8645849474474;4482.646610529466;4499.347594598781;4530.932036019462;4536.591706746392;3739.223868662391;4220.97889510552;4423.690341676084;4439.689221754478;4460.776961151945;3991.3877940463635;4165.812538435747;4353.455818022763;4362.520122786491;4378.321360189176;3442.7417943654327;4075.513936498763;4102.425660407545;4140.648044916185;4226.788660566959;4231.201739260832;4246.331147917534;5017.724025178651;5020.548150666457;5329.440408757102;4549.248539312355;5137.768800403954;5154.819581314636;5164.328637277167;5167.612429067797;4444.644428445749;4894.3147959285725;4946.653734238605;5298.417174110845;5838.007538718428;4147.253494371012;4263.91472170558;4532.5694059064335;4599.678022538424;4614.261718125117;5146.845594632167;5280.762365693553;5505.889882202414;6056.424639659225;6069.666168360738;3871.8644847430205;3956.643902048829;4389.861249206832;4445.822167113344;4467.709598876063;3844.36567966288;3945.4279129526058;4563.634909207255;4760.428748550196;4805.05395314898;4711.411073615798;4722.929156062662;4821.373221481185;4905.386826646669;5114.468338172628;4840.467548569654;5181.5595674368105;5613.410591887875;5732.598718089694;5751.785562558362;1380.675541247806;1594.9122798245976;1622.7585896788967;1641.3750387116668;1643.5069479156443;1088.9128869690405;1105.2649987758211;1158.783277558577;1203.4163430736087;1220.2495465288962;4644.698094623838;4943.505649435099;5303.257543943851;5340.599455040888;5347.786088556414;5355.7054362009685;5787.623804452697;5950.940490595125;5959.612625179349;6027.547933904887;3270.1497610620936;4443.488998765119;5129.487051294841;5209.479052094836;5249.265102885599;2675.3380386515155;2794.7842688430596;2870.583765354012;2977.464056469813;3073.9538906916414;5514.724263786875;6040.697965101727;6075.756969721162;6116.3883611638485;6130.160951714557;4627.398438203012;5302.973485132627;5426.782928682887;6100.72546735398;6113.8471538211015;5451.945480545144;6089.238845144357;6150.601265506456;6153.907691384633;6199.907001394983;4932.654714169974;5484.245041586813;5526.723663816873;5968.910466342922;6189.442950134509;5572.832815015556;5872.385094099157;5883.676397798162;5914.704264786829;5916.467517923235;5648.802291919763;5928.466438020599;5978.701064946648;6009.278886533644;6195.57016733038;3849.2493963676816;4520.59314661682;4602.585767280961;4780.880477988078;4811.7834697438;4847.80608775645;5418.864528386822;5657.10486986864;5667.291588551431;5972.283326000907;4815.640336235289;5166.925679220858;5595.07681232592;5745.764660597992;5752.539796816328;5722.74247658848;5739.426057394394;5757.3379061406795;5764.117941029485;5777.711114444345;4597.379353787196;5195.506157044715;5547.722613869371;5592.797548527086;5595.5803314752375;4998.875028124326;5643.322801263875;5664.461876121762;5699.375677931769;5702.6863522506665;4775.856724298276;5174.689518628894;5367.852858856489;5397.487238712316;5700.800471983461 09:04:16 INFO - PROCESS | 1642 | |5;yui.html;756.9772906812798;785.8231897822994;809.4172196018865;902.6975963052378;911.7447114807854;12754.553590624319;16263.674726505478;17303.56741081472;17614.29542818289;17615.943043417403;15640.84359156408;15736.527904162866;15979.600509987256;16190.947588406745;16211.513654590352;999.740327187745;1027.1423361493162;1027.6249169053278;1027.9434631095287;1028.5268776362882;983.4935008470643;1014.0417305646167;1014.371089924248;1014.5231741081717;1015.9644412445566;1064.5688496159069;1067.146282973622;1067.8077945969721;1068.4924660786141;1069.0432063303379;1062.1768129699467;1062.3572738493217;1062.5749700119975;1062.9468526573717;1064.0955188090138;786.9881951770728;789.001912580586;790.5967956342249;791.0191362710198;791.4103992525547;17064.317427302893;17156.313747450087;17203.741943870853;18042.819571804244;18314.542136446562;14632;14851.48019819312;14939;15028.248587570577;15435.999999999887;11905.345206013666;11987.820182697267;12207.084468664796;12247.816282755766;12356.752864942651;3748.887533374003;3797.6012518685693;3837.5394952605866;4085.571015043437;4093.5497095319492;86215;87412;95591;110725.89274107237;119094.4045279768;11623.418829058683;11790.469428875722;11937.164398492023;12099.274043557414;12167.574134905235;24607.63088553673;24829.130980415597;24829.751702483107;24848.875755621106;24859.62710559343;8185;8193.67225310981;8205.220504052106;8309.210624990616;8362.832743345221;4236.031643365292;4375.584319489643;4438.400815889813;4486.551344865553;4587.197240482902;3588.784672919633;3639.636036396339;3656.8171591420323;3695.7043436525014;3711.461838033524;584.2229834320344;649.5063751548762;655.1973832055788;666.0142988377229;667.6494840208832;706.3078183380213;719.1801346465035;734.4821900560092;743.390419855724;758.2190343945745;9390.439061354946;10511.894881051092;10732.248742587944;11012.779744405067;11022.008019278304;691.8754624167598;700.3432375272997;735.3933005270673;745.7389913530221;762.5539059650056;5053.166227572409;5078.847634570895;5110.906371048417;5169.198774190007;5211.9478805211465;102270;103986;104815;105499.89000219952;107349.94875384377;53182.73408633009;90964;94573.0542694578;95818.52090739501;95855;44995.55004449979;51807.48192518102;65892.70536473254;72036;74905.62547187229;9140.908590914272;10331.915148909557;11161.513254111156;11240.887591123985;11392.373419462008;45867.77066114648;45979.54020459887;46573.53426465827;47395;47408;59124.70437647784;60549.69725151522;61298.69350653218;61454.69272653608;62025.75948481095;11316.94341528287;12688.682782930131;13279.203247805162;13351.265680387673;13591.86408135906;14886.032407893452;14948.103113813206;15108.471203507825;15407.380745273107;15479.922600386926;13790.862091378958;13997.197181278696;14066.788998164831;14278.714425711636;14858.182799946107;13807.51673691416;14083.647908802363;14129.434822606981;14627.268636568342;14628.780568291697;13646.658833529242;13657.317134143055;14171.789250812657;14528.445208491736;14682.972191946461;9746.512674366111;9979.559818463622;10193.337433066827;10356.741081473023;10994.560217591206;9658.517074146124;9804.55879485439;11242.66272011841;11641.185117041725;11666.241694289844 09:04:16 INFO - PROCESS | 1642 | __end_tp_report 09:04:16 INFO - PROCESS | 1642 | __start_cc_report 09:04:16 INFO - PROCESS | 1642 | _x_x_mozilla_cycle_collect,1175 09:04:16 INFO - PROCESS | 1642 | __end_cc_report 09:04:16 INFO - PROCESS | 1642 | __startTimestamp1495555456903__endTimestamp 09:04:16 INFO - PROCESS | 1642 | 09:04:16 INFO - PROCESS | 1642 | ------- Summary: start ------- 09:04:16 INFO - PROCESS | 1642 | Number of tests: 6 09:04:16 INFO - PROCESS | 1642 | 09:04:16 INFO - PROCESS | 1642 | [#0] dojo.html Cycles:165 Average:6041.51 Median:6283.81 stddev:666.99 (10.6%) stddev-sans-first:639.12 09:04:16 INFO - PROCESS | 1642 | Values: 3523.9 3545.6 3545.9 3582.2 3607.8 6045.0 6082.6 6087.8 6117.4 6133.5 6086.0 6130.7 6138.3 6155.8 6158.0 5483.9 5516.8 5524.8 5548.6 5643.7 5383.7 5399.0 5454.9 5619.7 5629.8 5487.8 5506.1 5506.3 5523.6 5526.6 5484.5 5495.6 5508.9 5517.9 5518.3 5329.0 5446.3 5455.2 5461.2 5467.2 6100.3 6110.9 6116.6 6142.1 6144.8 6359.2 6423.5 6439.4 6540.7 6550.3 6615.6 6618.0 6624.4 6624.5 6631.6 6214.3 6245.4 6249.1 6255.3 6274.1 6442.6 6475.9 6479.3 6484.5 6493.0 6158.3 6303.6 6328.9 6349.9 6355.0 6600.3 6618.2 6626.5 6626.8 6638.8 6491.3 6501.0 6508.3 6513.1 6513.9 5373.6 6255.6 6453.6 6511.6 6602.8 5190.1 5197.3 5203.8 5207.5 5207.9 4634.6 4645.7 4662.2 4664.6 4686.1 6081.8 6109.4 6115.9 6125.4 6134.9 6520.5 6570.5 6580.3 6581.4 6595.9 6191.1 6203.3 6226.4 6233.6 6236.7 5560.7 5569.1 5653.2 5696.7 5727.4 6602.6 6616.1 6624.9 6625.1 6629.0 5728.5 6377.8 6392.0 6398.9 6494.9 6819.7 6819.8 6839.7 6851.8 6854.4 6457.8 6477.8 6479.9 6495.3 6534.3 6115.9 6339.4 6571.3 6583.0 6596.1 5358.2 6371.9 6372.5 6376.6 6391.8 6309.0 6315.7 6372.0 6387.9 6399.1 6351.6 6362.8 6388.0 6389.2 6397.4 5295.8 6283.8 6328.3 6368.7 6426.9 6356.9 6378.5 6379.1 6389.3 6390.8 09:04:16 INFO - PROCESS | 1642 | 09:04:16 INFO - PROCESS | 1642 | [#1] ext.html Cycles:180 Average:57001.69 Median:182641.17 stddev:111727.91 (61.2%) stddev-sans-first:111961.03 09:04:16 INFO - PROCESS | 1642 | Values: 581.6 673.7 713.0 782.6 786.9 27810.4 42123.7 42742.4 42795.6 42827.0 43557.6 43903.8 43913.3 43928.6 44071.8 8099.0 9574.9 9733.2 9737.2 9742.6 8091.8 12042.6 12249.2 12263.4 12278.9 12029.9 12060.8 12072.5 12079.8 12085.4 11970.9 12015.5 12028.4 12031.5 12043.5 10657.3 10880.2 11645.9 11697.4 11718.1 41299.8 41357.6 41387.0 41495.8 41512.4 4025.8 4088.2 4099.3 4113.5 4135.8 28631.1 29347.9 30681.0 30974.4 31326.8 2903.4 3325.3 3418.3 3434.0 3436.4 332494.0 337350.0 338620.0 339707.0 342902.0 53997.2 54408.5 54427.0 54482.0 54546.7 56421.0 56533.4 56561.4 56566.4 56636.4 15570.0 15634.0 15662.7 15665.5 15665.8 10897.3 10995.3 13218.9 13246.8 13265.0 11041.6 11058.9 11059.3 11059.7 11081.7 4658.7 5794.8 5796.7 5800.7 5817.0 1137.2 1146.3 1153.2 1161.6 1193.3 27113.3 27998.9 28158.4 28362.7 28380.7 1273.1 1281.0 1283.4 1287.3 1288.3 14433.0 14508.3 14744.0 14746.7 14777.0 448464.8 450091.0 450169.0 450505.0 452219.0 48920.5 49845.3 58786.7 60044.0 63702.7 1394.8 1449.7 1464.2 1507.1 1520.7 29521.9 29704.0 30594.7 30737.2 31277.8 49632.8 49684.5 49724.8 49779.8 49803.8 464404.0 465983.0 466081.0 466133.0 467260.0 35924.8 36479.6 36539.8 36712.1 36713.4 40402.6 42459.6 45757.3 46692.0 47434.3 31398.5 31517.5 31983.7 32507.5 32522.9 31326.9 31350.4 31415.0 31893.7 32370.8 37273.3 38003.2 38040.2 38072.6 38074.8 30605.5 31507.7 31673.8 32537.5 32788.3 24212.3 25463.7 25531.6 25646.0 25956.7 09:04:16 INFO - PROCESS | 1642 | 09:04:16 INFO - PROCESS | 1642 | [#2] jquery.html Cycles:180 Average:44977.05 Median:12630.03 stddev:132027.38 (1045.3%) stddev-sans-first:132355.38 09:04:16 INFO - PROCESS | 1642 | Values: 431.7 435.3 440.6 447.4 448.5 21108.8 21412.9 22045.8 22159.8 22213.2 22241.6 22969.8 22972.0 22972.1 23241.9 6780.6 6998.2 7048.2 7119.5 7152.0 6655.1 6967.4 6968.6 6981.8 6991.9 6903.6 6973.1 7053.9 7124.3 7134.0 6978.9 7037.4 7073.3 7073.8 7160.7 6920.6 6926.1 6935.5 6935.5 6935.8 27401.9 27407.0 27591.0 27651.9 27890.0 3003.3 3049.8 3055.1 3089.2 3206.5 16376.5 16869.0 17342.2 17811.9 18239.1 2125.8 2192.7 2217.4 2227.3 2290.3 139622.6 141101.3 141238.3 143793.0 146731.0 17969.9 18367.0 20744.3 20933.8 20999.4 32579.8 33183.8 33220.7 33222.2 33266.7 10275.3 10364.3 10598.6 10611.8 10777.1 7457.9 8501.9 8854.7 8966.2 8984.8 9456.7 9519.4 9584.7 9626.8 9662.2 3033.7 3418.6 3520.9 3670.5 3950.7 919.2 925.9 937.3 940.8 979.4 16396.3 19519.9 22457.1 22507.9 22693.7 859.8 889.6 925.2 960.1 961.8 11177.4 11450.6 11582.1 11655.4 11682.4 777376.1 785164.0 788323.0 799557.0 818124.0 159287.0 160086.0 160672.0 161299.3 161702.0 1242.7 1294.7 1340.7 1345.5 1345.5 19562.6 20043.7 20573.5 20582.0 20597.3 67320.0 67814.6 67863.0 68615.7 69305.7 98102.5 98504.0 98671.0 98757.0 101898.5 11666.2 11669.8 11679.6 11771.9 12023.1 13024.7 13435.8 13848.4 14087.0 14278.4 11624.7 11827.8 11880.4 12170.0 12214.8 8776.6 10854.1 10995.8 11292.0 11327.4 11833.9 12176.4 12186.8 12246.9 12321.6 11543.7 11770.8 11865.3 12131.8 12182.5 11660.0 11691.3 11710.6 11992.3 12094.9 09:04:16 INFO - PROCESS | 1642 | 09:04:16 INFO - PROCESS | 1642 | [#3] mootools.html Cycles:180 Average:6935.77 Median:2532.39 stddev:4333.90 (171.1%) stddev-sans-first:4327.46 09:04:16 INFO - PROCESS | 1642 | Values: 1592.0 1614.5 1625.7 1647.2 1663.3 10956.7 11685.6 11689.5 11695.2 11711.1 11087.4 11401.5 11419.5 11433.4 11438.3 2477.5 2520.7 2527.1 2529.4 2531.3 2453.8 2464.3 2513.3 2513.8 2517.4 2473.2 2477.4 2499.5 2516.4 2533.5 2421.2 2466.1 2468.5 2480.1 2481.9 2444.4 2448.8 2467.6 2479.3 2483.5 11528.2 11606.2 11621.8 11635.9 11636.0 6009.7 6189.8 6191.8 6205.6 6209.8 3632.2 3638.7 3707.5 3777.1 3856.7 1268.6 1894.2 1925.9 1958.8 1961.8 14341.8 14570.0 15001.2 15988.0 16140.3 4695.9 4850.5 4890.4 4915.8 4917.4 12285.9 12302.4 12330.8 12331.7 12336.5 4370.5 4979.8 5172.9 6023.4 6027.0 4764.9 5432.9 5559.6 5562.8 5607.2 3633.8 3867.0 3984.5 4022.6 4080.8 1078.9 1079.7 1080.1 1096.6 1098.1 929.5 958.4 974.2 985.7 999.0 5722.8 6411.9 6750.0 6776.7 6904.4 800.9 961.8 998.9 1020.5 1056.5 3855.6 4271.7 4325.5 4335.5 4406.4 13696.3 14495.5 14511.5 14511.9 14513.4 13492.6 13741.9 14417.3 14430.2 14438.9 9508.8 9740.3 9754.3 9818.4 9824.7 6598.2 6820.6 6985.7 7017.3 7027.1 12306.1 12779.4 12784.9 12789.4 12796.6 12740.4 12885.9 13052.9 13062.3 13205.4 9188.0 9387.2 9522.7 9534.0 9546.8 10353.1 10437.8 10495.2 10541.3 10628.3 8971.8 9021.5 9089.3 9114.3 9228.7 8690.8 8794.6 8862.6 8868.0 8943.0 8034.9 8139.3 8238.6 8246.8 8255.9 4998.3 5756.9 5927.0 6034.9 6116.6 5995.2 6015.4 6108.1 6159.2 6269.6 09:04:16 INFO - PROCESS | 1642 | 09:04:16 INFO - PROCESS | 1642 | [#4] prototype.html Cycles:180 Average:4705.67 Median:5019.14 stddev:1339.98 (26.7%) stddev-sans-first:1303.24 09:04:16 INFO - PROCESS | 1642 | Values: 349.5 376.8 391.5 393.2 398.3 4989.8 5390.4 5396.1 5399.6 5499.5 4844.9 4917.2 5193.9 5246.5 5293.0 4429.6 4442.8 4443.8 4468.9 4474.5 3848.9 4482.6 4499.3 4530.9 4536.6 3739.2 4221.0 4423.7 4439.7 4460.8 3991.4 4165.8 4353.5 4362.5 4378.3 3442.7 4075.5 4102.4 4140.6 4226.8 4231.2 4246.3 5017.7 5020.5 5329.4 4549.2 5137.8 5154.8 5164.3 5167.6 4444.6 4894.3 4946.7 5298.4 5838.0 4147.3 4263.9 4532.6 4599.7 4614.3 5146.8 5280.8 5505.9 6056.4 6069.7 3871.9 3956.6 4389.9 4445.8 4467.7 3844.4 3945.4 4563.6 4760.4 4805.1 4711.4 4722.9 4821.4 4905.4 5114.5 4840.5 5181.6 5613.4 5732.6 5751.8 1380.7 1594.9 1622.8 1641.4 1643.5 1088.9 1105.3 1158.8 1203.4 1220.2 4644.7 4943.5 5303.3 5340.6 5347.8 5355.7 5787.6 5950.9 5959.6 6027.5 3270.1 4443.5 5129.5 5209.5 5249.3 2675.3 2794.8 2870.6 2977.5 3074.0 5514.7 6040.7 6075.8 6116.4 6130.2 4627.4 5303.0 5426.8 6100.7 6113.8 5451.9 6089.2 6150.6 6153.9 6199.9 4932.7 5484.2 5526.7 5968.9 6189.4 5572.8 5872.4 5883.7 5914.7 5916.5 5648.8 5928.5 5978.7 6009.3 6195.6 3849.2 4520.6 4602.6 4780.9 4811.8 4847.8 5418.9 5657.1 5667.3 5972.3 4815.6 5166.9 5595.1 5745.8 5752.5 5722.7 5739.4 5757.3 5764.1 5777.7 4597.4 5195.5 5547.7 5592.8 5595.6 4998.9 5643.3 5664.5 5699.4 5702.7 4775.9 5174.7 5367.9 5397.5 5700.8 09:04:16 INFO - PROCESS | 1642 | 09:04:16 INFO - PROCESS | 1642 | [#5] yui.html Cycles:180 Average:19997.10 Median:17615.12 stddev:27874.53 (158.2%) stddev-sans-first:27915.29 09:04:16 INFO - PROCESS | 1642 | Values: 757.0 785.8 809.4 902.7 911.7 12754.6 16263.7 17303.6 17614.3 17615.9 15640.8 15736.5 15979.6 16190.9 16211.5 999.7 1027.1 1027.6 1027.9 1028.5 983.5 1014.0 1014.4 1014.5 1016.0 1064.6 1067.1 1067.8 1068.5 1069.0 1062.2 1062.4 1062.6 1062.9 1064.1 787.0 789.0 790.6 791.0 791.4 17064.3 17156.3 17203.7 18042.8 18314.5 14632.0 14851.5 14939.0 15028.2 15436.0 11905.3 11987.8 12207.1 12247.8 12356.8 3748.9 3797.6 3837.5 4085.6 4093.5 86215.0 87412.0 95591.0 110725.9 119094.4 11623.4 11790.5 11937.2 12099.3 12167.6 24607.6 24829.1 24829.8 24848.9 24859.6 8185.0 8193.7 8205.2 8309.2 8362.8 4236.0 4375.6 4438.4 4486.6 4587.2 3588.8 3639.6 3656.8 3695.7 3711.5 584.2 649.5 655.2 666.0 667.6 706.3 719.2 734.5 743.4 758.2 9390.4 10511.9 10732.2 11012.8 11022.0 691.9 700.3 735.4 745.7 762.6 5053.2 5078.8 5110.9 5169.2 5211.9 102270.0 103986.0 104815.0 105499.9 107349.9 53182.7 90964.0 94573.1 95818.5 95855.0 44995.6 51807.5 65892.7 72036.0 74905.6 9140.9 10331.9 11161.5 11240.9 11392.4 45867.8 45979.5 46573.5 47395.0 47408.0 59124.7 60549.7 61298.7 61454.7 62025.8 11316.9 12688.7 13279.2 13351.3 13591.9 14886.0 14948.1 15108.5 15407.4 15479.9 13790.9 13997.2 14066.8 14278.7 14858.2 13807.5 14083.6 14129.4 14627.3 14628.8 13646.7 13657.3 14171.8 14528.4 14683.0 9746.5 9979.6 10193.3 10356.7 10994.6 9658.5 9804.6 11242.7 11641.2 11666.2 09:04:16 INFO - PROCESS | 1642 | -------- Summary: end -------- 09:04:16 INFO - PROCESS | 1642 | 09:04:17 INFO - TEST-INFO | 1642: exit 0 09:04:17 INFO - TEST-OK | dromaeo_css | took 1120006ms 09:04:17 INFO - TEST-START | kraken 09:04:17 INFO - operating with platform_type : mac_ 09:04:17 INFO - Initialising browser for kraken test... 09:04:17 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpeXJBWb/profile http://localhost:49221/getInfo.html) 09:04:19 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 09:04:19 INFO - PROCESS | 1652 | colorDepth:24 09:04:19 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 09:04:19 INFO - PROCESS | 1652 | __metrics 09:04:19 INFO - PROCESS | 1652 | 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" 09:04:19 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:04:20 INFO - TEST-INFO | 1652: exit 0 09:04:20 INFO - Browser initialized. 09:04:20 INFO - Running cycle 1/1 for kraken test... 09:04:20 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/T/tmpeXJBWb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:04:32 INFO - PROCESS | 1654 | RSS: Main: 165027840 09:04:32 INFO - PROCESS | 1654 | 09:04:35 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection) 09:04:36 INFO - PROCESS | 1654 | RSS: Main: 216006656 09:04:36 INFO - PROCESS | 1654 | 09:04:38 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-dft) 09:04:39 INFO - PROCESS | 1654 | RSS: Main: 231493632 09:04:39 INFO - PROCESS | 1654 | 09:04:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-fft) 09:04:43 INFO - PROCESS | 1654 | RSS: Main: 327782400 09:04:43 INFO - PROCESS | 1654 | 09:04:44 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator) 09:04:45 INFO - PROCESS | 1654 | RSS: Main: 229392384 09:04:45 INFO - PROCESS | 1654 | 09:04:47 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:04:48 INFO - PROCESS | 1654 | RSS: Main: 232153088 09:04:48 INFO - PROCESS | 1654 | 09:04:51 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom) 09:04:51 INFO - PROCESS | 1654 | RSS: Main: 271876096 09:04:51 INFO - PROCESS | 1654 | 09:04:54 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate) 09:04:55 INFO - PROCESS | 1654 | RSS: Main: 272629760 09:04:55 INFO - PROCESS | 1654 | 09:04:58 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial) 09:04:59 INFO - PROCESS | 1654 | RSS: Main: 271462400 09:04:59 INFO - PROCESS | 1654 | 09:05:00 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:05:01 INFO - PROCESS | 1654 | RSS: Main: 247947264 09:05:01 INFO - PROCESS | 1654 | 09:05:03 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:05:04 INFO - PROCESS | 1654 | RSS: Main: 251359232 09:05:04 INFO - PROCESS | 1654 | 09:05:06 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:05:07 INFO - PROCESS | 1654 | RSS: Main: 255963136 09:05:07 INFO - PROCESS | 1654 | 09:05:09 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:05:10 INFO - PROCESS | 1654 | RSS: Main: 262443008 09:05:10 INFO - PROCESS | 1654 | 09:05:13 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:05:14 INFO - PROCESS | 1654 | RSS: Main: 263761920 09:05:14 INFO - PROCESS | 1654 | 09:05:15 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49221/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49221/tests/kraken/driver.html?testName=ai-astar) 09:05:16 INFO - PROCESS | 1654 | RSS: Main: 308527104 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | __start_tp_report 09:05:16 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 09:05:16 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 09:05:16 INFO - PROCESS | 1654 | |i|pagename|runs| 09:05:16 INFO - PROCESS | 1654 | |0;ai-astar;103;115;116;116;116;116;116;116;116;114 09:05:16 INFO - PROCESS | 1654 | |1;audio-beat-detection;142;109;109;143;109;108;109;107;127;108 09:05:16 INFO - PROCESS | 1654 | |2;audio-dft;185;183;183;183;181;182;182;182;183;183 09:05:16 INFO - PROCESS | 1654 | |3;audio-fft;83;59;60;92;61;60;61;60;73;59 09:05:16 INFO - PROCESS | 1654 | |4;audio-oscillator;88;70;108;101;100;81;80;69;68;83 09:05:16 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;87;86;87;86;87;87;87;87;88;88 09:05:16 INFO - PROCESS | 1654 | |6;imaging-darkroom;93;108;149;93;93;94;95;94;93;102 09:05:16 INFO - PROCESS | 1654 | |7;imaging-desaturate;83;86;83;83;83;83;97;128;129;84 09:05:16 INFO - PROCESS | 1654 | |8;json-parse-financial;61;62;60;58;68;90;95;91;77;63 09:05:16 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;55;56;65;66;62;59;59;57;74;86 09:05:16 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;85;80;78;91;81;83;81;85;88;108 09:05:16 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;134;135;135;132;133;132;140;137;156;137 09:05:16 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;162;164;169;221;216;167;163;159;162;172 09:05:16 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;61;64;64;63;65;64;64;63;62;62 09:05:16 INFO - PROCESS | 1654 | __end_tp_report 09:05:16 INFO - PROCESS | 1654 | __start_cc_report 09:05:16 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,2076 09:05:16 INFO - PROCESS | 1654 | __end_cc_report 09:05:16 INFO - PROCESS | 1654 | __startTimestamp1495555516019__endTimestamp 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | ------- Summary: start ------- 09:05:16 INFO - PROCESS | 1654 | Number of tests: 14 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:114.40 Median:116.00 stddev:4.06 (3.5%) stddev-sans-first:0.71 09:05:16 INFO - PROCESS | 1654 | Values: 103.0 115.0 116.0 116.0 116.0 116.0 116.0 116.0 116.0 114.0 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:117.10 Median:109.00 stddev:14.60 (13.4%) stddev-sans-first:12.40 09:05:16 INFO - PROCESS | 1654 | Values: 142.0 109.0 109.0 143.0 109.0 108.0 109.0 107.0 127.0 108.0 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:182.70 Median:183.00 stddev:1.06 (0.6%) stddev-sans-first:0.73 09:05:16 INFO - PROCESS | 1654 | Values: 185.0 183.0 183.0 183.0 181.0 182.0 182.0 182.0 183.0 183.0 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:66.80 Median:61.00 stddev:11.85 (19.4%) stddev-sans-first:11.02 09:05:16 INFO - PROCESS | 1654 | Values: 83.0 59.0 60.0 92.0 61.0 60.0 61.0 60.0 73.0 59.0 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:84.80 Median:75.00 stddev:14.27 (19.0%) stddev-sans-first:15.09 09:05:16 INFO - PROCESS | 1654 | Values: 88.0 70.0 108.0 101.0 100.0 81.0 80.0 69.0 68.0 83.0 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:87.00 stddev:0.67 (0.8%) stddev-sans-first:0.71 09:05:16 INFO - PROCESS | 1654 | Values: 87.0 86.0 87.0 86.0 87.0 87.0 87.0 87.0 88.0 88.0 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:101.40 Median:93.00 stddev:17.46 (18.8%) stddev-sans-first:18.25 09:05:16 INFO - PROCESS | 1654 | Values: 93.0 108.0 149.0 93.0 93.0 94.0 95.0 94.0 93.0 102.0 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:83.00 stddev:18.73 (22.6%) stddev-sans-first:19.45 09:05:16 INFO - PROCESS | 1654 | Values: 83.0 86.0 83.0 83.0 83.0 83.0 97.0 128.0 129.0 84.0 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:72.50 Median:72.50 stddev:14.51 (20.0%) stddev-sans-first:14.78 09:05:16 INFO - PROCESS | 1654 | Values: 61.0 62.0 60.0 58.0 68.0 90.0 95.0 91.0 77.0 63.0 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:63.90 Median:63.50 stddev:9.64 (15.2%) stddev-sans-first:9.68 09:05:16 INFO - PROCESS | 1654 | Values: 55.0 56.0 65.0 66.0 62.0 59.0 59.0 57.0 74.0 86.0 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:84.00 stddev:8.65 (10.3%) stddev-sans-first:9.17 09:05:16 INFO - PROCESS | 1654 | Values: 85.0 80.0 78.0 91.0 81.0 83.0 81.0 85.0 88.0 108.0 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:137.10 Median:136.00 stddev:7.09 (5.2%) stddev-sans-first:7.43 09:05:16 INFO - PROCESS | 1654 | Values: 134.0 135.0 135.0 132.0 133.0 132.0 140.0 137.0 156.0 137.0 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.50 Median:168.00 stddev:23.00 (13.7%) stddev-sans-first:23.87 09:05:16 INFO - PROCESS | 1654 | Values: 162.0 164.0 169.0 221.0 216.0 167.0 163.0 159.0 162.0 172.0 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.20 Median:64.00 stddev:1.23 (1.9%) stddev-sans-first:1.01 09:05:16 INFO - PROCESS | 1654 | Values: 61.0 64.0 64.0 63.0 65.0 64.0 64.0 63.0 62.0 62.0 09:05:16 INFO - PROCESS | 1654 | -------- Summary: end -------- 09:05:16 INFO - PROCESS | 1654 | 09:05:16 INFO - TEST-INFO | 1654: exit 0 09:05:16 INFO - TEST-OK | kraken | took 59368ms 09:05:16 INFO - SUITE-END | took 1179s 09:05:17 INFO - Completed test suite (00:19:39) 09:05:17 INFO - javascript benchmark 09:05:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7963.2350716124665, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3523.929521409572, 3545.574531056273, 3545.858165673372, 3582.1940063485736, 3607.801570913599, 6045.03279475284, 6082.604630699001, 6087.847803804907, 6117.357677443865, 6133.50931925446, 6086.0, 6130.693465326727, 6138.263408390988, 6155.784547540837, 6158.0455029470395, 5483.862903427416, 5516.806911758089, 5524.806631767889, 5548.55611551077, 5643.717814109278, 5383.703896285722, 5399.028174928511, 5454.890902181954, 5619.719014049281, 5629.774809007635, 5487.807926722566, 5506.119020956648, 5506.339239291291, 5523.59148417153, 5526.557875369981, 5484.533814625762, 5495.6427832191175, 5508.862278443031, 5517.917231241495, 5518.2826232589705, 5328.973355133199, 5446.2647542582035, 5455.18172274158, 5461.15352120422, 5467.152591348347, 6100.3389628629675, 6110.92330025654, 6116.609690437446, 6142.1093941378695, 6144.84637884052, 6359.236891573041, 6423.4861211103, 6439.420452159281, 6540.6729663516635, 6550.344965503459, 6615.569987950816, 6618.0, 6624.370684784938, 6624.53628246023, 6631.635260060695, 6214.316425193225, 6245.406686364788, 6249.125122482856, 6255.343188965138, 6274.090256912722, 6442.581232219891, 6475.935240647627, 6479.319671434526, 6484.481241500669, 6493.0, 6158.291796443376, 6303.590266632654, 6328.936710632835, 6349.904751428732, 6354.9514330135935, 6600.339966003457, 6618.238902526271, 6626.503012274099, 6626.801195964129, 6638.834029149213, 6491.318411566717, 6501.0, 6508.251551071593, 6513.088167656532, 6513.869722605521, 5373.596980226499, 6255.56211065221, 6453.580517266362, 6511.641859697762, 6602.834929126714, 5190.11767999441, 5197.272381866542, 5203.843884683465, 5207.505286997729, 5207.921881171786, 4634.629229661619, 4645.651576131736, 4662.207424737802, 4664.55686709762, 4686.086213188463, 6081.817545473643, 6109.404666050326, 6115.877682446326, 6125.418085281891, 6134.9079763803575, 6520.5435619507125, 6570.474362051024, 6580.341965803477, 6581.407673309425, 6595.901061484082, 6191.133241346215, 6203.31763506014, 6226.377362263737, 6233.594816337014, 6236.656983865841, 5560.749766260528, 5569.136783798518, 5653.152027195872, 5696.686682232517, 5727.3699893011735, 6602.636854973022, 6616.106825578581, 6624.867502650017, 6625.105610742488, 6629.005649152666, 5728.455796699307, 6377.840553986188, 6391.96804015977, 6398.90401643985, 6494.9025764612625, 6819.659017049028, 6819.829504262235, 6839.6580170990255, 6851.828704282433, 6854.383105520527, 6457.773977910751, 6477.805665830033, 6479.870402591828, 6495.317991610813, 6534.346565343428, 6115.94015414234, 6339.429451349401, 6571.34286571339, 6583.0, 6596.076549283201, 5358.22305765654, 6371.904421433589, 6372.458341041063, 6376.585521941059, 6391.77628783009, 6308.968455157695, 6315.684215789284, 6371.96814015927, 6387.904181437375, 6399.072134540559, 6351.587146835625, 6362.809115726535, 6388.041793730792, 6389.201349831271, 6397.424231818973, 5295.841124766263, 6283.8114856554375, 6328.335524770017, 6368.745250189941, 6426.935730642634, 6356.872862542631, 6378.48972082223, 6379.138816259746, 6389.297177310399, 6390.808275751735], "value": 5999.782977497668, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.569638467534, 673.6665350651429, 713.0445203427408, 782.636074225485, 786.8819677048446, 27810.443791124166, 42123.73628791137, 42742.358864617054, 42795.57204427955, 42827.0, 43557.56442435575, 43903.78048109763, 43913.34129988053, 43928.56071439285, 44071.77964110183, 8099.0, 9574.904250957488, 9733.172680322146, 9737.172340351079, 9742.610295588169, 8091.757247282591, 12042.63872083839, 12249.203801752901, 12263.448144833459, 12278.877211227862, 12029.917307442329, 12060.819087713691, 12072.517099316017, 12079.758404831899, 12085.395730213499, 11970.880291197063, 12015.519379224821, 12028.398580071005, 12031.51873925042, 12043.458044388024, 10657.253992220547, 10880.238383313172, 11645.883541164565, 11697.3566453845, 11718.062554995666, 41299.793501032604, 41357.586424135974, 41387.0, 41495.7925210375, 41512.37731434031, 4025.75845449274, 4088.223237584879, 4099.344104943225, 4113.465249517573, 4135.834566617332, 28631.14106576806, 29347.853260733773, 30681.0, 30974.380512389853, 31326.843365783254, 2903.3902880395144, 3325.334933013383, 3418.3334249821296, 3434.0, 3436.381451338758, 332494.0, 337350.0, 338620.0, 339707.0, 342902.0, 53997.1900421494, 54408.45591544113, 54427.0, 54482.0, 54546.727266363414, 56421.0, 56533.434665652814, 56561.434385655615, 56566.434335656115, 56636.43363566312, 15570.0, 15633.983791053544, 15662.686746265012, 15665.530034098996, 15665.843341566666, 10897.291676041032, 10995.285306455055, 13218.933905330412, 13246.801297980537, 13265.0, 11041.558337666564, 11058.889411105947, 11059.281146725438, 11059.72350691239, 11081.667549973514, 4658.6825211693795, 5794.768209271666, 5796.724720561469, 5800.738966746507, 5817.0, 1137.2323681514945, 1146.3465824479965, 1153.233099988515, 1161.5760247509597, 1193.3078814287692, 27113.32216694559, 27998.86000569984, 28158.43683126326, 28362.71637283642, 28380.71619283822, 1273.1342686972948, 1281.0, 1283.4224598930518, 1287.2774082814967, 1288.3300683644452, 14433.0, 14508.274586270643, 14744.041637293541, 14746.705065898623, 14777.0, 448464.75767620956, 450091.0, 450169.0, 450505.0, 452219.0, 48920.51079489231, 49845.25232121521, 58786.70606647025, 60044.0, 63702.681486593196, 1394.8186735724494, 1449.7097583151003, 1464.2239613005124, 1507.0855887425753, 1520.665453600184, 29521.85239073791, 29704.0, 30594.694053059186, 30737.231569210948, 31277.8436107818, 49632.75183624059, 49684.50315496943, 49724.751376242886, 49779.75110124426, 49803.75098124486, 464404.0, 465983.0, 466081.0, 466133.0, 467260.0, 35924.82037589795, 36479.635203647624, 36539.817300913324, 36712.08219794527, 36713.44929826001, 40402.59597403988, 42459.575404246796, 45757.31364029609, 46692.0, 47434.28848567343, 31398.52902206423, 31517.52723709192, 31983.680163199002, 32507.512387313735, 32522.861699840392, 31326.903558375347, 31350.372992540477, 31415.0, 31893.68106319, 32370.83814580912, 37273.25453490969, 38003.239935201695, 38040.239195215385, 38072.61927380801, 38074.80962595169, 30605.540916886708, 31507.68492315139, 31673.8416307917, 32537.511937321433, 32788.34423311472, 24212.273631791075, 25463.745362546877, 25531.617025744257, 25646.0, 25956.740432595434], "value": 19648.271653981465, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.6546762589927, 435.3491530162408, 440.5682431217407, 447.3915474954063, 448.47528391781634, 21108.788912110875, 21412.89293553532, 22045.779542204575, 22159.781212033333, 22213.2225372112, 22241.55516889661, 22969.770302296973, 22972.0, 22972.08111675531, 23241.883790581065, 6780.55926364786, 6998.195207551146, 7048.1542214934025, 7119.466040046991, 7152.0, 6655.134832471772, 6967.442604591621, 6968.616726080064, 6981.82545436363, 6991.930080699179, 6903.551269167488, 6973.058637084005, 7053.858922821541, 7124.323189297009, 7134.0, 6978.895316570255, 7037.437005039585, 7073.292670732912, 7073.823154421129, 7160.749373771893, 6920.5847649141215, 6926.064981227521, 6935.479839012094, 6935.479839012094, 6935.757248496278, 27401.86299068512, 27407.0, 27591.0, 27651.893924243006, 27890.0, 3003.2642002709476, 3049.786514943955, 3055.129288152888, 3089.212250876035, 3206.5350524173864, 16376.508704738877, 16869.0, 17342.219600118024, 17811.910940445345, 18239.088045597666, 2125.7555381131215, 2192.725909261342, 2217.3569664797187, 2227.3206671965045, 2290.2900100968754, 139622.60377396198, 141101.2944935279, 141238.29380853134, 143793.0, 146731.0, 17969.910150449163, 18367.0, 20744.340452763743, 20933.79066209349, 20999.37001889946, 32579.837100814817, 33183.834080829925, 33220.66779332224, 33222.16944576405, 33266.66733332684, 10275.332103413255, 10364.274500785023, 10598.629047983284, 10611.77152797921, 10777.083947864363, 7457.85084298311, 8501.914980850112, 8854.734357969272, 8966.193042626064, 8984.820303593891, 9456.73038470762, 9519.428834269966, 9584.712458626253, 9626.807463850684, 9662.178714809179, 3033.6511301200203, 3418.5555877735737, 3520.894373168809, 3670.541182352206, 3950.696270230817, 919.1735413603063, 925.9490728010024, 937.3485427627734, 940.8259471997702, 979.3536266064506, 16396.26216820222, 19519.90240048819, 22457.10171593118, 22507.861753133428, 22693.659595106088, 859.7850537365658, 889.6174644902754, 925.1812146250617, 960.0879164793334, 961.7884065505577, 11177.44112794357, 11450.599229026944, 11582.131340149523, 11655.417229138679, 11682.415879206006, 777376.1131194308, 785164.0, 788323.0, 799557.0, 818124.0, 159287.0, 160086.0, 160672.0, 161299.32204067794, 161702.0, 1242.6520574239228, 1294.6633875192517, 1340.7251513439917, 1345.4550906882728, 1345.5021641992525, 19562.60874782468, 20043.699344509554, 20573.485662858548, 20582.0, 20597.279095231595, 67320.0, 67814.64370712459, 67863.0, 68615.65692171507, 69305.65347173232, 98102.5094874521, 98504.0, 98671.0, 98757.0, 101898.49050754699, 11666.183367164216, 11669.76660466803, 11679.591214307458, 11771.941140294244, 12023.098267630137, 13024.674383140497, 13435.798463022868, 13848.446062157402, 14087.0, 14278.42886284537, 11624.709382265512, 11827.82258266144, 11880.405979700807, 12170.0, 12214.755704885674, 8776.605052772513, 10854.131669466266, 10995.835062473909, 11291.983721465107, 11327.433628318717, 11833.940830295793, 12176.391180440765, 12186.817197741864, 12246.877531224574, 12321.568745093879, 11543.71140721455, 11770.82343764827, 11865.347405892759, 12131.75736485283, 12182.512699491921, 11660.0, 11691.298522088702, 11710.648680539256, 11992.340421276913, 12094.939525302669], "value": 11925.470739749308, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1591.9681606367872, 1614.5237155039263, 1625.6911186874493, 1647.1681800690649, 1663.2931004323168, 10956.726081847957, 11685.59100431484, 11689.532418703242, 11695.181337306392, 11711.12166587505, 11087.390193539351, 11401.543938242481, 11419.54321827126, 11433.371164585966, 11438.313701177934, 2477.4549599088173, 2520.7479252074736, 2527.1028784781415, 2529.3929456930387, 2531.2659328794625, 2453.815963802722, 2464.3099932019063, 2513.3339664988794, 2513.8240323177383, 2517.3958250019955, 2473.196211231348, 2477.380654836291, 2499.5375855466796, 2516.3960649444093, 2533.505966336565, 2421.225207933462, 2466.099873546161, 2468.456939473313, 2480.1071614218868, 2481.8759062047006, 2444.4255599934, 2448.7673671001226, 2467.580511313081, 2479.3305807431893, 2483.540544999181, 11528.193026488148, 11606.187566870323, 11621.76756464866, 11635.883641163564, 11636.0, 6009.729562169713, 6189.783357582508, 6191.752329906798, 6205.565610407273, 6209.813705588839, 3632.219072899339, 3638.6725194732603, 3707.5180226570374, 3777.112378591029, 3856.6529012388737, 1268.5940499040312, 1894.232835701543, 1925.8748181368167, 1958.7943265957012, 1961.774395944456, 14341.78487322691, 14570.0, 15001.249937503082, 15988.0, 16140.273687684083, 4695.859124226278, 4850.544836549021, 4890.413150421901, 4915.778789954389, 4917.385326834145, 12285.938570307091, 12302.446389912475, 12330.81503777444, 12331.69170770738, 12336.50653973831, 4370.475542934804, 4979.800807967713, 5172.922406163911, 6023.397660234029, 6027.0, 4764.880877978078, 5432.91850622241, 5559.610827242135, 5562.833115006556, 5607.186957891083, 3633.800140992271, 3867.0, 3984.521857377133, 4022.557518672944, 4080.816363263601, 1078.8651418572679, 1079.7246702091074, 1080.1089101491302, 1096.6107032003524, 1098.1215027977587, 929.5305870535336, 958.3722661656626, 974.181687382602, 985.669800616787, 998.9950050249702, 5722.828315150552, 6411.935880641227, 6750.0212469192675, 6776.72893084281, 6904.378605925491, 800.8998875140608, 961.8313748795108, 998.9400635961865, 1020.5101551255439, 1056.5140035583563, 3855.575886652466, 4271.658267338606, 4325.502567204749, 4335.523092459827, 4406.361077643788, 13696.315184240748, 14495.492657757139, 14511.492097776738, 14511.927440362731, 14513.419463221353, 13492.59522214335, 13741.862581374258, 14417.279136043157, 14430.206338651475, 14438.855611443962, 9508.762280942894, 9740.269479789044, 9754.317197796086, 9818.361806482555, 9824.705258842247, 6598.208215014229, 6820.590764554143, 6985.720571177096, 7017.333353331425, 7027.086478757933, 12306.07704422154, 12779.36103194855, 12784.936075319563, 12789.360531973178, 12796.552120676104, 12740.362981851056, 12885.904698100767, 13052.934735326262, 13062.301900752842, 13205.405756740778, 9188.035256297993, 9387.202087822612, 9522.714318570177, 9533.952330238582, 9546.761330966781, 10353.119984801377, 10437.843432348369, 10495.212859035755, 10541.31481453703, 10628.309159904582, 8971.820563588823, 9021.458712477272, 9089.318301127574, 9114.27085833145, 9228.723138305862, 8690.78273043179, 8794.648214071365, 8862.556872156238, 8867.98018227912, 8942.955285223792, 8034.87947680797, 8139.267465928095, 8238.588070596566, 8246.793830154294, 8255.917440825515, 4998.3002379667605, 5756.913646295393, 5927.0, 6034.853377858193, 6116.5718399711595, 5995.16067750524, 6015.39846015395, 6108.08378743192, 6159.23009623797, 6269.623822570478], "value": 5328.440893916678, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.51766562144235, 376.8002958432032, 391.5164771507188, 393.221421585261, 398.33478091587045, 4989.825356112537, 5390.380106287778, 5396.057419329043, 5399.595030372718, 5499.450054994499, 4844.903101937959, 4917.229797272978, 5193.909623773849, 5246.501582349671, 5293.0, 4429.60133587977, 4442.777861106948, 4443.844735303375, 4468.865934021985, 4474.507804141542, 3848.8645849474474, 4482.646610529466, 4499.347594598781, 4530.932036019462, 4536.591706746392, 3739.223868662391, 4220.97889510552, 4423.690341676084, 4439.689221754478, 4460.776961151945, 3991.3877940463635, 4165.812538435747, 4353.455818022763, 4362.520122786491, 4378.321360189176, 3442.7417943654327, 4075.513936498763, 4102.425660407545, 4140.648044916185, 4226.788660566959, 4231.201739260832, 4246.331147917534, 5017.724025178651, 5020.548150666457, 5329.440408757102, 4549.248539312355, 5137.768800403954, 5154.819581314636, 5164.328637277167, 5167.612429067797, 4444.644428445749, 4894.3147959285725, 4946.653734238605, 5298.417174110845, 5838.007538718428, 4147.253494371012, 4263.91472170558, 4532.5694059064335, 4599.678022538424, 4614.261718125117, 5146.845594632167, 5280.762365693553, 5505.889882202414, 6056.424639659225, 6069.666168360738, 3871.8644847430205, 3956.643902048829, 4389.861249206832, 4445.822167113344, 4467.709598876063, 3844.36567966288, 3945.4279129526058, 4563.634909207255, 4760.428748550196, 4805.05395314898, 4711.411073615798, 4722.929156062662, 4821.373221481185, 4905.386826646669, 5114.468338172628, 4840.467548569654, 5181.5595674368105, 5613.410591887875, 5732.598718089694, 5751.785562558362, 1380.675541247806, 1594.9122798245976, 1622.7585896788967, 1641.3750387116668, 1643.5069479156443, 1088.9128869690405, 1105.2649987758211, 1158.783277558577, 1203.4163430736087, 1220.2495465288962, 4644.698094623838, 4943.505649435099, 5303.257543943851, 5340.599455040888, 5347.786088556414, 5355.7054362009685, 5787.623804452697, 5950.940490595125, 5959.612625179349, 6027.547933904887, 3270.1497610620936, 4443.488998765119, 5129.487051294841, 5209.479052094836, 5249.265102885599, 2675.3380386515155, 2794.7842688430596, 2870.583765354012, 2977.464056469813, 3073.9538906916414, 5514.724263786875, 6040.697965101727, 6075.756969721162, 6116.3883611638485, 6130.160951714557, 4627.398438203012, 5302.973485132627, 5426.782928682887, 6100.72546735398, 6113.8471538211015, 5451.945480545144, 6089.238845144357, 6150.601265506456, 6153.907691384633, 6199.907001394983, 4932.654714169974, 5484.245041586813, 5526.723663816873, 5968.910466342922, 6189.442950134509, 5572.832815015556, 5872.385094099157, 5883.676397798162, 5914.704264786829, 5916.467517923235, 5648.802291919763, 5928.466438020599, 5978.701064946648, 6009.278886533644, 6195.57016733038, 3849.2493963676816, 4520.59314661682, 4602.585767280961, 4780.880477988078, 4811.7834697438, 4847.80608775645, 5418.864528386822, 5657.10486986864, 5667.291588551431, 5972.283326000907, 4815.640336235289, 5166.925679220858, 5595.07681232592, 5745.764660597992, 5752.539796816328, 5722.74247658848, 5739.426057394394, 5757.3379061406795, 5764.117941029485, 5777.711114444345, 4597.379353787196, 5195.506157044715, 5547.722613869371, 5592.797548527086, 5595.5803314752375, 4998.875028124326, 5643.322801263875, 5664.461876121762, 5699.375677931769, 5702.6863522506665, 4775.856724298276, 5174.689518628894, 5367.852858856489, 5397.487238712316, 5700.800471983461], "value": 4317.041380144123, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [756.9772906812798, 785.8231897822994, 809.4172196018865, 902.6975963052378, 911.7447114807854, 12754.553590624319, 16263.674726505478, 17303.56741081472, 17614.29542818289, 17615.943043417403, 15640.84359156408, 15736.527904162866, 15979.600509987256, 16190.947588406745, 16211.513654590352, 999.740327187745, 1027.1423361493162, 1027.6249169053278, 1027.9434631095287, 1028.5268776362882, 983.4935008470643, 1014.0417305646167, 1014.371089924248, 1014.5231741081717, 1015.9644412445566, 1064.5688496159069, 1067.146282973622, 1067.8077945969721, 1068.4924660786141, 1069.0432063303379, 1062.1768129699467, 1062.3572738493217, 1062.5749700119975, 1062.9468526573717, 1064.0955188090138, 786.9881951770728, 789.001912580586, 790.5967956342249, 791.0191362710198, 791.4103992525547, 17064.317427302893, 17156.313747450087, 17203.741943870853, 18042.819571804244, 18314.542136446562, 14632.0, 14851.48019819312, 14939.0, 15028.248587570577, 15435.999999999887, 11905.345206013666, 11987.820182697267, 12207.084468664796, 12247.816282755766, 12356.752864942651, 3748.887533374003, 3797.6012518685693, 3837.5394952605866, 4085.571015043437, 4093.5497095319492, 86215.0, 87412.0, 95591.0, 110725.89274107237, 119094.4045279768, 11623.418829058683, 11790.469428875722, 11937.164398492023, 12099.274043557414, 12167.574134905235, 24607.63088553673, 24829.130980415597, 24829.751702483107, 24848.875755621106, 24859.62710559343, 8185.0, 8193.67225310981, 8205.220504052106, 8309.210624990616, 8362.832743345221, 4236.031643365292, 4375.584319489643, 4438.400815889813, 4486.551344865553, 4587.197240482902, 3588.784672919633, 3639.636036396339, 3656.8171591420323, 3695.7043436525014, 3711.461838033524, 584.2229834320344, 649.5063751548762, 655.1973832055788, 666.0142988377229, 667.6494840208832, 706.3078183380213, 719.1801346465035, 734.4821900560092, 743.390419855724, 758.2190343945745, 9390.439061354946, 10511.894881051092, 10732.248742587944, 11012.779744405067, 11022.008019278304, 691.8754624167598, 700.3432375272997, 735.3933005270673, 745.7389913530221, 762.5539059650056, 5053.166227572409, 5078.847634570895, 5110.906371048417, 5169.198774190007, 5211.9478805211465, 102270.0, 103986.0, 104815.0, 105499.89000219952, 107349.94875384377, 53182.73408633009, 90964.0, 94573.0542694578, 95818.52090739501, 95855.0, 44995.55004449979, 51807.48192518102, 65892.70536473254, 72036.0, 74905.62547187229, 9140.908590914272, 10331.915148909557, 11161.513254111156, 11240.887591123985, 11392.373419462008, 45867.77066114648, 45979.54020459887, 46573.53426465827, 47395.0, 47408.0, 59124.70437647784, 60549.69725151522, 61298.69350653218, 61454.69272653608, 62025.75948481095, 11316.94341528287, 12688.682782930131, 13279.203247805162, 13351.265680387673, 13591.86408135906, 14886.032407893452, 14948.103113813206, 15108.471203507825, 15407.380745273107, 15479.922600386926, 13790.862091378958, 13997.197181278696, 14066.788998164831, 14278.714425711636, 14858.182799946107, 13807.51673691416, 14083.647908802363, 14129.434822606981, 14627.268636568342, 14628.780568291697, 13646.658833529242, 13657.317134143055, 14171.789250812657, 14528.445208491736, 14682.972191946461, 9746.512674366111, 9979.559818463622, 10193.337433066827, 10356.741081473023, 10994.560217591206, 9658.517074146124, 9804.55879485439, 11242.66272011841, 11641.185117041725, 11666.241694289844], "value": 7884.507452514465, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1446.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0, 114.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 109.0, 109.0, 143.0, 109.0, 108.0, 109.0, 107.0, 127.0, 108.0], "value": 117.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 183.0, 183.0, 181.0, 182.0, 182.0, 182.0, 183.0, 183.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 59.0, 60.0, 92.0, 61.0, 60.0, 61.0, 60.0, 73.0, 59.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 108.0, 101.0, 100.0, 81.0, 80.0, 69.0, 68.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 86.0, 87.0, 87.0, 87.0, 87.0, 88.0, 88.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 108.0, 149.0, 93.0, 93.0, 94.0, 95.0, 94.0, 93.0, 102.0], "value": 101.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 83.0, 83.0, 83.0, 83.0, 97.0, 128.0, 129.0, 84.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 62.0, 60.0, 58.0, 68.0, 90.0, 95.0, 91.0, 77.0, 63.0], "value": 72.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 65.0, 66.0, 62.0, 59.0, 59.0, 57.0, 74.0, 86.0], "value": 63.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 80.0, 78.0, 91.0, 81.0, 83.0, 81.0, 85.0, 88.0, 108.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 135.0, 135.0, 132.0, 133.0, 132.0, 140.0, 137.0, 156.0, 137.0], "value": 137.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 169.0, 221.0, 216.0, 167.0, 163.0, 159.0, 162.0, 172.0], "value": 175.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 64.0, 63.0, 65.0, 64.0, 64.0, 63.0, 62.0, 62.0], "value": 63.2, "unit": "score"}]}]} 09:05:17 INFO - Return code: 0 09:05:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:05:17 INFO - # TBPL SUCCESS # 09:05:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:05:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:05:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:05:17 INFO - 2017-05-23 09:05:17.354 screenresolution[1658:9724] starting screenresolution argv=screenresolution get 09:05:17 INFO - 2017-05-23 09:05:17.357 screenresolution[1658:9724] Display 0: 1600x1200x32@60 09:05:17 INFO - 2017-05-23 09:05:17.363 screenresolution[1659:9727] starting screenresolution argv=screenresolution list 09:05:17 INFO - Available Modes on Display 0 09:05:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:05:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:05:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:05:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:05:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:05:17 INFO - Intel Iris: 09:05:17 INFO - Chipset Model: Intel Iris 09:05:17 INFO - Type: GPU 09:05:17 INFO - Bus: Built-In 09:05:17 INFO - VRAM (Dynamic, Max): 1536 MB 09:05:17 INFO - Vendor: Intel (0x8086) 09:05:17 INFO - Device ID: 0x0a2e 09:05:17 INFO - Revision ID: 0x0009 09:05:17 INFO - Displays: 09:05:17 INFO - Display: 09:05:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:05:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:05:17 INFO - Display Serial Number: 1600x1200 60 09:05:17 INFO - Main Display: Yes 09:05:17 INFO - Mirror: Off 09:05:17 INFO - Online: Yes 09:05:17 INFO - Rotation: Supported 09:05:17 INFO - Return code: 0 09:05:17 INFO - Running post-action listener: _resource_record_post_action 09:05:17 INFO - [mozharness: 2017-05-23 16:05:17.597442Z] Finished run-tests step (success) 09:05:17 INFO - Running post-run listener: _resource_record_post_run 09:05:17 INFO - Total resource usage - Wall time: 1195s; CPU: 54.0%; Read bytes: 40428544; Write bytes: 421172224; Read time: 265; Write time: 1364 09:05:17 INFO - TinderboxPrint: CPU usage
53.8% 09:05:17 INFO - TinderboxPrint: I/O read bytes / time
40,428,544 / 265 09:05:17 INFO - TinderboxPrint: I/O write bytes / time
421,172,224 / 1,364 09:05:17 INFO - TinderboxPrint: CPU idle
2,208.5 (46.2%) 09:05:17 INFO - TinderboxPrint: CPU system
954.2 (20.0%) 09:05:17 INFO - TinderboxPrint: CPU user
1,619.0 (33.9%) 09:05:17 INFO - TinderboxPrint: Swap in / out
160,956,416 / 0 09:05:17 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121999872; Write bytes: 114192384; Read time: 11054; Write time: 244 09:05:17 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36205568; Write bytes: 296829952; Read time: 238; Write time: 1086 09:05:18 INFO - Running post-run listener: _upload_blobber_files 09:05:18 INFO - Blob upload gear active. 09:05:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:05:18 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 09:05:18 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'] 09:05:18 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 09:05:18 INFO - (blobuploader) - INFO - Open directory for files ... 09:05:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:05:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:05:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:19 INFO - (blobuploader) - INFO - Done attempting. 09:05:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:05:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:05:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:20 INFO - (blobuploader) - INFO - Done attempting. 09:05:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:05:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:05:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:22 INFO - (blobuploader) - INFO - Done attempting. 09:05:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:05:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:05:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:05:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:05:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:05:24 INFO - (blobuploader) - INFO - Done attempting. 09:05:24 INFO - (blobuploader) - INFO - Iteration through files over. 09:05:24 INFO - Return code: 0 09:05:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:05:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:05:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d0680ccb8a45255167454dea8043494118a65e6de009592286cd5a3570c324724f847b0c9cf66e794632d3b86fd87b3b8d6abd7d8d7c2c53f5544943486ca7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d42b68edadbd70c530c8b60a01991b55a81a957610bcabfb539fdbab438361f3eeff49210013baf4d4a590d6c0108b672f6daf0b2e7b64f691807108d7c414d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ee1d6c1d92aa01a4c638775da5674da9f5a52c3001c7d997b1c199822a7c182b2ab0bc9616370b82a1c695a5298acd319fda0f7915653c2b2bf1a8f9e995598", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a82da889c794946efc02fee9b99a159dce23fb6d12340ff27cf1d19a1051de792f28a4ad6a91ae8930c3c0653ea40e8322eda7cd6ed3d536127d440422f0938"} 09:05:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:05:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:05:24 INFO - Contents: 09:05:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2d0680ccb8a45255167454dea8043494118a65e6de009592286cd5a3570c324724f847b0c9cf66e794632d3b86fd87b3b8d6abd7d8d7c2c53f5544943486ca7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d42b68edadbd70c530c8b60a01991b55a81a957610bcabfb539fdbab438361f3eeff49210013baf4d4a590d6c0108b672f6daf0b2e7b64f691807108d7c414d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ee1d6c1d92aa01a4c638775da5674da9f5a52c3001c7d997b1c199822a7c182b2ab0bc9616370b82a1c695a5298acd319fda0f7915653c2b2bf1a8f9e995598", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a82da889c794946efc02fee9b99a159dce23fb6d12340ff27cf1d19a1051de792f28a4ad6a91ae8930c3c0653ea40e8322eda7cd6ed3d536127d440422f0938"} 09:05:24 INFO - Running post-run listener: copy_logs_to_upload_dir 09:05:24 INFO - Copying logs to upload dir... 09:05:24 INFO - mkdir: /builds/slave/test/build/upload/logs 09:05:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.261804 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-05-23 09:05:24.735617) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 09:05:24.739336) ========= 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.2OllZD6rYa/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.4s2wnBuUGD/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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/2d0680ccb8a45255167454dea8043494118a65e6de009592286cd5a3570c324724f847b0c9cf66e794632d3b86fd87b3b8d6abd7d8d7c2c53f5544943486ca7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d42b68edadbd70c530c8b60a01991b55a81a957610bcabfb539fdbab438361f3eeff49210013baf4d4a590d6c0108b672f6daf0b2e7b64f691807108d7c414d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ee1d6c1d92aa01a4c638775da5674da9f5a52c3001c7d997b1c199822a7c182b2ab0bc9616370b82a1c695a5298acd319fda0f7915653c2b2bf1a8f9e995598", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a82da889c794946efc02fee9b99a159dce23fb6d12340ff27cf1d19a1051de792f28a4ad6a91ae8930c3c0653ea40e8322eda7cd6ed3d536127d440422f0938"} build_url:https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011092 build_url: 'https://queue.taskcluster.net/v1/task/MDN4caXySqOyjiBs4XC7Qw/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/2d0680ccb8a45255167454dea8043494118a65e6de009592286cd5a3570c324724f847b0c9cf66e794632d3b86fd87b3b8d6abd7d8d7c2c53f5544943486ca7c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7d42b68edadbd70c530c8b60a01991b55a81a957610bcabfb539fdbab438361f3eeff49210013baf4d4a590d6c0108b672f6daf0b2e7b64f691807108d7c414d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ee1d6c1d92aa01a4c638775da5674da9f5a52c3001c7d997b1c199822a7c182b2ab0bc9616370b82a1c695a5298acd319fda0f7915653c2b2bf1a8f9e995598", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5a82da889c794946efc02fee9b99a159dce23fb6d12340ff27cf1d19a1051de792f28a4ad6a91ae8930c3c0653ea40e8322eda7cd6ed3d536127d440422f0938"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-23 09:05:24.771897) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 09:05:24.772260) ========= 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.2OllZD6rYa/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.4s2wnBuUGD/Listeners TMPDIR=/var/folders/0l/ckrq9r0505n_pgxf9zzz6jkh00000w/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.005263 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-23 09:05:24.820644) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 09:05:24.821000) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-23 09:05:24.821332) ========= ========= Total master_lag: 0.17 =========