builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0158 starttime: 1496173016.39 results: success (0) buildid: 20170530114616 builduid: 668efa8546804ce9a5edc93af3daddd2 revision: b6b58f56432af99bc6b8356da3ea96170a04190e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 12:36:56.394264) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 12:36:56.394739) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 12:36:56.395019) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 12:36:56.419002) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 12:36:56.419417) ========= 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.BQbAuROnzY/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.rKIiVU21wM/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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-30 12:36:56-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 149M=0s 2017-05-30 12:36:56 (149 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139198 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 12:36:56.582025) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 12:36:56.582465) ========= 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.BQbAuROnzY/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.rKIiVU21wM/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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.021018 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 12:36:56.631368) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-30 12:36:56.631774) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b6b58f56432af99bc6b8356da3ea96170a04190e --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 b6b58f56432af99bc6b8356da3ea96170a04190e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.BQbAuROnzY/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.rKIiVU21wM/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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-30 12:36:56,679 truncating revision to first 12 chars 2017-05-30 12:36:56,679 Setting DEBUG logging. 2017-05-30 12:36:56,679 attempt 1/10 2017-05-30 12:36:56,679 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b6b58f56432a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 12:36:58,464 unpacking tar archive at: mozilla-beta-b6b58f56432a/testing/mozharness/ program finished with exit code 0 elapsedTime=2.064259 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-30 12:36:58.719997) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 12:36:58.720394) ========= script_repo_revision: b6b58f56432af99bc6b8356da3ea96170a04190e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 12:36:58.720923) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 12:36:58.721257) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 12:36:58.740649) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-05-30 12:36:58.741053) ========= /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.BQbAuROnzY/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.rKIiVU21wM/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 12:36:58 INFO - MultiFileLogger online at 20170530 12:36:58 in /builds/slave/test 12:36: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 12:36:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:36:58 INFO - {'append_to_log': False, 12:36:58 INFO - 'base_work_dir': '/builds/slave/test', 12:36:58 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:36:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:36:58 INFO - 'branch': 'Mozilla-Beta', 12:36:58 INFO - 'buildbot_json_path': 'buildprops.json', 12:36:58 INFO - 'config_files': ('talos/mac_config.py',), 12:36:58 INFO - 'default_actions': ('clobber', 12:36:58 INFO - 'read-buildbot-config', 12:36:58 INFO - 'download-and-extract', 12:36:58 INFO - 'populate-webroot', 12:36:58 INFO - 'create-virtualenv', 12:36:58 INFO - 'install', 12:36:58 INFO - 'run-tests'), 12:36:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:36:58 INFO - 'download_minidump_stackwalk': True, 12:36:58 INFO - 'download_symbols': 'ondemand', 12:36:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:36:58 INFO - 'tooltool.py': '/tools/tooltool.py', 12:36:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:36:58 INFO - '/tools/misc-python/virtualenv.py')}, 12:36:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:36:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:36:58 INFO - 'gecko_profile': False, 12:36:58 INFO - 'gecko_profile_interval': 0, 12:36:58 INFO - 'installer_path': 'installer.exe', 12:36:58 INFO - 'log_level': 'info', 12:36:58 INFO - 'log_name': 'talos', 12:36:58 INFO - 'log_to_console': True, 12:36:58 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:36:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:36:58 INFO - 'opt_config_files': (), 12:36:58 INFO - 'pip_index': False, 12:36:58 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:36:58 INFO - 'cmd': ('bash', 12:36:58 INFO - '-c', 12:36:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:36:58 INFO - 'enabled': True, 12:36:58 INFO - 'halt_on_failure': False, 12:36:58 INFO - 'name': 'check_screen_resolution'},), 12:36:58 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:36:58 INFO - 'cmd': ('bash', 12:36:58 INFO - '-c', 12:36:58 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:36:58 INFO - 'enabled': True, 12:36:58 INFO - 'halt_on_failure': False, 12:36:58 INFO - 'name': 'check_screen_resolution'},), 12:36:58 INFO - 'run_cmd_checks_enabled': True, 12:36:58 INFO - 'suite': 'dromaeojs', 12:36:58 INFO - 'system_bits': '32', 12:36:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:36:58 INFO - 'title': 't-yosemite-r7-0158', 12:36:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:36:58 INFO - 'use_talos_json': True, 12:36:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:36:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:36:58 INFO - 'work_dir': 'build'} 12:36:58 INFO - [mozharness: 2017-05-30 19:36:58.912792Z] Running clobber step. 12:36:58 INFO - Running pre-action listener: _resource_record_pre_action 12:36:58 INFO - Running main action method: clobber 12:36:58 INFO - rmtree: /builds/slave/test/build 12:36:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:37:00 INFO - Running post-action listener: _resource_record_post_action 12:37:00 INFO - [mozharness: 2017-05-30 19:37:00.733643Z] Finished clobber step (success) 12:37:00 INFO - [mozharness: 2017-05-30 19:37:00.733826Z] Running read-buildbot-config step. 12:37:00 INFO - Running pre-action listener: _resource_record_pre_action 12:37:00 INFO - Running main action method: read_buildbot_config 12:37:00 INFO - Using buildbot properties: 12:37:00 INFO - { 12:37:00 INFO - "project": "", 12:37:00 INFO - "product": "firefox", 12:37:00 INFO - "script_repo_revision": "production", 12:37:00 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 12:37:00 INFO - "repository": "", 12:37:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 12:37:00 INFO - "buildid": "20170530114616", 12:37:00 INFO - "pgo_build": "False", 12:37:00 INFO - "basedir": "/builds/slave/test", 12:37:00 INFO - "buildnumber": 19, 12:37:00 INFO - "slavename": "t-yosemite-r7-0158", 12:37:00 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 12:37:00 INFO - "platform": "yosemite_r7", 12:37:00 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:37:00 INFO - "branch": "mozilla-beta", 12:37:00 INFO - "repo_path": "releases/mozilla-beta", 12:37:00 INFO - "revision": "b6b58f56432af99bc6b8356da3ea96170a04190e", 12:37:00 INFO - "stage_platform": "macosx64", 12:37:00 INFO - "builduid": "668efa8546804ce9a5edc93af3daddd2", 12:37:00 INFO - "slavebuilddir": "test" 12:37:00 INFO - } 12:37:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 12:37:00 INFO - Running post-action listener: _resource_record_post_action 12:37:00 INFO - [mozharness: 2017-05-30 19:37:00.738543Z] Finished read-buildbot-config step (success) 12:37:00 INFO - [mozharness: 2017-05-30 19:37:00.738695Z] Running download-and-extract step. 12:37:00 INFO - Running pre-action listener: _resource_record_pre_action 12:37:00 INFO - Running main action method: download_and_extract 12:37:00 INFO - mkdir: /builds/slave/test/build/tests 12:37: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')]} 12:37:00 INFO - trying https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 12:37:00 INFO - Downloading https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/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 12:37:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/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 12:37:01 INFO - Downloaded 1287 bytes. 12:37:01 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:37:01 INFO - Using the following test package requirements: 12:37:01 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:37:01 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 12:37:01 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:37:01 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:37:01 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 12:37:01 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:37:01 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 12:37:01 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:37:01 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:37:01 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 12:37:01 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:37:01 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:37:01 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 12:37:01 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:37:01 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 12:37:01 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:37:01 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 12:37:01 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:37:01 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 12:37:01 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 12:37:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:37:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:37:01 INFO - Fetch https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:37:03 INFO - Expected file size: 51393550 12:37:03 INFO - Obtained file size: 51393550 12:37:19 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 12:37:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:37:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:37:19 INFO - Fetch https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:37:20 INFO - Expected file size: 51393550 12:37:20 INFO - Obtained file size: 51393550 12:37:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 12:37:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 12:37:36 INFO - Fetch https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 12:37:37 INFO - Expected file size: 14404461 12:37:37 INFO - Obtained file size: 14404461 12:37: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')]} 12:37:37 INFO - trying https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:37:37 INFO - Downloading https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 12:37:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/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 12:37:39 INFO - Downloaded 59645442 bytes. 12:37:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:37:39 INFO - mkdir: /builds/slave/test/properties 12:37:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:37:39 INFO - Writing to file /builds/slave/test/properties/build_url 12:37:39 INFO - Contents: 12:37:39 INFO - build_url:https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:37:39 INFO - Running post-action listener: _resource_record_post_action 12:37:39 INFO - Running post-action listener: set_extra_try_arguments 12:37:39 INFO - [mozharness: 2017-05-30 19:37:39.775277Z] Finished download-and-extract step (success) 12:37:39 INFO - [mozharness: 2017-05-30 19:37:39.775434Z] Running populate-webroot step. 12:37:39 INFO - Running pre-action listener: _resource_record_pre_action 12:37:39 INFO - Running main action method: populate_webroot 12:37:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:37:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:37:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:37:39 INFO - '--tppagecycles', 12:37:39 INFO - '7'], 12:37:39 INFO - 'tests': ['tsvgm']}}, 12:37:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:37:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:37:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:37:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:37:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:37:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:37:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:37:39 INFO - 'talos_options': ['--disable-e10s'], 12:37:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:37:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:37:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:37:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:37:39 INFO - 'talos_options': ['--disable-e10s'], 12:37:39 INFO - 'tests': ['damp', 'tps']}, 12:37:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:37:39 INFO - 'tests': ['damp', 'tps']}, 12:37:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:37:39 INFO - 'tests': ['dromaeo_dom']}, 12:37:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:37:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:37:39 INFO - 'tests': ['basic_compositor_video']}, 12:37:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:37:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:37:39 INFO - 'tests': ['a11yr', 12:37:39 INFO - 'ts_paint', 12:37:39 INFO - 'tpaint', 12:37:39 INFO - 'sessionrestore', 12:37:39 INFO - 'sessionrestore_no_auto_restore', 12:37:39 INFO - 'tabpaint']}, 12:37:39 INFO - 'other-e10s': {'tests': ['a11yr', 12:37:39 INFO - 'ts_paint', 12:37:39 INFO - 'tpaint', 12:37:39 INFO - 'sessionrestore', 12:37:39 INFO - 'sessionrestore_no_auto_restore', 12:37:39 INFO - 'tabpaint']}, 12:37:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:37:39 INFO - 'ts_paint', 12:37:39 INFO - 'tpaint', 12:37:39 INFO - 'sessionrestore', 12:37:39 INFO - 'sessionrestore_no_auto_restore', 12:37:39 INFO - 'tabpaint']}, 12:37:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:37:39 INFO - 'ts_paint', 12:37:39 INFO - 'tpaint', 12:37:39 INFO - 'sessionrestore', 12:37:39 INFO - 'sessionrestore_no_auto_restore', 12:37:39 INFO - 'tabpaint']}, 12:37:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:37:39 INFO - 'tests': ['a11yr', 12:37:39 INFO - 'ts_paint', 12:37:39 INFO - 'tpaint', 12:37:39 INFO - 'sessionrestore', 12:37:39 INFO - 'sessionrestore_no_auto_restore', 12:37:39 INFO - 'tabpaint']}, 12:37:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:37:39 INFO - 'tests': ['a11yr', 12:37:39 INFO - 'ts_paint', 12:37:39 INFO - 'tpaint', 12:37:39 INFO - 'sessionrestore', 12:37:39 INFO - 'sessionrestore_no_auto_restore', 12:37:39 INFO - 'tabpaint']}, 12:37:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:37:39 INFO - 'tests': ['tsvgx', 12:37:39 INFO - 'tsvgr_opacity', 12:37:39 INFO - 'tart', 12:37:39 INFO - 'tscrollx', 12:37:39 INFO - 'cart', 12:37:39 INFO - 'tsvg_static']}, 12:37:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:37:39 INFO - 'tsvgr_opacity', 12:37:39 INFO - 'tart', 12:37:39 INFO - 'tscrollx', 12:37:39 INFO - 'cart', 12:37:39 INFO - 'tsvg_static']}, 12:37:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:37:39 INFO - 'talos_options': ['--disable-e10s'], 12:37:39 INFO - 'tests': ['tp5o']}, 12:37:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:37:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:37:39 INFO - 'talos_options': ['--disable-e10s', 12:37:39 INFO - '--xperf_path', 12:37:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:37:39 INFO - 'tests': ['tp5n']}, 12:37:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:37:39 INFO - 'talos_options': ['--xperf_path', 12:37:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:37:39 INFO - 'tests': ['tp5n']}}, 12:37:39 INFO - 'talos.zip': {'path': '', 12:37:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:37:39 INFO - Running post-action listener: _resource_record_post_action 12:37:39 INFO - [mozharness: 2017-05-30 19:37:39.794033Z] Finished populate-webroot step (success) 12:37:39 INFO - [mozharness: 2017-05-30 19:37:39.794165Z] Running create-virtualenv step. 12:37:39 INFO - Running pre-action listener: _resource_record_pre_action 12:37:39 INFO - Running main action method: create_virtualenv 12:37:39 INFO - Creating virtualenv /builds/slave/test/build/venv 12:37:39 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 12:37:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:37:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:37:39 INFO - Using real prefix '/tools/python27' 12:37:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:37:40 INFO - Installing distribute.............................................................................................................................................................................................done. 12:37:43 INFO - Installing pip.................done. 12:37:43 INFO - Return code: 0 12:37:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:37:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:37:43 INFO - Reading from file tmpfile_stdout 12:37:43 INFO - Output received: 12:37:43 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) 12:37:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:37:43 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')]} 12:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:43 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')]} 12:37:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:43 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 0x105bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc24e1e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a9d30>, '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 0x1058d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10599ebe8>, '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.BQbAuROnzY/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.rKIiVU21wM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 12:37:43 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 12:37:43 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 12:37:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQbAuROnzY/Render', 12:37:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:43 INFO - 'HOME': '/Users/cltbld', 12:37:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:43 INFO - 'LOGNAME': 'cltbld', 12:37:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:43 INFO - 'MOZ_NO_REMOTE': '1', 12:37:43 INFO - 'NO_EM_RESTART': '1', 12:37:43 INFO - 'PAGER': '/bin/cat', 12:37:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:43 INFO - 'PWD': '/builds/slave/test', 12:37:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:43 INFO - 'SHELL': '/bin/bash', 12:37:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rKIiVU21wM/Listeners', 12:37:43 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 12:37:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:43 INFO - 'USER': 'cltbld', 12:37:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:43 INFO - 'XPC_FLAGS': '0x0', 12:37:43 INFO - 'XPC_SERVICE_NAME': '0', 12:37:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:43 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 12:37:43 INFO - Cleaning up... 12:37:44 INFO - Return code: 0 12:37:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:37:44 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')]} 12:37:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:44 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')]} 12:37:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:44 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 0x105bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc24e1e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a9d30>, '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 0x1058d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10599ebe8>, '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.BQbAuROnzY/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.rKIiVU21wM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 12:37:44 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 12:37:44 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 12:37:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQbAuROnzY/Render', 12:37:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:44 INFO - 'HOME': '/Users/cltbld', 12:37:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:44 INFO - 'LOGNAME': 'cltbld', 12:37:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:44 INFO - 'MOZ_NO_REMOTE': '1', 12:37:44 INFO - 'NO_EM_RESTART': '1', 12:37:44 INFO - 'PAGER': '/bin/cat', 12:37:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:44 INFO - 'PWD': '/builds/slave/test', 12:37:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:44 INFO - 'SHELL': '/bin/bash', 12:37:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rKIiVU21wM/Listeners', 12:37:44 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 12:37:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:44 INFO - 'USER': 'cltbld', 12:37:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:44 INFO - 'XPC_FLAGS': '0x0', 12:37:44 INFO - 'XPC_SERVICE_NAME': '0', 12:37:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:44 INFO - Downloading/unpacking psutil>=3.1.1 12:37:44 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 12:37:44 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 12:37:44 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 12:37:44 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 12:37:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:37:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:37:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:37:46 INFO - Installing collected packages: psutil 12:37:46 INFO - Running setup.py install for psutil 12:37:46 INFO - building 'psutil._psutil_osx' extension 12:37:46 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 12:37:46 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 12:37:46 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 12:37:46 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 12:37:46 INFO - building 'psutil._psutil_posix' extension 12:37:46 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 12:37:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:37:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:37:46 INFO - ^ 12:37:46 INFO - 1 warning generated. 12:37:46 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 12:37:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:37:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:37:46 INFO - Successfully installed psutil 12:37:46 INFO - Cleaning up... 12:37:47 INFO - Return code: 0 12:37:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:37:47 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')]} 12:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:47 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')]} 12:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:47 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 0x105bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc24e1e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a9d30>, '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 0x1058d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10599ebe8>, '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.BQbAuROnzY/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.rKIiVU21wM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 12:37:47 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 12:37:47 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 12:37:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQbAuROnzY/Render', 12:37:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:47 INFO - 'HOME': '/Users/cltbld', 12:37:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:47 INFO - 'LOGNAME': 'cltbld', 12:37:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:47 INFO - 'MOZ_NO_REMOTE': '1', 12:37:47 INFO - 'NO_EM_RESTART': '1', 12:37:47 INFO - 'PAGER': '/bin/cat', 12:37:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:47 INFO - 'PWD': '/builds/slave/test', 12:37:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:47 INFO - 'SHELL': '/bin/bash', 12:37:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rKIiVU21wM/Listeners', 12:37:47 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 12:37:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:47 INFO - 'USER': 'cltbld', 12:37:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:47 INFO - 'XPC_FLAGS': '0x0', 12:37:47 INFO - 'XPC_SERVICE_NAME': '0', 12:37:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:37:47 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 12:37:47 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 12:37:47 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 12:37:47 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 12:37:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:37:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:37:48 INFO - Installing collected packages: mozsystemmonitor 12:37:48 INFO - Running setup.py install for mozsystemmonitor 12:37:48 INFO - Successfully installed mozsystemmonitor 12:37:48 INFO - Cleaning up... 12:37:48 INFO - Return code: 0 12:37:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:37:48 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')]} 12:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37: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')]} 12:37:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:49 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 0x105bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc24e1e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a9d30>, '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 0x1058d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10599ebe8>, '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.BQbAuROnzY/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.rKIiVU21wM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 12:37:49 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 12:37:49 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 12:37:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQbAuROnzY/Render', 12:37:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:49 INFO - 'HOME': '/Users/cltbld', 12:37:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:49 INFO - 'LOGNAME': 'cltbld', 12:37:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:49 INFO - 'MOZ_NO_REMOTE': '1', 12:37:49 INFO - 'NO_EM_RESTART': '1', 12:37:49 INFO - 'PAGER': '/bin/cat', 12:37:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:49 INFO - 'PWD': '/builds/slave/test', 12:37:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:49 INFO - 'SHELL': '/bin/bash', 12:37:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rKIiVU21wM/Listeners', 12:37:49 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 12:37:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:49 INFO - 'USER': 'cltbld', 12:37:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:49 INFO - 'XPC_FLAGS': '0x0', 12:37:49 INFO - 'XPC_SERVICE_NAME': '0', 12:37:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:50 INFO - Downloading/unpacking jsonschema==2.5.1 12:37:50 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 12:37:50 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 12:37:50 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 12:37:50 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 12:37:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:37:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:37:51 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 12:37:51 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 12:37:51 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 12:37:51 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 12:37:52 INFO - Downloading functools32-3.2.3-2.tar.gz 12:37:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:37:52 INFO - warning: no files found matching '*.txt' 12:37:52 INFO - no previously-included directories found matching 'build' 12:37:52 INFO - no previously-included directories found matching 'dist' 12:37:52 INFO - no previously-included directories found matching '.git*' 12:37:52 INFO - Installing collected packages: jsonschema, functools32 12:37:52 INFO - Running setup.py install for functools32 12:37:52 INFO - warning: no files found matching '*.txt' 12:37:52 INFO - no previously-included directories found matching 'build' 12:37:52 INFO - no previously-included directories found matching 'dist' 12:37:52 INFO - no previously-included directories found matching '.git*' 12:37:52 INFO - Successfully installed jsonschema functools32 12:37:52 INFO - Cleaning up... 12:37:52 INFO - Return code: 0 12:37:52 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc24e1e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a9d30>, '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 0x1058d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10599ebe8>, '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.BQbAuROnzY/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.rKIiVU21wM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 12:37:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:37:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQbAuROnzY/Render', 12:37:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:52 INFO - 'HOME': '/Users/cltbld', 12:37:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:52 INFO - 'LOGNAME': 'cltbld', 12:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:52 INFO - 'MOZ_NO_REMOTE': '1', 12:37:52 INFO - 'NO_EM_RESTART': '1', 12:37:52 INFO - 'PAGER': '/bin/cat', 12:37:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:52 INFO - 'PWD': '/builds/slave/test', 12:37:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:52 INFO - 'SHELL': '/bin/bash', 12:37:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rKIiVU21wM/Listeners', 12:37:52 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 12:37:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:52 INFO - 'USER': 'cltbld', 12:37:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:52 INFO - 'XPC_FLAGS': '0x0', 12:37:52 INFO - 'XPC_SERVICE_NAME': '0', 12:37:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:37:52 INFO - Cleaning up... 12:37:52 INFO - Return code: 0 12:37:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc24e1e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a9d30>, '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 0x1058d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10599ebe8>, '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.BQbAuROnzY/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.rKIiVU21wM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 12:37:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:37:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:37:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQbAuROnzY/Render', 12:37:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:52 INFO - 'HOME': '/Users/cltbld', 12:37:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:52 INFO - 'LOGNAME': 'cltbld', 12:37:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:52 INFO - 'MOZ_NO_REMOTE': '1', 12:37:52 INFO - 'NO_EM_RESTART': '1', 12:37:52 INFO - 'PAGER': '/bin/cat', 12:37:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:52 INFO - 'PWD': '/builds/slave/test', 12:37:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:52 INFO - 'SHELL': '/bin/bash', 12:37:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rKIiVU21wM/Listeners', 12:37:52 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 12:37:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:52 INFO - 'USER': 'cltbld', 12:37:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:52 INFO - 'XPC_FLAGS': '0x0', 12:37:52 INFO - 'XPC_SERVICE_NAME': '0', 12:37:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:53 INFO - Downloading/unpacking blobuploader==1.2.4 12:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:54 INFO - Downloading blobuploader-1.2.4.tar.gz 12:37:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:37:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:37:54 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 12:37:54 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 12:37:54 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 12:37:54 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 12:37:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:37:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:37:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:37:55 INFO - Downloading docopt-0.6.1.tar.gz 12:37:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:37:55 INFO - Installing collected packages: blobuploader, requests, docopt 12:37:55 INFO - Running setup.py install for blobuploader 12:37:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:37:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:37:55 INFO - Running setup.py install for requests 12:37:56 INFO - Running setup.py install for docopt 12:37:56 INFO - Successfully installed blobuploader requests docopt 12:37:56 INFO - Cleaning up... 12:37:56 INFO - Return code: 0 12:37:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:37:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:37:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:37:56 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 0x105bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc24e1e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a9d30>, '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 0x1058d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10599ebe8>, '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.BQbAuROnzY/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.rKIiVU21wM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 12:37:56 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 12:37:56 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 12:37:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQbAuROnzY/Render', 12:37:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:37:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:37:56 INFO - 'HOME': '/Users/cltbld', 12:37:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:37:56 INFO - 'LOGNAME': 'cltbld', 12:37:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:37:56 INFO - 'MOZ_NO_REMOTE': '1', 12:37:56 INFO - 'NO_EM_RESTART': '1', 12:37:56 INFO - 'PAGER': '/bin/cat', 12:37:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:37:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:37:56 INFO - 'PWD': '/builds/slave/test', 12:37:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:37:56 INFO - 'SHELL': '/bin/bash', 12:37:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rKIiVU21wM/Listeners', 12:37:56 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 12:37:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:37:56 INFO - 'USER': 'cltbld', 12:37:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:37:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:37:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:37:56 INFO - 'XPC_FLAGS': '0x0', 12:37:56 INFO - 'XPC_SERVICE_NAME': '0', 12:37:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:37:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:37:56 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-ILtRDs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:37:56 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-MssWcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:37:56 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-QenfJo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:37:56 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-5pVH1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:37:57 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-RCWrXz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:37:57 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-Tjm4rK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:37:57 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-J9UX7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:37:57 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-Bxy0g4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:37:57 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-kmG9St-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:37:57 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-wnv5ST-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:37:57 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-W_0LUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:37:57 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-n824w2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:37:57 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-rSHnyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:37:57 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-X_vcCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:37:58 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-LSQ4ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:37:58 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-6z8QOg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:37:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:37:58 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-GQB0El-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:37:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:37:58 INFO - Running setup.py install for manifestparser 12:37:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:37:58 INFO - Running setup.py install for mozcrash 12:37:58 INFO - Running setup.py install for mozdebug 12:37:58 INFO - Running setup.py install for mozdevice 12:37:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:37:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:37:58 INFO - Running setup.py install for mozfile 12:37:59 INFO - Running setup.py install for mozhttpd 12:37:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:37:59 INFO - Running setup.py install for mozinfo 12:37:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:37:59 INFO - Running setup.py install for mozInstall 12:37:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:37:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:37:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:37:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:37:59 INFO - Running setup.py install for mozleak 12:37:59 INFO - Running setup.py install for mozlog 12:37:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:37:59 INFO - Running setup.py install for moznetwork 12:37:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:38:00 INFO - Running setup.py install for mozprocess 12:38:00 INFO - Running setup.py install for mozprofile 12:38:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:38:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:38:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:38:00 INFO - Running setup.py install for mozrunner 12:38:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:38:00 INFO - Running setup.py install for mozscreenshot 12:38:00 INFO - Running setup.py install for moztest 12:38:00 INFO - Running setup.py install for mozversion 12:38:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:38:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:38:00 INFO - Cleaning up... 12:38:00 INFO - Return code: 0 12:38:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:38: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')]} 12:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38: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')]} 12:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:00 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 0x105bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc24e1e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a9d30>, '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 0x1058d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10599ebe8>, '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.BQbAuROnzY/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.rKIiVU21wM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 12:38:00 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 12:38:00 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 12:38:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQbAuROnzY/Render', 12:38:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:00 INFO - 'HOME': '/Users/cltbld', 12:38:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:00 INFO - 'LOGNAME': 'cltbld', 12:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:00 INFO - 'MOZ_NO_REMOTE': '1', 12:38:00 INFO - 'NO_EM_RESTART': '1', 12:38:00 INFO - 'PAGER': '/bin/cat', 12:38:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:00 INFO - 'PWD': '/builds/slave/test', 12:38:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:00 INFO - 'SHELL': '/bin/bash', 12:38:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rKIiVU21wM/Listeners', 12:38:00 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 12:38:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:00 INFO - 'USER': 'cltbld', 12:38:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:00 INFO - 'XPC_FLAGS': '0x0', 12:38:00 INFO - 'XPC_SERVICE_NAME': '0', 12:38:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:38:01 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-fmd4m3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:38:01 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)) 12:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:38:01 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-Zaf94Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:38:01 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)) 12:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:38:01 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-jT4ibi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:38:01 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)) 12:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:38:01 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-eR4t11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:38:01 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)) 12:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:38:01 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-x3ubWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:38:01 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)) 12:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:38:01 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-uWMERC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:38:01 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)) 12:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:38:01 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-e8CVou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:38:01 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)) 12:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:38:01 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-fYOj2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:38:02 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)) 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:38:02 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-IzxEf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:38:02 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)) 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:38:02 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-UsMBDR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:38:02 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)) 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:38:02 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-fr2INz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:38:02 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)) 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:38:02 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-TtOlF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:38:02 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)) 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:38:02 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-BH8kC4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:38:02 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)) 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:38:02 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-5kIV2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:38:02 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)) 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:02 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-eYViwh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:38:02 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)) 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:38:02 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-lK14vK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:38:02 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)) 12:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:38:02 INFO - Running setup.py (path:/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/pip-TIa9ex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:38:03 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)) 12:38:03 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)) 12:38:03 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)) 12:38:03 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)) 12:38:03 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)) 12:38:03 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)) 12:38:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:38:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:38:04 INFO - Downloading blessings-1.6.tar.gz 12:38:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:38:04 INFO - Installing collected packages: blessings 12:38:04 INFO - Running setup.py install for blessings 12:38:04 INFO - Successfully installed blessings 12:38:04 INFO - Cleaning up... 12:38:04 INFO - Return code: 0 12:38:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:38:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:38:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:38:05 INFO - Reading from file tmpfile_stdout 12:38:05 INFO - Current package versions: 12:38:05 INFO - blessings == 1.6 12:38:05 INFO - blobuploader == 1.2.4 12:38:05 INFO - docopt == 0.6.1 12:38:05 INFO - functools32 == 3.2.3-2 12:38:05 INFO - jsonschema == 2.5.1 12:38:05 INFO - manifestparser == 1.1 12:38:05 INFO - mozInstall == 1.12 12:38:05 INFO - mozcrash == 1.0 12:38:05 INFO - mozdebug == 0.1 12:38:05 INFO - mozdevice == 0.49 12:38:05 INFO - mozfile == 1.2 12:38:05 INFO - mozhttpd == 0.7 12:38:05 INFO - mozinfo == 0.9 12:38:05 INFO - mozleak == 0.1 12:38:05 INFO - mozlog == 3.4 12:38:05 INFO - moznetwork == 0.27 12:38:05 INFO - mozprocess == 0.25 12:38:05 INFO - mozprofile == 0.28 12:38:05 INFO - mozrunner == 6.13 12:38:05 INFO - mozscreenshot == 0.1 12:38:05 INFO - mozsystemmonitor == 0.3 12:38:05 INFO - moztest == 0.8 12:38:05 INFO - mozversion == 1.4 12:38:05 INFO - psutil == 3.1.1 12:38:05 INFO - requests == 1.2.3 12:38:05 INFO - wsgiref == 0.1.2 12:38:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:38:05 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')]} 12:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:05 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')]} 12:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:05 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 0x105bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc24e1e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a9d30>, '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 0x1058d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10599ebe8>, '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.BQbAuROnzY/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.rKIiVU21wM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 12:38:05 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 12:38:05 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 12:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQbAuROnzY/Render', 12:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:05 INFO - 'HOME': '/Users/cltbld', 12:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:05 INFO - 'LOGNAME': 'cltbld', 12:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:05 INFO - 'MOZ_NO_REMOTE': '1', 12:38:05 INFO - 'NO_EM_RESTART': '1', 12:38:05 INFO - 'PAGER': '/bin/cat', 12:38:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:05 INFO - 'PWD': '/builds/slave/test', 12:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:05 INFO - 'SHELL': '/bin/bash', 12:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rKIiVU21wM/Listeners', 12:38:05 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 12:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:05 INFO - 'USER': 'cltbld', 12:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:05 INFO - 'XPC_FLAGS': '0x0', 12:38:05 INFO - 'XPC_SERVICE_NAME': '0', 12:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:05 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)) 12:38:05 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)) 12:38:05 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)) 12:38:05 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)) 12:38:05 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)) 12:38:05 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)) 12:38:05 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)) 12:38:05 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)) 12:38:05 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)) 12:38:05 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)) 12:38:05 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)) 12:38:05 INFO - Cleaning up... 12:38:05 INFO - Return code: 0 12:38:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:38:05 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')]} 12:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:38:05 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')]} 12:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:38:05 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 0x105bcbb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bce768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8dc24e1e20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a9d30>, '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 0x1058d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10599ebe8>, '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.BQbAuROnzY/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.rKIiVU21wM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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 12:38:05 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 12:38:05 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 12:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQbAuROnzY/Render', 12:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:05 INFO - 'HOME': '/Users/cltbld', 12:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:05 INFO - 'LOGNAME': 'cltbld', 12:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:05 INFO - 'MOZ_NO_REMOTE': '1', 12:38:05 INFO - 'NO_EM_RESTART': '1', 12:38:05 INFO - 'PAGER': '/bin/cat', 12:38:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:05 INFO - 'PWD': '/builds/slave/test', 12:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:05 INFO - 'SHELL': '/bin/bash', 12:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rKIiVU21wM/Listeners', 12:38:05 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 12:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:05 INFO - 'USER': 'cltbld', 12:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:05 INFO - 'XPC_FLAGS': '0x0', 12:38:05 INFO - 'XPC_SERVICE_NAME': '0', 12:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:38:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:38:05 INFO - Cleaning up... 12:38:05 INFO - Return code: 0 12:38:05 INFO - Running post-action listener: _resource_record_post_action 12:38:05 INFO - Running post-action listener: _start_resource_monitoring 12:38:05 INFO - Starting resource monitoring. 12:38:05 INFO - [mozharness: 2017-05-30 19:38:05.685441Z] Finished create-virtualenv step (success) 12:38:05 INFO - [mozharness: 2017-05-30 19:38:05.686409Z] Running install step. 12:38:05 INFO - Running pre-action listener: _resource_record_pre_action 12:38:05 INFO - Running main action method: install 12:38:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:38:05 INFO - Reading from file tmpfile_stdout 12:38:05 INFO - Detecting whether we're running mozinstall >=1.0... 12:38:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:38:06 INFO - Reading from file tmpfile_stdout 12:38:06 INFO - Output received: 12:38:06 INFO - Usage: mozinstall [options] installer 12:38:06 INFO - Options: 12:38:06 INFO - -h, --help show this help message and exit 12:38:06 INFO - -d DEST, --destination=DEST 12:38:06 INFO - Directory to install application into. [default: 12:38:06 INFO - "/builds/slave/test"] 12:38:06 INFO - --app=APP Application being installed. [default: firefox] 12:38:06 INFO - mkdir: /builds/slave/test/build/application 12:38:06 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'] 12:38:06 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 12:38:20 INFO - Reading from file tmpfile_stdout 12:38:20 INFO - Output received: 12:38:20 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:38:20 INFO - Running post-action listener: _resource_record_post_action 12:38:20 INFO - [mozharness: 2017-05-30 19:38:20.987511Z] Finished install step (success) 12:38:20 INFO - [mozharness: 2017-05-30 19:38:20.987628Z] Running run-tests step. 12:38:20 INFO - Running pre-action listener: _resource_record_pre_action 12:38:20 INFO - Running main action method: run_tests 12:38:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:38:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:38:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:38:21 INFO - 2017-05-30 12:38:21.002 screenresolution[2337:26012] starting screenresolution argv=screenresolution get 12:38:21 INFO - 2017-05-30 12:38:21.004 screenresolution[2337:26012] Display 0: 1600x1200x32@60 12:38:21 INFO - 2017-05-30 12:38:21.010 screenresolution[2338:26014] starting screenresolution argv=screenresolution list 12:38:21 INFO - Available Modes on Display 0 12:38:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:38:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:38:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:38:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:38:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:38:21 INFO - Intel Iris: 12:38:21 INFO - Chipset Model: Intel Iris 12:38:21 INFO - Type: GPU 12:38:21 INFO - Bus: Built-In 12:38:21 INFO - VRAM (Dynamic, Max): 1536 MB 12:38:21 INFO - Vendor: Intel (0x8086) 12:38:21 INFO - Device ID: 0x0a2e 12:38:21 INFO - Revision ID: 0x0009 12:38:21 INFO - Displays: 12:38:21 INFO - Display: 12:38:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:38:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:38:21 INFO - Display Serial Number: 1600x1200 60 12:38:21 INFO - Main Display: Yes 12:38:21 INFO - Mirror: Off 12:38:21 INFO - Online: Yes 12:38:21 INFO - Rotation: Supported 12:38:21 INFO - Return code: 0 12:38:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:38:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:38:21 INFO - Python 2.7.3 12:38:21 INFO - Return code: 0 12:38:21 INFO - grabbing minidump binary from tooltool 12:38: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')]} 12:38:21 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 0x1059a9d30>, '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 0x1058d0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10599ebe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:38:21 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 12:38:21 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 12:38:21 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 12:38:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:38:21 INFO - Return code: 0 12:38:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:38:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:38:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:38:21 INFO - ENV: RUST_BACKTRACE is now 1 12:38:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:38:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:38:21 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/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0158', '--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 12:38:21 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/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0158 --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 12:38:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.BQbAuROnzY/Render', 12:38:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:38:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:38:21 INFO - 'HOME': '/Users/cltbld', 12:38:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:38:21 INFO - 'LOGNAME': 'cltbld', 12:38:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:38:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:38:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:38:21 INFO - 'MOZ_NO_REMOTE': '1', 12:38:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:38:21 INFO - 'NO_EM_RESTART': '1', 12:38:21 INFO - 'PAGER': '/bin/cat', 12:38:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:38:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:38:21 INFO - 'PWD': '/builds/slave/test', 12:38:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:38:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:38:21 INFO - 'RUST_BACKTRACE': '1', 12:38:21 INFO - 'SHELL': '/bin/bash', 12:38:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rKIiVU21wM/Listeners', 12:38:21 INFO - 'TMPDIR': '/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/', 12:38:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:38:21 INFO - 'USER': 'cltbld', 12:38:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:38:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:38:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:38:21 INFO - 'XPC_FLAGS': '0x0', 12:38:21 INFO - 'XPC_SERVICE_NAME': '0', 12:38:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:38:21 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/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0158', '--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 12:38:21 INFO - mozversion application_buildid: 20170530114616 12:38:21 INFO - mozversion application_changeset: b6b58f56432af99bc6b8356da3ea96170a04190e 12:38:21 INFO - mozversion application_display_name: Firefox 12:38:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:38:21 INFO - mozversion application_name: Firefox 12:38:21 INFO - mozversion application_remotingname: firefox 12:38:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:38:21 INFO - mozversion application_vendor: Mozilla 12:38:21 INFO - mozversion application_version: 54.0 12:38:21 INFO - mozversion platform_buildid: 20170530114616 12:38:21 INFO - mozversion platform_changeset: b6b58f56432af99bc6b8356da3ea96170a04190e 12:38:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:38:21 INFO - mozversion platform_version: 54.0 12:38:21 INFO - using testdate: 1496173101 12:38:21 INFO - actual date: 1496173101 12:38:21 INFO - starting webserver on 'localhost:54963' 12:38:21 INFO - SUITE-START | Running 2 tests 12:38:21 INFO - TEST-START | dromaeo_css 12:38:21 INFO - operating with platform_type : mac_ 12:38:21 INFO - Initialising browser for dromaeo_css test... 12:38:21 INFO - TEST-INFO | started process 2348 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmp4LZY2S/profile http://localhost:54963/getInfo.html) 12:38:23 INFO - PROCESS | 2348 | __metrics Screen width/height:1600/1200 12:38:23 INFO - PROCESS | 2348 | colorDepth:24 12:38:23 INFO - PROCESS | 2348 | Browser inner width/height: 1024/689 12:38:23 INFO - PROCESS | 2348 | __metrics 12:38:24 INFO - PROCESS | 2348 | 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" 12:38:24 INFO - PROCESS | 2348 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:38:24 INFO - TEST-INFO | 2348: exit 0 12:38:24 INFO - Browser initialized. 12:38:24 INFO - Running cycle 1/1 for dromaeo_css test... 12:38:24 INFO - TEST-INFO | started process 2351 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmp4LZY2S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:38:36 INFO - PROCESS | 2351 | RSS: Main: 165199872 12:38:36 INFO - PROCESS | 2351 | 12:41:41 INFO - PROCESS | 2351 | Cycle 1(1): loaded http://localhost:54963/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54963/tests/dromaeo/cssquery-ext.html) 12:41:42 INFO - PROCESS | 2351 | RSS: Main: 311582720 12:41:42 INFO - PROCESS | 2351 | 12:44:45 INFO - PROCESS | 2351 | Cycle 1(1): loaded http://localhost:54963/tests/dromaeo/cssquery-ext.html (next: http://localhost:54963/tests/dromaeo/cssquery-jquery.html) 12:44:46 INFO - PROCESS | 2351 | RSS: Main: 302120960 12:44:46 INFO - PROCESS | 2351 | 12:47:48 INFO - PROCESS | 2351 | Cycle 1(1): loaded http://localhost:54963/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54963/tests/dromaeo/cssquery-mootools.html) 12:47:49 INFO - PROCESS | 2351 | RSS: Main: 282365952 12:47:49 INFO - PROCESS | 2351 | 12:50:51 INFO - PROCESS | 2351 | Cycle 1(1): loaded http://localhost:54963/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54963/tests/dromaeo/cssquery-prototype.html) 12:50:51 INFO - PROCESS | 2351 | RSS: Main: 298041344 12:50:51 INFO - PROCESS | 2351 | 12:53:55 INFO - PROCESS | 2351 | Cycle 1(1): loaded http://localhost:54963/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54963/tests/dromaeo/cssquery-yui.html) 12:53:56 INFO - PROCESS | 2351 | RSS: Main: 263106560 12:53:56 INFO - PROCESS | 2351 | 12:56:59 INFO - PROCESS | 2351 | Cycle 1(1): loaded http://localhost:54963/tests/dromaeo/cssquery-yui.html (next: http://localhost:54963/tests/dromaeo/cssquery-dojo.html) 12:56:59 INFO - PROCESS | 2351 | RSS: Main: 434794496 12:56:59 INFO - PROCESS | 2351 | 12:56:59 INFO - PROCESS | 2351 | __start_tp_report 12:56:59 INFO - PROCESS | 2351 | _x_x_mozilla_page_load 12:56:59 INFO - PROCESS | 2351 | _x_x_mozilla_page_load_details 12:56:59 INFO - PROCESS | 2351 | |i|pagename|runs| 12:56:59 INFO - PROCESS | 2351 | |0;dojo.html;3514.2971405718845;3520.331137083953;3540.8052557109363;3568.4647302904577;3568.6074531801496;6122.81631551053;6134.877302453949;6147.354527774586;6152.507799376045;6171.321154672988;6092.87814243716;6161.661108639034;6164.506839452844;6165.16770236018;6179.382061793818;5456;5466.726663666811;5480.725963701819;5528.226048353234;5589.301337332833;5375.569954403638;5410.026195284847;5582.162675598652;5590.636608620447;5595.63628364155;5436.537894278991;5461.972690136564;5474.178873169037;5492.890142197154;5493.807716729915;5524.4199359067525;5541.972290138524;5561.221428999943;5572.60991730579;5584.944150558483;5427.674339539601;5499.560035197174;5504.174373843915;5518.889622207574;5570.5822063345;5912.852178695524;5926.348101708809;5983.282006159288;6013.699315034274;6048.637081775064;6331.8417039573915;6351.936480635227;6428.807135785887;6429.260635026985;6433.324500927429;6489.253735820394;6517.3808488193545;6522.21733391996;6545.181852268466;6550.541462097655;6059;6223.87552248957;6254.7498100075945;6297.748090076346;6305.527085468608;6487.610743355413;6509.056186852879;6587.967060164764;6641.767538136142;6719.798406047827;6345.238571371465;6363.427291543783;6365.649889256043;6369;6373.075903993895;6420.06908998202;6553.770618028346;6571.9671401643645;6573.506986975996;6574.80275591733;6246;6305.96847015762;6306.463950564162;6314.210723659543;6406.839829004312;6273.153124328157;6300.558960872695;6305.558610897193;6413.935860641333;6436.678166091677;5207.140821764367;5230.450802665741;5233.738313084331;5255;5256.132738098171;4648.349231107648;4734.218853889139;4754.120487709784;4777.95222047775;4790.880227994258;6073.30157031947;6088.969555152196;6258.468030217391;6295.150154729053;6315.778947736807;6332.176817013758;6430.164078669836;6487.481001519867;6570.704318305688;6575.506836987245;6298.496120310456;6310.716017779119;6318.273398559132;6331.081993110973;6333.2716737575765;5577.05190117681;5692;5781.595288329777;5783.624064435799;5808.215890854765;6489.837754056092;6552.63960482178;6638.867222655424;6698.832529186614;6714.899276510954;6510.44661203784;6521;6521.5108866834225;6525.738970441319;6559.048937903882;6691.09670194537;6773.661316934035;6802.115724955724;6808.285130061272;6834.589924604539;6268.028455589391;6300.842478938063;6373.6175829450385;6417.133686952202;6472.676366181766;6550.246721627074;6563.835904102245;6575.4410875076155;6576.572522786004;6579.736810527525;5549.916751248654;6248.718807653576;6333.96833015832;6370.713317900613;6389.9361006389345;6267.4986001120715;6310.652914089769;6337.841553961188;6385.648789316632;6402.839929001812;6323.810285691437;6324.335944725737;6334.081558173947;6342.9682851587295;6349.14286571307;6407.711652975534;6420.293767685457;6421.293657697556;6440.935590644033;6471.996840489732;5208.765605547684;6121.8493044937295;6182.258129024546;6237.812865613857;6283.905741413791 12:56:59 INFO - PROCESS | 2351 | |1;ext.html;745.4036770583532;760.7147319755092;823.0699309779948;905.8595917632765;915.7573243090578;29614.703852961466;41423.75728728139;41889.743307700744;41968.790156049254;42123;43424.565754342446;43527.56472435275;43552;43631.69104926849;43804.56195438045;8044.678212871508;8399.62201700925;9447.008064153268;9800.656977005825;9960.90039099607;8179.959100204521;9996.90003099967;12157.027437804954;12164.81752773709;12200.328981905992;12258.387080645976;12298.754024919495;12300.138990270685;12315.199512031733;12323.322217278046;12241.938790306036;12276.324802135923;12277.938610306981;12283.815742763865;12297;11695.35675537845;11779.7055073623;11847.585334513251;11859.52561897523;11865.52537898483;40883;40989.795051024856;41086;41136.38295425571;41260;4000.219957108336;4022.336314508132;4081.3877918312196;4091.40674602184;4091.509018917719;29151.979680711072;29175.124746257647;30212.24469388261;30683;31081.533776993365;3210.133264018725;3258.8696452141885;3345.749068819824;3348.614909285439;3439.243366459375;335096;338378;339662;340071;340072.9999999975;53520;53580;53582.85668573291;53631;53676.73161634167;55568;55647.72176139093;55649.72175139179;55679.443205567426;55679.443205568234;15243.23783810805;15658.060516369054;15663;15682.921585392;15764.975276607212;11465.426728663533;11651.53393864253;13269.203847769164;13310.866891330963;13315.933420332836;11410.486528106256;12731.045171612166;12885.291308977909;12971;13188.076834621485;5538.972305138529;5575.303087114111;5580.358258800209;5584.453871791442;5596.748146333425;956.784723437221;975.8145952269047;975.89753075926;981.3768257156614;1005.0301459092011;28514.14457566276;28629.713702862704;29257;29291.707082929322;29383.706162938524;903.5166186090541;1038.2161468091542;1070.2187403195555;1101.8347247912877;1107.7230692326918;14838.851611483962;14873.33070011852;14893.553193404216;15131.621709457131;15211.163386013875;403574;404437;405216;405682;406831;57735.422645773004;57812.42187578155;57904;58494;58688.119678204865;1443.7545617245094;1450.0429716387134;1484.547213100015;1491.970160596782;1531.19612203594;22251.888740556195;26714.866425667748;27136.457270854866;27917;28455.28861778472;41598.792006039774;41808.79095604503;41946.790266048476;41992.580074198864;41999.7900010498;403888;404252;404328;404345;404375.9781201075;29804.70195298019;29974.95087671921;30439;30865.228369290948;30945.535816962314;31656.841715791274;39360.40959385669;39549.81350559488;39891.40162897617;39894.80052599718;28210.576841347807;28603.713962860103;28708.282292942844;28720.569191462564;29408.85295573594;26193.083242086435;28422;28515;29240.707592923798;29323.266918327212;30632.93670632919;33026;33399.16502087467;34241;34313.485297721054;25990.87004564965;26639.866800665874;28942;29454.705452945196;29670.554941676324;21970.89014554917;23267.836608169862;23660.053597856582;23733;24027.759722402552 12:56:59 INFO - PROCESS | 2351 | |2;jquery.html;425.1794037507613;435.1167130724629;435.9863317786146;438.820083765656;444.4533224134313;19674.016299185037;21122.366329010136;22065.669014964747;22150.446238844037;22231.888840555795;21590.784092159072;22251.332460026224;22462.32613021612;22744.20395286165;23443.531129377403;6917;7050.330218629235;7336.303051210127;7338.229485903983;7339.266073392671;5692.402297758737;6861.931380686179;7062.223155452896;7067.187273463541;7096.254893236213;6720.630365329905;6895.2070511891025;6961;6975.720971161148;7058.364747172754;6967.059446974669;6978.476614253926;7008.92991070093;7054.4003759680745;7473.887891681629;6168.9691551541955;7720.575368354738;7724.497907635986;7730.729424470116;7741.22587741227;20576.691349629768;25867.353316167057;26167.738322616722;26578.73421265782;27091.729082709116;2927.1072322941495;2979.3445442002726;3038.194878357237;3047.9847600762077;3070.5087186050123;16117.435889743803;16254.593635159215;16609.83390166095;16667.583310417216;17729.7340539892;2131.765505794361;2153.418576984221;2227.654713519391;2231.263682984611;2273.1248469339257;123780.5243895117;133407.33296333457;144718;149606;155920;18378.632427351644;19200.903995479934;19379.224831006883;19386.321478748177;19412.417627471197;30660.38679226403;30870;30990.91531796376;31027.224319392197;31096.68903310938;10127.645532406477;10329.018743219382;10441.321314114559;10507.159427245802;10669.893301067044;7941.36469082472;8205.630746616416;8875.644974200959;9032.729018129467;9082.86375704365;9322.906770932204;9390.887050413603;9498.667546635834;9502.809943801085;9517.23862091031;3694.612065733113;3836.846526138979;3995.860144894915;4070.107400058987;4109.363048727452;920.6271460058687;949.2832911152174;1030.8866024737422;1036.6630844975261;1056.4612047855649;21081.89459052695;21438.356849294545;21708.89145554262;21723;21752.564948701252;1036.1348274190984;1040.3913710479303;1040.599369242832;1072.3673032910465;1074.7796701676139;8113.5131892086665;10567.524461399256;10574.471276436147;11066.723331916606;11284.830727539094;425861.4483131022;696700;707480;710630;710798;82694.58652706697;111570;149941;152185.68780715633;155863;1253.4610117649315;1276.7446510697898;1294.2687381629348;1296.1186393252678;1299.5516546791341;15266.770998435255;20133.39599812008;20659.58680826405;20729.27447539329;20951.371458856258;63480;64185.67907160434;64248.67875660592;65977.68044639177;65985.67007164934;87531;87729.04052570759;90329.09670903206;90740;90764;11494.540218391505;11520.366379849189;11612.593559225386;11666.650000499998;11944.402779861146;8279.710210142615;12320.322382269062;13060.322633860225;13216.273104979318;13322.267275299952;11317.886821131684;11452.713682158013;11490.77018459643;11764.882351176379;11782.469788859693;10023.949880250551;10043.648472303434;10488.26582139243;10824;10899.455027248765;11513.769724605294;11516.827247591125;11693.239939403911;11808.232464889754;11955.820662689892;11456.94271528637;11550.711232219262;11719.003884669899;11839;11888.227265227733;9406.43561386319;11453.255538389978;11572.421378931189;11689.590864319707;11747.588834390755 12:56:59 INFO - PROCESS | 2351 | |3;mootools.html;1608.887377883548;1630.7472341787027;1634.3299247308614;1656.2795184094916;1669.4908053043825;11137.944310278448;11718.941405292971;11720.120990925672;11721.765564688701;11726.241063840418;11170;11557.884421155786;11755.823662645045;11780.941095294533;11821.822672659917;2399.3521749127767;2413.1674572272536;2426.4783071639595;2430.428849220432;2431.2706188143575;2267.557826223887;2296.8621882687007;2299.931002069932;2303.6890019847274;2393.0308225168833;2037.9388618341472;2299.8965046572944;2360.3155085025323;2363.291012696193;2371.3834403055157;2314.5718042162084;2335.2877372401413;2347.612643913752;2355.434695673026;2359.1389142962867;2369.5024044950583;2373.7863592276603;2373.91691290804;2383.785459308653;2385.045981607371;11502.309861408343;11584.768304633944;11585.015273701745;11602.361870097142;11609.897059779307;4622.583967442913;5532.6680399176175;5676.375598684142;5680.801171958961;5710.857228569319;3264.2165880188822;3421.623621401644;3482.4602186660854;3519.683228509421;3543.5216245807;1151.7638884028754;1194.0686264713538;1212.7028877924965;1219.7499512599895;1232.3961258983034;14184.43262269508;14448.71102577953;14745.92627036858;15971.600709982227;16014.199290035569;4408.074304396048;4468.419105516327;4547.47704014036;4563.201439748097;4575.153596584642;12191.817122743167;12197.329146897006;12198.085143614264;12207.938960305142;12211.877881221075;4853.150698627726;4976.552110310017;5017.548420642087;5640.943590564042;5685.602007859493;4172.728772629769;4350.869473915787;5251.4485978972425;5294.92057619136;5302.310699609104;3190.920226994297;3647.94528082079;3747.9063023424633;3814.408766641175;3820.872842511466;829.6847198064697;935.317218430536;941.3457646935451;946.5551190940247;953.3850666320185;913.5386629751932;927.7263207353847;939.182910867536;941.6562954521551;993.0317940008434;6592.439642630334;6620.801375958633;6636.435902948304;6903.516753827284;6938.375546200866;993.6720882108887;1007.2848277722899;1009.8232809258351;1044.535181844072;1053.2574534952994;4224.260754367973;4232.957670423318;4236.76697781625;4249.2988656871885;4352.956470435319;13768.518101866386;14347.92826035863;14355.78466323006;14367.568972930829;14374;11750.58872939443;13849.030567860354;14199.503017394341;14242.786358204636;14256.572302830931;10376.16990640762;10648.201384896163;10674.985876341736;10678.466076696133;10698.732531686614;6625.768098116543;6884.552504087218;6970.546914450544;7053.541519801196;7057.400120989773;12711.872881271069;12714.491420343084;12935.870641293466;12953.870461295643;12995.935020324838;11350.545978161112;11409.54361825551;13033.739325213253;13236.933815331247;13377.53178638743;8115.75652730419;9288.071192880658;9409.059094090537;9581.041895810364;9592.23262139041;9129.497877616795;10452;10473.162147028068;10584.89415105839;10586.311889727143;9123.90876091257;9130.406523575946;9166.129217724305;9227.44635321883;9305.395149315273;8576.399652024298;8761.680494509717;8897.955510222666;9007.279417646703;9080.545972701206;7836.333911617577;8016.1182269949095;8193.75418737415;8295.294899933573;8324;5926.644401335933;5948.464638182584;6089.69551522431;6114.144019837137;6169.876602468015;5974.761009559569;5982.58121931478;6091.390860914051;6112.327643959072;6267.780627678009 12:56:59 INFO - PROCESS | 2351 | |4;prototype.html;354.5143153879186;371.8642695416174;372.42274474564454;383.2526573182294;384.5347129972733;4556.9088618227615;4642.721436713795;5146.897062058757;5243.580513558915;5379.757910894009;4903.583195428384;4922.729249891255;5019.623528235379;5095.592352611791;5173.327467429229;4298.247806633843;4324.8971512264325;4325.589069038437;4351.891202719926;4363.476382834065;4337.566243375653;4339.652827773786;4352.499462561815;4370.672199585028;4411.228035093862;3825.387937929932;4025.879223623296;4053.9797301013455;4197.832086716528;4297.4413326267695;4259.91480170398;4273.4444522212225;4279.101388708359;4280.8715738527735;4303.439453525148;3711.888643340704;3716.3682174030478;3975.3043217436834;3977.1846771411792;3979.7214195006363;4607.907841843178;4632.397788287535;4882.829100981448;4960.776765045582;5094.898102037975;4303.784810759449;4753.35829662998;4818.710877347336;4920;4966.900661986776;4866.635002374836;5520.944790552083;5539.584531160179;5552.3892371839065;5577.569794576347;3943.4084887266854;4032.931273212572;4421.579949904786;4457.398251236074;4485.46174459067;5027.547520723152;5215.530602245778;5545.805896793633;5596.776128954878;5812.738426770806;4411.18393097278;4475.753833539122;4568.725876447424;4604.585587297092;4643.3284017753585;3749.087965137745;4626.4910859805395;4670.439547254283;4688.2733176357715;4756.881077973009;4014.5985401460202;4053.5946405359227;4747.857564273008;4889.435380678149;5025.120603894304;4952.826651067196;5421.5061071906575;5491.36849262332;5501.779928802883;5523.265983338273;1453.1716921354725;1542.8071491063617;1560.3290585048312;1582.3591445464606;1599.928003239857;948.6015873333216;1040.3757745352727;1167.96496105117;1178.2164860367932;1192.80915053591;4512.818462983589;5047.394312682502;5159.948400515947;5163.328767260232;5188.0661480933795;4809.615230781529;4830.66185367028;5590.65192619101;5681.545476361881;5717.571182161355;4622.787162921737;4777.689450185727;4794.897173650011;5027.2207807789855;5128;2851.586519954595;2910.7816913731554;2931.9267018324285;2939.4714748331176;2941.1323659520494;5270.7628156733035;5412.5940554458575;5738.82452364814;5818;5841.649501029867;5466.671999680032;5688.345840228427;5740.885182296331;5778.653280803165;5780.971095144581;5230.843074707765;5788.681622510802;5819.6508209507565;5854.941450585439;5869.559783016291;5112.642115051911;5115.360579927509;5123.590112790893;5569.387367389503;5616.090529219921;4857;5147.249935022111;5386.461353864739;5543.334799824047;5544.916826247691;4792.2987339493975;5430.343745157765;5467.863303417446;5747.971260143672;5752.424757524214;4504;4526.343680166423;4539.84110556129;4541.729135432284;4549.863504094882;5331.720387107144;5651.745671444713;5672.290963629546;5735.053716136791;5739.035734089899;4990;5531.695756733418;5540.833774986757;5547.362053363753;5567.610267281251;4771.467106249147;5144.945286216242;5464.9180262296895;5515.338159420895;5534.667919924817;4764.664734685664;5022.623303252344;5376.704281264568;5388.353397592314;5453.291072160594;5018.096742586369;5251.9474805252985;5511.12621708621;5520.522071095184;5539.590278228822;4856.9314750754775;5074.746262686925;5154.53609175176;5409.945900541102;5424.810131645373 12:56:59 INFO - PROCESS | 2351 | |5;yui.html;766.4826242286458;796.1322158846856;881.8985816631088;902.7697937026057;907.0521305236027;15881.047137171763;17102.828971710278;17133.91433042836;17316.8744031638;17413.912930435345;14050.929745351285;16196.190190490488;16487.258073386696;16513.504594862145;16536.669266614692;990.2919412619975;1009.3287963504298;1014.1481155829102;1015.603914473356;1015.7663737340416;991.9652812151576;1004.9095581397672;1005.4972513743129;1008.2790804574728;1013.5388398278802;1033.9844902326472;1033.9896601034006;1034.053840735726;1034.2346663469054;1035.3322107240824;1028.5114570578953;1029.1252435429824;1029.7940411917646;1030.4899074957907;1033.132168978062;764.0449438202247;766.5592284436472;767.263427109975;767.535640937236;769.4114002787871;15174.089554626758;15510.45713400037;15535.766963495556;17781.7332740009;18058.548536286566;14486.637834054232;14673.853261467355;14752.631184220372;14770.483033093898;14783.926080369529;11605.419729013516;11854.40727963607;11857.407129643483;12035.518579256819;12170.148089633729;3732.2722069196516;3765.1340191755776;3870.632289932452;3897.7856217908;3970.3845903884944;98823.50588247084;102085;103279;106613;109038.45480772624;10207.846882296772;11287.887121128848;11345.773084538429;11389.43052847371;11643.53425862956;22357.88821055917;22736.772632273467;22882.31353059411;22905;22906.541869162524;7700.037495313086;7983.760487185394;8127.75616731499;8143.144969778088;8401.537915414588;4550.340200670884;4593.6095431888;4612.723236605814;4620.168369693488;4635.768211589474;3398.4392575225306;3504.7897126172;3580.15866271426;3586.6012255220485;3608.296382205445;649.0718272869747;652.5921299188008;660.7423104989058;680.391050010238;712.0707476742797;594.7947957954499;598.3836648252307;600.7897235967376;612.6783438694721;620.1008537620469;10664.413457259925;10727.570897164182;10864.837027444437;11049.558017679203;11455.255408398427;699.2203692882501;704.2570088556622;709.2375696126581;743.5241445474901;752.4619896773723;5034.622403319692;5297.390800058042;5319.2021196820015;5331.333583302087;5345.578829549092;83916;87484.5625771867;94847.52576237074;96815;97329;81366.59316703379;84952;85786;86040.13959860447;88426;77902.44195116065;79221;80535.99999999882;80636.19363806404;82373.58813205895;11042.116630669689;11055.83416248773;11405.885941140814;11415.885841141482;11430.885691143314;50431.49568504268;50823.491765081875;51839.481605183464;52252;52931;59711;61973.690131549054;62674.686626566574;62726;62790.05814912689;12652.746945060862;12659.367031648197;12721.363931803558;12841;13368.732625347633;14129.364178611782;14436.061655992327;14609;15582.844171558592;15930.442434514736;13435.261060641367;14566.907481938684;14668.292731292719;14803.851961480677;14942.701145976802;13660.521881734092;14114.36485358182;14216.573502794932;14771.335289911765;14962.850371496581;12720.618381448572;13768.686463548422;13950.581482555539;14080.859191408364;14290.999630025799;9686.176674982706;10115.49422528856;10236;10354.534045968101;10488.68533943983;10434.37393756377;10570.2072344576;11064;11280.548778049115;11657.825132623188 12:56:59 INFO - PROCESS | 2351 | __end_tp_report 12:56:59 INFO - PROCESS | 2351 | __start_cc_report 12:56:59 INFO - PROCESS | 2351 | _x_x_mozilla_cycle_collect,1528 12:56:59 INFO - PROCESS | 2351 | __end_cc_report 12:56:59 INFO - PROCESS | 2351 | __startTimestamp1496174219562__endTimestamp 12:56:59 INFO - PROCESS | 2351 | 12:56:59 INFO - PROCESS | 2351 | ------- Summary: start ------- 12:56:59 INFO - PROCESS | 2351 | Number of tests: 6 12:56:59 INFO - PROCESS | 2351 | 12:56:59 INFO - PROCESS | 2351 | [#0] dojo.html Cycles:165 Average:6046.52 Median:6305.53 stddev:649.69 (10.3%) stddev-sans-first:620.57 12:56:59 INFO - PROCESS | 2351 | Values: 3514.3 3520.3 3540.8 3568.5 3568.6 6122.8 6134.9 6147.4 6152.5 6171.3 6092.9 6161.7 6164.5 6165.2 6179.4 5456.0 5466.7 5480.7 5528.2 5589.3 5375.6 5410.0 5582.2 5590.6 5595.6 5436.5 5462.0 5474.2 5492.9 5493.8 5524.4 5542.0 5561.2 5572.6 5584.9 5427.7 5499.6 5504.2 5518.9 5570.6 5912.9 5926.3 5983.3 6013.7 6048.6 6331.8 6351.9 6428.8 6429.3 6433.3 6489.3 6517.4 6522.2 6545.2 6550.5 6059.0 6223.9 6254.7 6297.7 6305.5 6487.6 6509.1 6588.0 6641.8 6719.8 6345.2 6363.4 6365.6 6369.0 6373.1 6420.1 6553.8 6572.0 6573.5 6574.8 6246.0 6306.0 6306.5 6314.2 6406.8 6273.2 6300.6 6305.6 6413.9 6436.7 5207.1 5230.5 5233.7 5255.0 5256.1 4648.3 4734.2 4754.1 4778.0 4790.9 6073.3 6089.0 6258.5 6295.2 6315.8 6332.2 6430.2 6487.5 6570.7 6575.5 6298.5 6310.7 6318.3 6331.1 6333.3 5577.1 5692.0 5781.6 5783.6 5808.2 6489.8 6552.6 6638.9 6698.8 6714.9 6510.4 6521.0 6521.5 6525.7 6559.0 6691.1 6773.7 6802.1 6808.3 6834.6 6268.0 6300.8 6373.6 6417.1 6472.7 6550.2 6563.8 6575.4 6576.6 6579.7 5549.9 6248.7 6334.0 6370.7 6389.9 6267.5 6310.7 6337.8 6385.6 6402.8 6323.8 6324.3 6334.1 6343.0 6349.1 6407.7 6420.3 6421.3 6440.9 6472.0 5208.8 6121.8 6182.3 6237.8 6283.9 12:56:59 INFO - PROCESS | 2351 | 12:56:59 INFO - PROCESS | 2351 | [#1] ext.html Cycles:180 Average:52961.89 Median:17433.49 stddev:101378.24 (581.5%) stddev-sans-first:101586.82 12:56:59 INFO - PROCESS | 2351 | Values: 745.4 760.7 823.1 905.9 915.8 29614.7 41423.8 41889.7 41968.8 42123.0 43424.6 43527.6 43552.0 43631.7 43804.6 8044.7 8399.6 9447.0 9800.7 9960.9 8180.0 9996.9 12157.0 12164.8 12200.3 12258.4 12298.8 12300.1 12315.2 12323.3 12241.9 12276.3 12277.9 12283.8 12297.0 11695.4 11779.7 11847.6 11859.5 11865.5 40883.0 40989.8 41086.0 41136.4 41260.0 4000.2 4022.3 4081.4 4091.4 4091.5 29152.0 29175.1 30212.2 30683.0 31081.5 3210.1 3258.9 3345.7 3348.6 3439.2 335096.0 338378.0 339662.0 340071.0 340073.0 53520.0 53580.0 53582.9 53631.0 53676.7 55568.0 55647.7 55649.7 55679.4 55679.4 15243.2 15658.1 15663.0 15682.9 15765.0 11465.4 11651.5 13269.2 13310.9 13315.9 11410.5 12731.0 12885.3 12971.0 13188.1 5539.0 5575.3 5580.4 5584.5 5596.7 956.8 975.8 975.9 981.4 1005.0 28514.1 28629.7 29257.0 29291.7 29383.7 903.5 1038.2 1070.2 1101.8 1107.7 14838.9 14873.3 14893.6 15131.6 15211.2 403574.0 404437.0 405216.0 405682.0 406831.0 57735.4 57812.4 57904.0 58494.0 58688.1 1443.8 1450.0 1484.5 1492.0 1531.2 22251.9 26714.9 27136.5 27917.0 28455.3 41598.8 41808.8 41946.8 41992.6 41999.8 403888.0 404252.0 404328.0 404345.0 404376.0 29804.7 29975.0 30439.0 30865.2 30945.5 31656.8 39360.4 39549.8 39891.4 39894.8 28210.6 28603.7 28708.3 28720.6 29408.9 26193.1 28422.0 28515.0 29240.7 29323.3 30632.9 33026.0 33399.2 34241.0 34313.5 25990.9 26639.9 28942.0 29454.7 29670.6 21970.9 23267.8 23660.1 23733.0 24027.8 12:56:59 INFO - PROCESS | 2351 | 12:56:59 INFO - PROCESS | 2351 | [#2] jquery.html Cycles:180 Average:39468.60 Median:22108.06 stddev:110264.72 (498.8%) stddev-sans-first:110535.07 12:56:59 INFO - PROCESS | 2351 | Values: 425.2 435.1 436.0 438.8 444.5 19674.0 21122.4 22065.7 22150.4 22231.9 21590.8 22251.3 22462.3 22744.2 23443.5 6917.0 7050.3 7336.3 7338.2 7339.3 5692.4 6861.9 7062.2 7067.2 7096.3 6720.6 6895.2 6961.0 6975.7 7058.4 6967.1 6978.5 7008.9 7054.4 7473.9 6169.0 7720.6 7724.5 7730.7 7741.2 20576.7 25867.4 26167.7 26578.7 27091.7 2927.1 2979.3 3038.2 3048.0 3070.5 16117.4 16254.6 16609.8 16667.6 17729.7 2131.8 2153.4 2227.7 2231.3 2273.1 123780.5 133407.3 144718.0 149606.0 155920.0 18378.6 19200.9 19379.2 19386.3 19412.4 30660.4 30870.0 30990.9 31027.2 31096.7 10127.6 10329.0 10441.3 10507.2 10669.9 7941.4 8205.6 8875.6 9032.7 9082.9 9322.9 9390.9 9498.7 9502.8 9517.2 3694.6 3836.8 3995.9 4070.1 4109.4 920.6 949.3 1030.9 1036.7 1056.5 21081.9 21438.4 21708.9 21723.0 21752.6 1036.1 1040.4 1040.6 1072.4 1074.8 8113.5 10567.5 10574.5 11066.7 11284.8 425861.4 696700.0 707480.0 710630.0 710798.0 82694.6 111570.0 149941.0 152185.7 155863.0 1253.5 1276.7 1294.3 1296.1 1299.6 15266.8 20133.4 20659.6 20729.3 20951.4 63480.0 64185.7 64248.7 65977.7 65985.7 87531.0 87729.0 90329.1 90740.0 90764.0 11494.5 11520.4 11612.6 11666.7 11944.4 8279.7 12320.3 13060.3 13216.3 13322.3 11317.9 11452.7 11490.8 11764.9 11782.5 10023.9 10043.6 10488.3 10824.0 10899.5 11513.8 11516.8 11693.2 11808.2 11955.8 11456.9 11550.7 11719.0 11839.0 11888.2 9406.4 11453.3 11572.4 11689.6 11747.6 12:56:59 INFO - PROCESS | 2351 | 12:56:59 INFO - PROCESS | 2351 | [#3] mootools.html Cycles:180 Average:6840.04 Median:2428.45 stddev:4381.78 (180.4%) stddev-sans-first:4376.45 12:56:59 INFO - PROCESS | 2351 | Values: 1608.9 1630.7 1634.3 1656.3 1669.5 11137.9 11718.9 11720.1 11721.8 11726.2 11170.0 11557.9 11755.8 11780.9 11821.8 2399.4 2413.2 2426.5 2430.4 2431.3 2267.6 2296.9 2299.9 2303.7 2393.0 2037.9 2299.9 2360.3 2363.3 2371.4 2314.6 2335.3 2347.6 2355.4 2359.1 2369.5 2373.8 2373.9 2383.8 2385.0 11502.3 11584.8 11585.0 11602.4 11609.9 4622.6 5532.7 5676.4 5680.8 5710.9 3264.2 3421.6 3482.5 3519.7 3543.5 1151.8 1194.1 1212.7 1219.7 1232.4 14184.4 14448.7 14745.9 15971.6 16014.2 4408.1 4468.4 4547.5 4563.2 4575.2 12191.8 12197.3 12198.1 12207.9 12211.9 4853.2 4976.6 5017.5 5640.9 5685.6 4172.7 4350.9 5251.4 5294.9 5302.3 3190.9 3647.9 3747.9 3814.4 3820.9 829.7 935.3 941.3 946.6 953.4 913.5 927.7 939.2 941.7 993.0 6592.4 6620.8 6636.4 6903.5 6938.4 993.7 1007.3 1009.8 1044.5 1053.3 4224.3 4233.0 4236.8 4249.3 4353.0 13768.5 14347.9 14355.8 14367.6 14374.0 11750.6 13849.0 14199.5 14242.8 14256.6 10376.2 10648.2 10675.0 10678.5 10698.7 6625.8 6884.6 6970.5 7053.5 7057.4 12711.9 12714.5 12935.9 12953.9 12995.9 11350.5 11409.5 13033.7 13236.9 13377.5 8115.8 9288.1 9409.1 9581.0 9592.2 9129.5 10452.0 10473.2 10584.9 10586.3 9123.9 9130.4 9166.1 9227.4 9305.4 8576.4 8761.7 8898.0 9007.3 9080.5 7836.3 8016.1 8193.8 8295.3 8324.0 5926.6 5948.5 6089.7 6114.1 6169.9 5974.8 5982.6 6091.4 6112.3 6267.8 12:56:59 INFO - PROCESS | 2351 | 12:56:59 INFO - PROCESS | 2351 | [#4] prototype.html Cycles:180 Average:4596.16 Median:4937.78 stddev:1262.09 (25.6%) stddev-sans-first:1224.82 12:56:59 INFO - PROCESS | 2351 | Values: 354.5 371.9 372.4 383.3 384.5 4556.9 4642.7 5146.9 5243.6 5379.8 4903.6 4922.7 5019.6 5095.6 5173.3 4298.2 4324.9 4325.6 4351.9 4363.5 4337.6 4339.7 4352.5 4370.7 4411.2 3825.4 4025.9 4054.0 4197.8 4297.4 4259.9 4273.4 4279.1 4280.9 4303.4 3711.9 3716.4 3975.3 3977.2 3979.7 4607.9 4632.4 4882.8 4960.8 5094.9 4303.8 4753.4 4818.7 4920.0 4966.9 4866.6 5520.9 5539.6 5552.4 5577.6 3943.4 4032.9 4421.6 4457.4 4485.5 5027.5 5215.5 5545.8 5596.8 5812.7 4411.2 4475.8 4568.7 4604.6 4643.3 3749.1 4626.5 4670.4 4688.3 4756.9 4014.6 4053.6 4747.9 4889.4 5025.1 4952.8 5421.5 5491.4 5501.8 5523.3 1453.2 1542.8 1560.3 1582.4 1599.9 948.6 1040.4 1168.0 1178.2 1192.8 4512.8 5047.4 5159.9 5163.3 5188.1 4809.6 4830.7 5590.7 5681.5 5717.6 4622.8 4777.7 4794.9 5027.2 5128.0 2851.6 2910.8 2931.9 2939.5 2941.1 5270.8 5412.6 5738.8 5818.0 5841.6 5466.7 5688.3 5740.9 5778.7 5781.0 5230.8 5788.7 5819.7 5854.9 5869.6 5112.6 5115.4 5123.6 5569.4 5616.1 4857.0 5147.2 5386.5 5543.3 5544.9 4792.3 5430.3 5467.9 5748.0 5752.4 4504.0 4526.3 4539.8 4541.7 4549.9 5331.7 5651.7 5672.3 5735.1 5739.0 4990.0 5531.7 5540.8 5547.4 5567.6 4771.5 5144.9 5464.9 5515.3 5534.7 4764.7 5022.6 5376.7 5388.4 5453.3 5018.1 5251.9 5511.1 5520.5 5539.6 4856.9 5074.7 5154.5 5409.9 5424.8 12:56:59 INFO - PROCESS | 2351 | 12:56:59 INFO - PROCESS | 2351 | [#5] yui.html Cycles:180 Average:20327.44 Median:17118.37 stddev:28017.91 (163.7%) stddev-sans-first:28058.01 12:56:59 INFO - PROCESS | 2351 | Values: 766.5 796.1 881.9 902.8 907.1 15881.0 17102.8 17133.9 17316.9 17413.9 14050.9 16196.2 16487.3 16513.5 16536.7 990.3 1009.3 1014.1 1015.6 1015.8 992.0 1004.9 1005.5 1008.3 1013.5 1034.0 1034.0 1034.1 1034.2 1035.3 1028.5 1029.1 1029.8 1030.5 1033.1 764.0 766.6 767.3 767.5 769.4 15174.1 15510.5 15535.8 17781.7 18058.5 14486.6 14673.9 14752.6 14770.5 14783.9 11605.4 11854.4 11857.4 12035.5 12170.1 3732.3 3765.1 3870.6 3897.8 3970.4 98823.5 102085.0 103279.0 106613.0 109038.5 10207.8 11287.9 11345.8 11389.4 11643.5 22357.9 22736.8 22882.3 22905.0 22906.5 7700.0 7983.8 8127.8 8143.1 8401.5 4550.3 4593.6 4612.7 4620.2 4635.8 3398.4 3504.8 3580.2 3586.6 3608.3 649.1 652.6 660.7 680.4 712.1 594.8 598.4 600.8 612.7 620.1 10664.4 10727.6 10864.8 11049.6 11455.3 699.2 704.3 709.2 743.5 752.5 5034.6 5297.4 5319.2 5331.3 5345.6 83916.0 87484.6 94847.5 96815.0 97329.0 81366.6 84952.0 85786.0 86040.1 88426.0 77902.4 79221.0 80536.0 80636.2 82373.6 11042.1 11055.8 11405.9 11415.9 11430.9 50431.5 50823.5 51839.5 52252.0 52931.0 59711.0 61973.7 62674.7 62726.0 62790.1 12652.7 12659.4 12721.4 12841.0 13368.7 14129.4 14436.1 14609.0 15582.8 15930.4 13435.3 14566.9 14668.3 14803.9 14942.7 13660.5 14114.4 14216.6 14771.3 14962.9 12720.6 13768.7 13950.6 14080.9 14291.0 9686.2 10115.5 10236.0 10354.5 10488.7 10434.4 10570.2 11064.0 11280.5 11657.8 12:56:59 INFO - PROCESS | 2351 | -------- Summary: end -------- 12:56:59 INFO - PROCESS | 2351 | 12:57:00 INFO - TEST-INFO | 2351: exit 0 12:57:00 INFO - TEST-OK | dromaeo_css | took 1118546ms 12:57:00 INFO - TEST-START | kraken 12:57:00 INFO - operating with platform_type : mac_ 12:57:00 INFO - Initialising browser for kraken test... 12:57:00 INFO - TEST-INFO | started process 2356 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmpTwTqmV/profile http://localhost:54963/getInfo.html) 12:57:02 INFO - PROCESS | 2356 | __metrics Screen width/height:1600/1200 12:57:02 INFO - PROCESS | 2356 | colorDepth:24 12:57:02 INFO - PROCESS | 2356 | Browser inner width/height: 1024/689 12:57:02 INFO - PROCESS | 2356 | __metrics 12:57:02 INFO - PROCESS | 2356 | 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" 12:57:02 INFO - PROCESS | 2356 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:57:03 INFO - TEST-INFO | 2356: exit 0 12:57:03 INFO - Browser initialized. 12:57:03 INFO - Running cycle 1/1 for kraken test... 12:57:03 INFO - TEST-INFO | started process 2358 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/T/tmpTwTqmV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:57:15 INFO - PROCESS | 2358 | RSS: Main: 165089280 12:57:15 INFO - PROCESS | 2358 | 12:57:17 INFO - PROCESS | 2358 | Cycle 1(1): loaded http://localhost:54963/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54963/tests/kraken/driver.html?testName=audio-beat-detection) 12:57:18 INFO - PROCESS | 2358 | RSS: Main: 215842816 12:57:18 INFO - PROCESS | 2358 | 12:57:21 INFO - PROCESS | 2358 | Cycle 1(1): loaded http://localhost:54963/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54963/tests/kraken/driver.html?testName=audio-dft) 12:57:22 INFO - PROCESS | 2358 | RSS: Main: 232255488 12:57:22 INFO - PROCESS | 2358 | 12:57:25 INFO - PROCESS | 2358 | Cycle 1(1): loaded http://localhost:54963/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54963/tests/kraken/driver.html?testName=audio-fft) 12:57:25 INFO - PROCESS | 2358 | RSS: Main: 329035776 12:57:25 INFO - PROCESS | 2358 | 12:57:27 INFO - PROCESS | 2358 | Cycle 1(1): loaded http://localhost:54963/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54963/tests/kraken/driver.html?testName=audio-oscillator) 12:57:28 INFO - PROCESS | 2358 | RSS: Main: 230494208 12:57:28 INFO - PROCESS | 2358 | 12:57:30 INFO - PROCESS | 2358 | Cycle 1(1): loaded http://localhost:54963/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54963/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:57:31 INFO - PROCESS | 2358 | RSS: Main: 233324544 12:57:31 INFO - PROCESS | 2358 | 12:57:33 INFO - PROCESS | 2358 | Cycle 1(1): loaded http://localhost:54963/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54963/tests/kraken/driver.html?testName=imaging-darkroom) 12:57:34 INFO - PROCESS | 2358 | RSS: Main: 274296832 12:57:34 INFO - PROCESS | 2358 | 12:57:37 INFO - PROCESS | 2358 | Cycle 1(1): loaded http://localhost:54963/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54963/tests/kraken/driver.html?testName=imaging-desaturate) 12:57:38 INFO - PROCESS | 2358 | RSS: Main: 274169856 12:57:38 INFO - PROCESS | 2358 | 12:57:41 INFO - PROCESS | 2358 | Cycle 1(1): loaded http://localhost:54963/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54963/tests/kraken/driver.html?testName=json-parse-financial) 12:57:41 INFO - PROCESS | 2358 | RSS: Main: 273334272 12:57:41 INFO - PROCESS | 2358 | 12:57:43 INFO - PROCESS | 2358 | Cycle 1(1): loaded http://localhost:54963/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54963/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:57:43 INFO - PROCESS | 2358 | RSS: Main: 247037952 12:57:43 INFO - PROCESS | 2358 | 12:57:45 INFO - PROCESS | 2358 | Cycle 1(1): loaded http://localhost:54963/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54963/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:57:46 INFO - PROCESS | 2358 | RSS: Main: 253915136 12:57:46 INFO - PROCESS | 2358 | 12:57:48 INFO - PROCESS | 2358 | Cycle 1(1): loaded http://localhost:54963/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54963/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:57:49 INFO - PROCESS | 2358 | RSS: Main: 256688128 12:57:49 INFO - PROCESS | 2358 | 12:57:52 INFO - PROCESS | 2358 | Cycle 1(1): loaded http://localhost:54963/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54963/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:57:52 INFO - PROCESS | 2358 | RSS: Main: 261406720 12:57:52 INFO - PROCESS | 2358 | 12:57:55 INFO - PROCESS | 2358 | Cycle 1(1): loaded http://localhost:54963/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54963/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:57:56 INFO - PROCESS | 2358 | RSS: Main: 262627328 12:57:56 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | Cycle 1(1): loaded http://localhost:54963/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54963/tests/kraken/driver.html?testName=ai-astar) 12:57:58 INFO - PROCESS | 2358 | RSS: Main: 306831360 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | __start_tp_report 12:57:58 INFO - PROCESS | 2358 | _x_x_mozilla_page_load 12:57:58 INFO - PROCESS | 2358 | _x_x_mozilla_page_load_details 12:57:58 INFO - PROCESS | 2358 | |i|pagename|runs| 12:57:58 INFO - PROCESS | 2358 | |0;ai-astar;105;114;115;114;115;115;115;114;115;115 12:57:58 INFO - PROCESS | 2358 | |1;audio-beat-detection;140;107;108;162;135;105;108;106;126;107 12:57:58 INFO - PROCESS | 2358 | |2;audio-dft;186;187;183;188;185;183;185;183;187;184 12:57:58 INFO - PROCESS | 2358 | |3;audio-fft;82;58;59;94;59;60;59;58;75;59 12:57:58 INFO - PROCESS | 2358 | |4;audio-oscillator;90;68;101;96;98;85;80;69;69;82 12:57:58 INFO - PROCESS | 2358 | |5;imaging-gaussian-blur;89;89;90;91;90;89;90;89;90;91 12:57:58 INFO - PROCESS | 2358 | |6;imaging-darkroom;95;95;96;96;97;96;96;97;98;96 12:57:58 INFO - PROCESS | 2358 | |7;imaging-desaturate;83;83;87;86;86;83;83;86;83;86 12:57:58 INFO - PROCESS | 2358 | |8;json-parse-financial;61;58;59;59;62;64;63;62;62;64 12:57:58 INFO - PROCESS | 2358 | |9;json-stringify-tinderbox;56;65;68;64;61;58;60;60;59;60 12:57:58 INFO - PROCESS | 2358 | |10;stanford-crypto-aes;80;81;85;88;80;83;81;83;88;108 12:57:58 INFO - PROCESS | 2358 | |11;stanford-crypto-ccm;146;137;137;130;152;149;140;139;146;135 12:57:58 INFO - PROCESS | 2358 | |12;stanford-crypto-pbkdf2;162;164;161;163;161;165;163;213;160;162 12:57:58 INFO - PROCESS | 2358 | |13;stanford-crypto-sha256-iterative;64;66;64;64;66;79;80;76;77;62 12:57:58 INFO - PROCESS | 2358 | __end_tp_report 12:57:58 INFO - PROCESS | 2358 | __start_cc_report 12:57:58 INFO - PROCESS | 2358 | _x_x_mozilla_cycle_collect,2014 12:57:58 INFO - PROCESS | 2358 | __end_cc_report 12:57:58 INFO - PROCESS | 2358 | __startTimestamp1496174278297__endTimestamp 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | ------- Summary: start ------- 12:57:58 INFO - PROCESS | 2358 | Number of tests: 14 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | [#0] ai-astar Cycles:10 Average:113.70 Median:115.00 stddev:3.09 (2.7%) stddev-sans-first:0.50 12:57:58 INFO - PROCESS | 2358 | Values: 105.0 114.0 115.0 114.0 115.0 115.0 115.0 114.0 115.0 115.0 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | [#1] audio-beat-detection Cycles:10 Average:120.40 Median:117.00 stddev:19.64 (16.8%) stddev-sans-first:19.50 12:57:58 INFO - PROCESS | 2358 | Values: 140.0 107.0 108.0 162.0 135.0 105.0 108.0 106.0 126.0 107.0 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | [#2] audio-dft Cycles:10 Average:185.10 Median:185.50 stddev:1.85 (1.0%) stddev-sans-first:1.94 12:57:58 INFO - PROCESS | 2358 | Values: 186.0 187.0 183.0 188.0 185.0 183.0 185.0 183.0 187.0 184.0 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | [#3] audio-fft Cycles:10 Average:66.30 Median:59.50 stddev:12.82 (21.6%) stddev-sans-first:12.28 12:57:58 INFO - PROCESS | 2358 | Values: 82.0 58.0 59.0 94.0 59.0 60.0 59.0 58.0 75.0 59.0 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | [#4] audio-oscillator Cycles:10 Average:83.80 Median:83.50 stddev:12.43 (14.9%) stddev-sans-first:12.99 12:57:58 INFO - PROCESS | 2358 | Values: 90.0 68.0 101.0 96.0 98.0 85.0 80.0 69.0 69.0 82.0 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | [#5] imaging-gaussian-blur Cycles:10 Average:89.80 Median:90.00 stddev:0.79 (0.9%) stddev-sans-first:0.78 12:57:58 INFO - PROCESS | 2358 | Values: 89.0 89.0 90.0 91.0 90.0 89.0 90.0 89.0 90.0 91.0 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | [#6] imaging-darkroom Cycles:10 Average:96.20 Median:96.00 stddev:0.92 (1.0%) stddev-sans-first:0.87 12:57:58 INFO - PROCESS | 2358 | Values: 95.0 95.0 96.0 96.0 97.0 96.0 96.0 97.0 98.0 96.0 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:86.00 stddev:1.71 (2.0%) stddev-sans-first:1.72 12:57:58 INFO - PROCESS | 2358 | Values: 83.0 83.0 87.0 86.0 86.0 83.0 83.0 86.0 83.0 86.0 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:62.00 stddev:2.12 (3.4%) stddev-sans-first:2.24 12:57:58 INFO - PROCESS | 2358 | Values: 61.0 58.0 59.0 59.0 62.0 64.0 63.0 62.0 62.0 64.0 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:60.50 stddev:3.57 (5.9%) stddev-sans-first:3.28 12:57:58 INFO - PROCESS | 2358 | Values: 56.0 65.0 68.0 64.0 61.0 58.0 60.0 60.0 59.0 60.0 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:83.00 stddev:8.38 (10.1%) stddev-sans-first:8.63 12:57:58 INFO - PROCESS | 2358 | Values: 80.0 81.0 85.0 88.0 80.0 83.0 81.0 83.0 88.0 108.0 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | [#11] stanford-crypto-ccm Cycles:10 Average:141.10 Median:143.00 stddev:6.90 (4.8%) stddev-sans-first:7.09 12:57:58 INFO - PROCESS | 2358 | Values: 146.0 137.0 137.0 130.0 152.0 149.0 140.0 139.0 146.0 135.0 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:163.00 stddev:16.09 (9.9%) stddev-sans-first:16.95 12:57:58 INFO - PROCESS | 2358 | Values: 162.0 164.0 161.0 163.0 161.0 165.0 163.0 213.0 160.0 162.0 12:57:58 INFO - PROCESS | 2358 | 12:57:58 INFO - PROCESS | 2358 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.80 Median:71.00 stddev:7.22 (10.2%) stddev-sans-first:7.35 12:57:58 INFO - PROCESS | 2358 | Values: 64.0 66.0 64.0 64.0 66.0 79.0 80.0 76.0 77.0 62.0 12:57:58 INFO - PROCESS | 2358 | -------- Summary: end -------- 12:57:58 INFO - PROCESS | 2358 | 12:57:59 INFO - TEST-INFO | 2358: exit 0 12:57:59 INFO - TEST-OK | kraken | took 59008ms 12:57:59 INFO - SUITE-END | took 1177s 12:57:59 INFO - Completed test suite (00:19:37) 12:57:59 INFO - javascript benchmark 12:57:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7799.961128743692, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3514.2971405718845, 3520.331137083953, 3540.8052557109363, 3568.4647302904577, 3568.6074531801496, 6122.81631551053, 6134.877302453949, 6147.354527774586, 6152.507799376045, 6171.321154672988, 6092.87814243716, 6161.661108639034, 6164.506839452844, 6165.16770236018, 6179.382061793818, 5456.0, 5466.726663666811, 5480.725963701819, 5528.226048353234, 5589.301337332833, 5375.569954403638, 5410.026195284847, 5582.162675598652, 5590.636608620447, 5595.63628364155, 5436.537894278991, 5461.972690136564, 5474.178873169037, 5492.890142197154, 5493.807716729915, 5524.4199359067525, 5541.972290138524, 5561.221428999943, 5572.60991730579, 5584.944150558483, 5427.674339539601, 5499.560035197174, 5504.174373843915, 5518.889622207574, 5570.5822063345, 5912.852178695524, 5926.348101708809, 5983.282006159288, 6013.699315034274, 6048.637081775064, 6331.8417039573915, 6351.936480635227, 6428.807135785887, 6429.260635026985, 6433.324500927429, 6489.253735820394, 6517.3808488193545, 6522.21733391996, 6545.181852268466, 6550.541462097655, 6059.0, 6223.87552248957, 6254.7498100075945, 6297.748090076346, 6305.527085468608, 6487.610743355413, 6509.056186852879, 6587.967060164764, 6641.767538136142, 6719.798406047827, 6345.238571371465, 6363.427291543783, 6365.649889256043, 6369.0, 6373.075903993895, 6420.06908998202, 6553.770618028346, 6571.9671401643645, 6573.506986975996, 6574.80275591733, 6246.0, 6305.96847015762, 6306.463950564162, 6314.210723659543, 6406.839829004312, 6273.153124328157, 6300.558960872695, 6305.558610897193, 6413.935860641333, 6436.678166091677, 5207.140821764367, 5230.450802665741, 5233.738313084331, 5255.0, 5256.132738098171, 4648.349231107648, 4734.218853889139, 4754.120487709784, 4777.95222047775, 4790.880227994258, 6073.30157031947, 6088.969555152196, 6258.468030217391, 6295.150154729053, 6315.778947736807, 6332.176817013758, 6430.164078669836, 6487.481001519867, 6570.704318305688, 6575.506836987245, 6298.496120310456, 6310.716017779119, 6318.273398559132, 6331.081993110973, 6333.2716737575765, 5577.05190117681, 5692.0, 5781.595288329777, 5783.624064435799, 5808.215890854765, 6489.837754056092, 6552.63960482178, 6638.867222655424, 6698.832529186614, 6714.899276510954, 6510.44661203784, 6521.0, 6521.5108866834225, 6525.738970441319, 6559.048937903882, 6691.09670194537, 6773.661316934035, 6802.115724955724, 6808.285130061272, 6834.589924604539, 6268.028455589391, 6300.842478938063, 6373.6175829450385, 6417.133686952202, 6472.676366181766, 6550.246721627074, 6563.835904102245, 6575.4410875076155, 6576.572522786004, 6579.736810527525, 5549.916751248654, 6248.718807653576, 6333.96833015832, 6370.713317900613, 6389.9361006389345, 6267.4986001120715, 6310.652914089769, 6337.841553961188, 6385.648789316632, 6402.839929001812, 6323.810285691437, 6324.335944725737, 6334.081558173947, 6342.9682851587295, 6349.14286571307, 6407.711652975534, 6420.293767685457, 6421.293657697556, 6440.935590644033, 6471.996840489732, 5208.765605547684, 6121.8493044937295, 6182.258129024546, 6237.812865613857, 6283.905741413791], "value": 6005.784722792661, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.4036770583532, 760.7147319755092, 823.0699309779948, 905.8595917632765, 915.7573243090578, 29614.703852961466, 41423.75728728139, 41889.743307700744, 41968.790156049254, 42123.0, 43424.565754342446, 43527.56472435275, 43552.0, 43631.69104926849, 43804.56195438045, 8044.678212871508, 8399.62201700925, 9447.008064153268, 9800.656977005825, 9960.90039099607, 8179.959100204521, 9996.90003099967, 12157.027437804954, 12164.81752773709, 12200.328981905992, 12258.387080645976, 12298.754024919495, 12300.138990270685, 12315.199512031733, 12323.322217278046, 12241.938790306036, 12276.324802135923, 12277.938610306981, 12283.815742763865, 12297.0, 11695.35675537845, 11779.7055073623, 11847.585334513251, 11859.52561897523, 11865.52537898483, 40883.0, 40989.795051024856, 41086.0, 41136.38295425571, 41260.0, 4000.219957108336, 4022.336314508132, 4081.3877918312196, 4091.40674602184, 4091.509018917719, 29151.979680711072, 29175.124746257647, 30212.24469388261, 30683.0, 31081.533776993365, 3210.133264018725, 3258.8696452141885, 3345.749068819824, 3348.614909285439, 3439.243366459375, 335096.0, 338378.0, 339662.0, 340071.0, 340072.9999999975, 53520.0, 53580.0, 53582.85668573291, 53631.0, 53676.73161634167, 55568.0, 55647.72176139093, 55649.72175139179, 55679.443205567426, 55679.443205568234, 15243.23783810805, 15658.060516369054, 15663.0, 15682.921585392, 15764.975276607212, 11465.426728663533, 11651.53393864253, 13269.203847769164, 13310.866891330963, 13315.933420332836, 11410.486528106256, 12731.045171612166, 12885.291308977909, 12971.0, 13188.076834621485, 5538.972305138529, 5575.303087114111, 5580.358258800209, 5584.453871791442, 5596.748146333425, 956.784723437221, 975.8145952269047, 975.89753075926, 981.3768257156614, 1005.0301459092011, 28514.14457566276, 28629.713702862704, 29257.0, 29291.707082929322, 29383.706162938524, 903.5166186090541, 1038.2161468091542, 1070.2187403195555, 1101.8347247912877, 1107.7230692326918, 14838.851611483962, 14873.33070011852, 14893.553193404216, 15131.621709457131, 15211.163386013875, 403574.0, 404437.0, 405216.0, 405682.0, 406831.0, 57735.422645773004, 57812.42187578155, 57904.0, 58494.0, 58688.119678204865, 1443.7545617245094, 1450.0429716387134, 1484.547213100015, 1491.970160596782, 1531.19612203594, 22251.888740556195, 26714.866425667748, 27136.457270854866, 27917.0, 28455.28861778472, 41598.792006039774, 41808.79095604503, 41946.790266048476, 41992.580074198864, 41999.7900010498, 403888.0, 404252.0, 404328.0, 404345.0, 404375.9781201075, 29804.70195298019, 29974.95087671921, 30439.0, 30865.228369290948, 30945.535816962314, 31656.841715791274, 39360.40959385669, 39549.81350559488, 39891.40162897617, 39894.80052599718, 28210.576841347807, 28603.713962860103, 28708.282292942844, 28720.569191462564, 29408.85295573594, 26193.083242086435, 28422.0, 28515.0, 29240.707592923798, 29323.266918327212, 30632.93670632919, 33026.0, 33399.16502087467, 34241.0, 34313.485297721054, 25990.87004564965, 26639.866800665874, 28942.0, 29454.705452945196, 29670.554941676324, 21970.89014554917, 23267.836608169862, 23660.053597856582, 23733.0, 24027.759722402552], "value": 18869.07888686534, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.1794037507613, 435.1167130724629, 435.9863317786146, 438.820083765656, 444.4533224134313, 19674.016299185037, 21122.366329010136, 22065.669014964747, 22150.446238844037, 22231.888840555795, 21590.784092159072, 22251.332460026224, 22462.32613021612, 22744.20395286165, 23443.531129377403, 6917.0, 7050.330218629235, 7336.303051210127, 7338.229485903983, 7339.266073392671, 5692.402297758737, 6861.931380686179, 7062.223155452896, 7067.187273463541, 7096.254893236213, 6720.630365329905, 6895.2070511891025, 6961.0, 6975.720971161148, 7058.364747172754, 6967.059446974669, 6978.476614253926, 7008.92991070093, 7054.4003759680745, 7473.887891681629, 6168.9691551541955, 7720.575368354738, 7724.497907635986, 7730.729424470116, 7741.22587741227, 20576.691349629768, 25867.353316167057, 26167.738322616722, 26578.73421265782, 27091.729082709116, 2927.1072322941495, 2979.3445442002726, 3038.194878357237, 3047.9847600762077, 3070.5087186050123, 16117.435889743803, 16254.593635159215, 16609.83390166095, 16667.583310417216, 17729.7340539892, 2131.765505794361, 2153.418576984221, 2227.654713519391, 2231.263682984611, 2273.1248469339257, 123780.5243895117, 133407.33296333457, 144718.0, 149606.0, 155920.0, 18378.632427351644, 19200.903995479934, 19379.224831006883, 19386.321478748177, 19412.417627471197, 30660.38679226403, 30870.0, 30990.91531796376, 31027.224319392197, 31096.68903310938, 10127.645532406477, 10329.018743219382, 10441.321314114559, 10507.159427245802, 10669.893301067044, 7941.36469082472, 8205.630746616416, 8875.644974200959, 9032.729018129467, 9082.86375704365, 9322.906770932204, 9390.887050413603, 9498.667546635834, 9502.809943801085, 9517.23862091031, 3694.612065733113, 3836.846526138979, 3995.860144894915, 4070.107400058987, 4109.363048727452, 920.6271460058687, 949.2832911152174, 1030.8866024737422, 1036.6630844975261, 1056.4612047855649, 21081.89459052695, 21438.356849294545, 21708.89145554262, 21723.0, 21752.564948701252, 1036.1348274190984, 1040.3913710479303, 1040.599369242832, 1072.3673032910465, 1074.7796701676139, 8113.5131892086665, 10567.524461399256, 10574.471276436147, 11066.723331916606, 11284.830727539094, 425861.4483131022, 696700.0, 707480.0, 710630.0, 710798.0, 82694.58652706697, 111570.0, 149941.0, 152185.68780715633, 155863.0, 1253.4610117649315, 1276.7446510697898, 1294.2687381629348, 1296.1186393252678, 1299.5516546791341, 15266.770998435255, 20133.39599812008, 20659.58680826405, 20729.27447539329, 20951.371458856258, 63480.0, 64185.67907160434, 64248.67875660592, 65977.68044639177, 65985.67007164934, 87531.0, 87729.04052570759, 90329.09670903206, 90740.0, 90764.0, 11494.540218391505, 11520.366379849189, 11612.593559225386, 11666.650000499998, 11944.402779861146, 8279.710210142615, 12320.322382269062, 13060.322633860225, 13216.273104979318, 13322.267275299952, 11317.886821131684, 11452.713682158013, 11490.77018459643, 11764.882351176379, 11782.469788859693, 10023.949880250551, 10043.648472303434, 10488.26582139243, 10824.0, 10899.455027248765, 11513.769724605294, 11516.827247591125, 11693.239939403911, 11808.232464889754, 11955.820662689892, 11456.94271528637, 11550.711232219262, 11719.003884669899, 11839.0, 11888.227265227733, 9406.43561386319, 11453.255538389978, 11572.421378931189, 11689.590864319707, 11747.588834390755], "value": 11615.275341529265, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1608.887377883548, 1630.7472341787027, 1634.3299247308614, 1656.2795184094916, 1669.4908053043825, 11137.944310278448, 11718.941405292971, 11720.120990925672, 11721.765564688701, 11726.241063840418, 11170.0, 11557.884421155786, 11755.823662645045, 11780.941095294533, 11821.822672659917, 2399.3521749127767, 2413.1674572272536, 2426.4783071639595, 2430.428849220432, 2431.2706188143575, 2267.557826223887, 2296.8621882687007, 2299.931002069932, 2303.6890019847274, 2393.0308225168833, 2037.9388618341472, 2299.8965046572944, 2360.3155085025323, 2363.291012696193, 2371.3834403055157, 2314.5718042162084, 2335.2877372401413, 2347.612643913752, 2355.434695673026, 2359.1389142962867, 2369.5024044950583, 2373.7863592276603, 2373.91691290804, 2383.785459308653, 2385.045981607371, 11502.309861408343, 11584.768304633944, 11585.015273701745, 11602.361870097142, 11609.897059779307, 4622.583967442913, 5532.6680399176175, 5676.375598684142, 5680.801171958961, 5710.857228569319, 3264.2165880188822, 3421.623621401644, 3482.4602186660854, 3519.683228509421, 3543.5216245807, 1151.7638884028754, 1194.0686264713538, 1212.7028877924965, 1219.7499512599895, 1232.3961258983034, 14184.43262269508, 14448.71102577953, 14745.92627036858, 15971.600709982227, 16014.199290035569, 4408.074304396048, 4468.419105516327, 4547.47704014036, 4563.201439748097, 4575.153596584642, 12191.817122743167, 12197.329146897006, 12198.085143614264, 12207.938960305142, 12211.877881221075, 4853.150698627726, 4976.552110310017, 5017.548420642087, 5640.943590564042, 5685.602007859493, 4172.728772629769, 4350.869473915787, 5251.4485978972425, 5294.92057619136, 5302.310699609104, 3190.920226994297, 3647.94528082079, 3747.9063023424633, 3814.408766641175, 3820.872842511466, 829.6847198064697, 935.317218430536, 941.3457646935451, 946.5551190940247, 953.3850666320185, 913.5386629751932, 927.7263207353847, 939.182910867536, 941.6562954521551, 993.0317940008434, 6592.439642630334, 6620.801375958633, 6636.435902948304, 6903.516753827284, 6938.375546200866, 993.6720882108887, 1007.2848277722899, 1009.8232809258351, 1044.535181844072, 1053.2574534952994, 4224.260754367973, 4232.957670423318, 4236.76697781625, 4249.2988656871885, 4352.956470435319, 13768.518101866386, 14347.92826035863, 14355.78466323006, 14367.568972930829, 14374.0, 11750.58872939443, 13849.030567860354, 14199.503017394341, 14242.786358204636, 14256.572302830931, 10376.16990640762, 10648.201384896163, 10674.985876341736, 10678.466076696133, 10698.732531686614, 6625.768098116543, 6884.552504087218, 6970.546914450544, 7053.541519801196, 7057.400120989773, 12711.872881271069, 12714.491420343084, 12935.870641293466, 12953.870461295643, 12995.935020324838, 11350.545978161112, 11409.54361825551, 13033.739325213253, 13236.933815331247, 13377.53178638743, 8115.75652730419, 9288.071192880658, 9409.059094090537, 9581.041895810364, 9592.23262139041, 9129.497877616795, 10452.0, 10473.162147028068, 10584.89415105839, 10586.311889727143, 9123.90876091257, 9130.406523575946, 9166.129217724305, 9227.44635321883, 9305.395149315273, 8576.399652024298, 8761.680494509717, 8897.955510222666, 9007.279417646703, 9080.545972701206, 7836.333911617577, 8016.1182269949095, 8193.75418737415, 8295.294899933573, 8324.0, 5926.644401335933, 5948.464638182584, 6089.69551522431, 6114.144019837137, 6169.876602468015, 5974.761009559569, 5982.58121931478, 6091.390860914051, 6112.327643959072, 6267.780627678009], "value": 5152.06075976547, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.5143153879186, 371.8642695416174, 372.42274474564454, 383.2526573182294, 384.5347129972733, 4556.9088618227615, 4642.721436713795, 5146.897062058757, 5243.580513558915, 5379.757910894009, 4903.583195428384, 4922.729249891255, 5019.623528235379, 5095.592352611791, 5173.327467429229, 4298.247806633843, 4324.8971512264325, 4325.589069038437, 4351.891202719926, 4363.476382834065, 4337.566243375653, 4339.652827773786, 4352.499462561815, 4370.672199585028, 4411.228035093862, 3825.387937929932, 4025.879223623296, 4053.9797301013455, 4197.832086716528, 4297.4413326267695, 4259.91480170398, 4273.4444522212225, 4279.101388708359, 4280.8715738527735, 4303.439453525148, 3711.888643340704, 3716.3682174030478, 3975.3043217436834, 3977.1846771411792, 3979.7214195006363, 4607.907841843178, 4632.397788287535, 4882.829100981448, 4960.776765045582, 5094.898102037975, 4303.784810759449, 4753.35829662998, 4818.710877347336, 4920.0, 4966.900661986776, 4866.635002374836, 5520.944790552083, 5539.584531160179, 5552.3892371839065, 5577.569794576347, 3943.4084887266854, 4032.931273212572, 4421.579949904786, 4457.398251236074, 4485.46174459067, 5027.547520723152, 5215.530602245778, 5545.805896793633, 5596.776128954878, 5812.738426770806, 4411.18393097278, 4475.753833539122, 4568.725876447424, 4604.585587297092, 4643.3284017753585, 3749.087965137745, 4626.4910859805395, 4670.439547254283, 4688.2733176357715, 4756.881077973009, 4014.5985401460202, 4053.5946405359227, 4747.857564273008, 4889.435380678149, 5025.120603894304, 4952.826651067196, 5421.5061071906575, 5491.36849262332, 5501.779928802883, 5523.265983338273, 1453.1716921354725, 1542.8071491063617, 1560.3290585048312, 1582.3591445464606, 1599.928003239857, 948.6015873333216, 1040.3757745352727, 1167.96496105117, 1178.2164860367932, 1192.80915053591, 4512.818462983589, 5047.394312682502, 5159.948400515947, 5163.328767260232, 5188.0661480933795, 4809.615230781529, 4830.66185367028, 5590.65192619101, 5681.545476361881, 5717.571182161355, 4622.787162921737, 4777.689450185727, 4794.897173650011, 5027.2207807789855, 5128.0, 2851.586519954595, 2910.7816913731554, 2931.9267018324285, 2939.4714748331176, 2941.1323659520494, 5270.7628156733035, 5412.5940554458575, 5738.82452364814, 5818.0, 5841.649501029867, 5466.671999680032, 5688.345840228427, 5740.885182296331, 5778.653280803165, 5780.971095144581, 5230.843074707765, 5788.681622510802, 5819.6508209507565, 5854.941450585439, 5869.559783016291, 5112.642115051911, 5115.360579927509, 5123.590112790893, 5569.387367389503, 5616.090529219921, 4857.0, 5147.249935022111, 5386.461353864739, 5543.334799824047, 5544.916826247691, 4792.2987339493975, 5430.343745157765, 5467.863303417446, 5747.971260143672, 5752.424757524214, 4504.0, 4526.343680166423, 4539.84110556129, 4541.729135432284, 4549.863504094882, 5331.720387107144, 5651.745671444713, 5672.290963629546, 5735.053716136791, 5739.035734089899, 4990.0, 5531.695756733418, 5540.833774986757, 5547.362053363753, 5567.610267281251, 4771.467106249147, 5144.945286216242, 5464.9180262296895, 5515.338159420895, 5534.667919924817, 4764.664734685664, 5022.623303252344, 5376.704281264568, 5388.353397592314, 5453.291072160594, 5018.096742586369, 5251.9474805252985, 5511.12621708621, 5520.522071095184, 5539.590278228822, 4856.9314750754775, 5074.746262686925, 5154.53609175176, 5409.945900541102, 5424.810131645373], "value": 4222.737893309547, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.4826242286458, 796.1322158846856, 881.8985816631088, 902.7697937026057, 907.0521305236027, 15881.047137171763, 17102.828971710278, 17133.91433042836, 17316.8744031638, 17413.912930435345, 14050.929745351285, 16196.190190490488, 16487.258073386696, 16513.504594862145, 16536.669266614692, 990.2919412619975, 1009.3287963504298, 1014.1481155829102, 1015.603914473356, 1015.7663737340416, 991.9652812151576, 1004.9095581397672, 1005.4972513743129, 1008.2790804574728, 1013.5388398278802, 1033.9844902326472, 1033.9896601034006, 1034.053840735726, 1034.2346663469054, 1035.3322107240824, 1028.5114570578953, 1029.1252435429824, 1029.7940411917646, 1030.4899074957907, 1033.132168978062, 764.0449438202247, 766.5592284436472, 767.263427109975, 767.535640937236, 769.4114002787871, 15174.089554626758, 15510.45713400037, 15535.766963495556, 17781.7332740009, 18058.548536286566, 14486.637834054232, 14673.853261467355, 14752.631184220372, 14770.483033093898, 14783.926080369529, 11605.419729013516, 11854.40727963607, 11857.407129643483, 12035.518579256819, 12170.148089633729, 3732.2722069196516, 3765.1340191755776, 3870.632289932452, 3897.7856217908, 3970.3845903884944, 98823.50588247084, 102085.0, 103279.0, 106613.0, 109038.45480772624, 10207.846882296772, 11287.887121128848, 11345.773084538429, 11389.43052847371, 11643.53425862956, 22357.88821055917, 22736.772632273467, 22882.31353059411, 22905.0, 22906.541869162524, 7700.037495313086, 7983.760487185394, 8127.75616731499, 8143.144969778088, 8401.537915414588, 4550.340200670884, 4593.6095431888, 4612.723236605814, 4620.168369693488, 4635.768211589474, 3398.4392575225306, 3504.7897126172, 3580.15866271426, 3586.6012255220485, 3608.296382205445, 649.0718272869747, 652.5921299188008, 660.7423104989058, 680.391050010238, 712.0707476742797, 594.7947957954499, 598.3836648252307, 600.7897235967376, 612.6783438694721, 620.1008537620469, 10664.413457259925, 10727.570897164182, 10864.837027444437, 11049.558017679203, 11455.255408398427, 699.2203692882501, 704.2570088556622, 709.2375696126581, 743.5241445474901, 752.4619896773723, 5034.622403319692, 5297.390800058042, 5319.2021196820015, 5331.333583302087, 5345.578829549092, 83916.0, 87484.5625771867, 94847.52576237074, 96815.0, 97329.0, 81366.59316703379, 84952.0, 85786.0, 86040.13959860447, 88426.0, 77902.44195116065, 79221.0, 80535.99999999882, 80636.19363806404, 82373.58813205895, 11042.116630669689, 11055.83416248773, 11405.885941140814, 11415.885841141482, 11430.885691143314, 50431.49568504268, 50823.491765081875, 51839.481605183464, 52252.0, 52931.0, 59711.0, 61973.690131549054, 62674.686626566574, 62726.0, 62790.05814912689, 12652.746945060862, 12659.367031648197, 12721.363931803558, 12841.0, 13368.732625347633, 14129.364178611782, 14436.061655992327, 14609.0, 15582.844171558592, 15930.442434514736, 13435.261060641367, 14566.907481938684, 14668.292731292719, 14803.851961480677, 14942.701145976802, 13660.521881734092, 14114.36485358182, 14216.573502794932, 14771.335289911765, 14962.850371496581, 12720.618381448572, 13768.686463548422, 13950.581482555539, 14080.859191408364, 14290.999630025799, 9686.176674982706, 10115.49422528856, 10236.0, 10354.534045968101, 10488.68533943983, 10434.37393756377, 10570.2072344576, 11064.0, 11280.548778049115, 11657.825132623188], "value": 7863.004966864778, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1426.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 114.0, 115.0, 114.0, 115.0, 115.0, 115.0, 114.0, 115.0, 115.0], "value": 113.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 107.0, 108.0, 162.0, 135.0, 105.0, 108.0, 106.0, 126.0, 107.0], "value": 120.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 183.0, 188.0, 185.0, 183.0, 185.0, 183.0, 187.0, 184.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 59.0, 94.0, 59.0, 60.0, 59.0, 58.0, 75.0, 59.0], "value": 66.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 68.0, 101.0, 96.0, 98.0, 85.0, 80.0, 69.0, 69.0, 82.0], "value": 83.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 90.0, 91.0, 90.0, 89.0, 90.0, 89.0, 90.0, 91.0], "value": 89.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 96.0, 97.0, 96.0, 96.0, 97.0, 98.0, 96.0], "value": 96.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 87.0, 86.0, 86.0, 83.0, 83.0, 86.0, 83.0, 86.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 58.0, 59.0, 59.0, 62.0, 64.0, 63.0, 62.0, 62.0, 64.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 65.0, 68.0, 64.0, 61.0, 58.0, 60.0, 60.0, 59.0, 60.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 85.0, 88.0, 80.0, 83.0, 81.0, 83.0, 88.0, 108.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 137.0, 137.0, 130.0, 152.0, 149.0, 140.0, 139.0, 146.0, 135.0], "value": 141.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 164.0, 161.0, 163.0, 161.0, 165.0, 163.0, 213.0, 160.0, 162.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 64.0, 64.0, 66.0, 79.0, 80.0, 76.0, 77.0, 62.0], "value": 69.8, "unit": "score"}]}]} 12:57:59 INFO - Return code: 0 12:57:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:57:59 INFO - # TBPL SUCCESS # 12:57:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:57:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:57:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:57:59 INFO - 2017-05-30 12:57:59.632 screenresolution[2362:28134] starting screenresolution argv=screenresolution get 12:57:59 INFO - 2017-05-30 12:57:59.634 screenresolution[2362:28134] Display 0: 1600x1200x32@60 12:57:59 INFO - 2017-05-30 12:57:59.640 screenresolution[2363:28137] starting screenresolution argv=screenresolution list 12:57:59 INFO - Available Modes on Display 0 12:57:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:57:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:57:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:57:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:57:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:57:59 INFO - Intel Iris: 12:57:59 INFO - Chipset Model: Intel Iris 12:57:59 INFO - Type: GPU 12:57:59 INFO - Bus: Built-In 12:57:59 INFO - VRAM (Dynamic, Max): 1536 MB 12:57:59 INFO - Vendor: Intel (0x8086) 12:57:59 INFO - Device ID: 0x0a2e 12:57:59 INFO - Revision ID: 0x0009 12:57:59 INFO - Displays: 12:57:59 INFO - Display: 12:57:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:57:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:57:59 INFO - Display Serial Number: 1600x1200 60 12:57:59 INFO - Main Display: Yes 12:57:59 INFO - Mirror: Off 12:57:59 INFO - Online: Yes 12:57:59 INFO - Rotation: Supported 12:57:59 INFO - Return code: 0 12:57:59 INFO - Running post-action listener: _resource_record_post_action 12:57:59 INFO - [mozharness: 2017-05-30 19:57:59.876604Z] Finished run-tests step (success) 12:57:59 INFO - Running post-run listener: _resource_record_post_run 12:57:59 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 1601536; Write bytes: 425872896; Read time: 14; Write time: 1405 12:57:59 INFO - TinderboxPrint: CPU usage
54.0% 12:57:59 INFO - TinderboxPrint: I/O read bytes / time
1,601,536 / 14 12:57:59 INFO - TinderboxPrint: I/O write bytes / time
425,872,896 / 1,405 12:57:59 INFO - TinderboxPrint: CPU idle
2,197.8 (46.0%) 12:57:59 INFO - TinderboxPrint: CPU system
954.9 (20.0%) 12:57:59 INFO - TinderboxPrint: CPU user
1,621.0 (34.0%) 12:57:59 INFO - TinderboxPrint: Swap in / out
123,867,136 / 0 12:57:59 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119548416; Write bytes: 118257664; Read time: 10810; Write time: 284 12:57:59 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 688128; Write bytes: 296539648; Read time: 9; Write time: 1084 12:58:00 INFO - Running post-run listener: _upload_blobber_files 12:58:00 INFO - Blob upload gear active. 12:58:00 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:58:00 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 12:58:00 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'] 12:58:00 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 12:58:00 INFO - (blobuploader) - INFO - Open directory for files ... 12:58:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:58:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:58:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:58:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:01 INFO - (blobuploader) - INFO - Done attempting. 12:58:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:58:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:58:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:58:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:02 INFO - (blobuploader) - INFO - Done attempting. 12:58:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:58:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:58:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:58:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:03 INFO - (blobuploader) - INFO - Done attempting. 12:58:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:58:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:58:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:58:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:58:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:58:05 INFO - (blobuploader) - INFO - Done attempting. 12:58:05 INFO - (blobuploader) - INFO - Iteration through files over. 12:58:05 INFO - Return code: 0 12:58:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:58:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:58:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6ace27e5045c0aeb89e72aed822fa19b2b6386bc4472fe10a7f36bf5771c298eeb147ff087227ab0e348cc7b360128ca79e307069a17255386d99a80db715328", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5d3168ed5f0690083e828a8efc577b4519315d25995585058197802f58cabfb9955e262bab897b7db690da2d92b3ad343b467ba32b664b9dd26c2f6f2248bf0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a5950b26bce92673ae7c1f940a563f6293a5b6b999f9879d21f42a18eaecfcacda5c9d92943b4311fbac1f9e770ed5fdfcf76316c83e973b26644736e28009b9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/23eebffc6b82a44f2afd55c702089e82e0657e4ad7d339961dbfe9762b1ef33026e86c4183952dc4e084f64b1b638d942f8223068496544f86ea3e800251afc2"} 12:58:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:58:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:58:05 INFO - Contents: 12:58:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6ace27e5045c0aeb89e72aed822fa19b2b6386bc4472fe10a7f36bf5771c298eeb147ff087227ab0e348cc7b360128ca79e307069a17255386d99a80db715328", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5d3168ed5f0690083e828a8efc577b4519315d25995585058197802f58cabfb9955e262bab897b7db690da2d92b3ad343b467ba32b664b9dd26c2f6f2248bf0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a5950b26bce92673ae7c1f940a563f6293a5b6b999f9879d21f42a18eaecfcacda5c9d92943b4311fbac1f9e770ed5fdfcf76316c83e973b26644736e28009b9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/23eebffc6b82a44f2afd55c702089e82e0657e4ad7d339961dbfe9762b1ef33026e86c4183952dc4e084f64b1b638d942f8223068496544f86ea3e800251afc2"} 12:58:05 INFO - Running post-run listener: copy_logs_to_upload_dir 12:58:05 INFO - Copying logs to upload dir... 12:58:05 INFO - mkdir: /builds/slave/test/build/upload/logs 12:58:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.204324 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2017-05-30 12:58:05.960003) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 12:58:05.963537) ========= 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.BQbAuROnzY/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.rKIiVU21wM/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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/6ace27e5045c0aeb89e72aed822fa19b2b6386bc4472fe10a7f36bf5771c298eeb147ff087227ab0e348cc7b360128ca79e307069a17255386d99a80db715328", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5d3168ed5f0690083e828a8efc577b4519315d25995585058197802f58cabfb9955e262bab897b7db690da2d92b3ad343b467ba32b664b9dd26c2f6f2248bf0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a5950b26bce92673ae7c1f940a563f6293a5b6b999f9879d21f42a18eaecfcacda5c9d92943b4311fbac1f9e770ed5fdfcf76316c83e973b26644736e28009b9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/23eebffc6b82a44f2afd55c702089e82e0657e4ad7d339961dbfe9762b1ef33026e86c4183952dc4e084f64b1b638d942f8223068496544f86ea3e800251afc2"} build_url:https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011476 build_url: 'https://queue.taskcluster.net/v1/task/GEmSyJqwTui5X467X3W0GA/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/6ace27e5045c0aeb89e72aed822fa19b2b6386bc4472fe10a7f36bf5771c298eeb147ff087227ab0e348cc7b360128ca79e307069a17255386d99a80db715328", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5d3168ed5f0690083e828a8efc577b4519315d25995585058197802f58cabfb9955e262bab897b7db690da2d92b3ad343b467ba32b664b9dd26c2f6f2248bf0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a5950b26bce92673ae7c1f940a563f6293a5b6b999f9879d21f42a18eaecfcacda5c9d92943b4311fbac1f9e770ed5fdfcf76316c83e973b26644736e28009b9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/23eebffc6b82a44f2afd55c702089e82e0657e4ad7d339961dbfe9762b1ef33026e86c4183952dc4e084f64b1b638d942f8223068496544f86ea3e800251afc2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 12:58:05.997457) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 12:58:05.997802) ========= 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.BQbAuROnzY/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.rKIiVU21wM/Listeners TMPDIR=/var/folders/np/2bpcjmvx1d95p6tb9r0jqkj400000w/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.005163 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 12:58:06.046203) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 12:58:06.046515) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 12:58:06.046848) ========= ========= Total master_lag: 0.16 =========