builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0176 starttime: 1495116672.27 results: success (0) buildid: 20170518053719 builduid: 3e3d5e6c96f94a79b5be8f4b479cc414 revision: 5a67d411498982aec919e3c4d3e045ce3c250d75 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 07:11:12.268224) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-18 07:11:12.268667) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 07:11:12.268947) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-18 07:11:12.294027) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 07:11:12.294383) ========= 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.yw1x8aIdKc/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.cxsGs203Mw/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-18 07:11:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.4M=0.001s 2017-05-18 07:11:12 (14.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126449 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 07:11:12.443495) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 07:11:12.443902) ========= 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.yw1x8aIdKc/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.cxsGs203Mw/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.029378 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 07:11:12.493459) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-18 07:11:12.493810) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5a67d411498982aec919e3c4d3e045ce3c250d75 --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 5a67d411498982aec919e3c4d3e045ce3c250d75 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yw1x8aIdKc/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.cxsGs203Mw/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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-18 07:11:12,598 truncating revision to first 12 chars 2017-05-18 07:11:12,598 Setting DEBUG logging. 2017-05-18 07:11:12,598 attempt 1/10 2017-05-18 07:11:12,598 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/5a67d4114989?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-18 07:11:13,893 unpacking tar archive at: mozilla-beta-5a67d4114989/testing/mozharness/ program finished with exit code 0 elapsedTime=1.561901 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-18 07:11:14.077683) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 07:11:14.078007) ========= script_repo_revision: 5a67d411498982aec919e3c4d3e045ce3c250d75 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-18 07:11:14.078407) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 07:11:14.078671) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-18 07:11:14.097056) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-18 07:11:14.097386) ========= /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.yw1x8aIdKc/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.cxsGs203Mw/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 07:11:14 INFO - MultiFileLogger online at 20170518 07:11:14 in /builds/slave/test 07:11:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 07:11:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:11:14 INFO - {'append_to_log': False, 07:11:14 INFO - 'base_work_dir': '/builds/slave/test', 07:11:14 INFO - 'blob_upload_branch': 'Mozilla-Beta', 07:11:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:11:14 INFO - 'branch': 'Mozilla-Beta', 07:11:14 INFO - 'buildbot_json_path': 'buildprops.json', 07:11:14 INFO - 'config_files': ('talos/mac_config.py',), 07:11:14 INFO - 'default_actions': ('clobber', 07:11:14 INFO - 'read-buildbot-config', 07:11:14 INFO - 'download-and-extract', 07:11:14 INFO - 'populate-webroot', 07:11:14 INFO - 'create-virtualenv', 07:11:14 INFO - 'install', 07:11:14 INFO - 'run-tests'), 07:11:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:11:14 INFO - 'download_minidump_stackwalk': True, 07:11:14 INFO - 'download_symbols': 'ondemand', 07:11:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:11:14 INFO - 'tooltool.py': '/tools/tooltool.py', 07:11:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:11:14 INFO - '/tools/misc-python/virtualenv.py')}, 07:11:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:11:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:11:14 INFO - 'gecko_profile': False, 07:11:14 INFO - 'gecko_profile_interval': 0, 07:11:14 INFO - 'installer_path': 'installer.exe', 07:11:14 INFO - 'log_level': 'info', 07:11:14 INFO - 'log_name': 'talos', 07:11:14 INFO - 'log_to_console': True, 07:11:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:11:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:11:14 INFO - 'opt_config_files': (), 07:11:14 INFO - 'pip_index': False, 07:11:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:11:14 INFO - 'cmd': ('bash', 07:11:14 INFO - '-c', 07:11:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:11:14 INFO - 'enabled': True, 07:11:14 INFO - 'halt_on_failure': False, 07:11:14 INFO - 'name': 'check_screen_resolution'},), 07:11:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:11:14 INFO - 'cmd': ('bash', 07:11:14 INFO - '-c', 07:11:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:11:14 INFO - 'enabled': True, 07:11:14 INFO - 'halt_on_failure': False, 07:11:14 INFO - 'name': 'check_screen_resolution'},), 07:11:14 INFO - 'run_cmd_checks_enabled': True, 07:11:14 INFO - 'suite': 'dromaeojs', 07:11:14 INFO - 'system_bits': '32', 07:11:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:11:14 INFO - 'title': 't-yosemite-r7-0176', 07:11:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:11:14 INFO - 'use_talos_json': True, 07:11:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:11:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:11:14 INFO - 'work_dir': 'build'} 07:11:14 INFO - [mozharness: 2017-05-18 14:11:14.315525Z] Running clobber step. 07:11:14 INFO - Running pre-action listener: _resource_record_pre_action 07:11:14 INFO - Running main action method: clobber 07:11:14 INFO - rmtree: /builds/slave/test/build 07:11:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:11:16 INFO - Running post-action listener: _resource_record_post_action 07:11:16 INFO - [mozharness: 2017-05-18 14:11:16.908393Z] Finished clobber step (success) 07:11:16 INFO - [mozharness: 2017-05-18 14:11:16.908519Z] Running read-buildbot-config step. 07:11:16 INFO - Running pre-action listener: _resource_record_pre_action 07:11:16 INFO - Running main action method: read_buildbot_config 07:11:16 INFO - Using buildbot properties: 07:11:16 INFO - { 07:11:16 INFO - "project": "", 07:11:16 INFO - "product": "firefox", 07:11:16 INFO - "script_repo_revision": "production", 07:11:16 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 07:11:16 INFO - "repository": "", 07:11:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 07:11:16 INFO - "buildid": "20170518053719", 07:11:16 INFO - "pgo_build": "False", 07:11:16 INFO - "basedir": "/builds/slave/test", 07:11:16 INFO - "buildnumber": 8, 07:11:16 INFO - "slavename": "t-yosemite-r7-0176", 07:11:16 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 07:11:16 INFO - "platform": "yosemite_r7", 07:11:16 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 07:11:16 INFO - "branch": "mozilla-beta", 07:11:16 INFO - "repo_path": "releases/mozilla-beta", 07:11:16 INFO - "revision": "5a67d411498982aec919e3c4d3e045ce3c250d75", 07:11:16 INFO - "stage_platform": "macosx64", 07:11:16 INFO - "builduid": "3e3d5e6c96f94a79b5be8f4b479cc414", 07:11:16 INFO - "slavebuilddir": "test" 07:11:16 INFO - } 07:11:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 07:11:16 INFO - Running post-action listener: _resource_record_post_action 07:11:16 INFO - [mozharness: 2017-05-18 14:11:16.911878Z] Finished read-buildbot-config step (success) 07:11:16 INFO - [mozharness: 2017-05-18 14:11:16.912009Z] Running download-and-extract step. 07:11:16 INFO - Running pre-action listener: _resource_record_pre_action 07:11:16 INFO - Running main action method: download_and_extract 07:11:16 INFO - mkdir: /builds/slave/test/build/tests 07:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:16 INFO - trying https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 07:11:16 INFO - Downloading https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 07:11:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 07:11:18 INFO - Downloaded 1287 bytes. 07:11:18 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 07:11:18 INFO - Using the following test package requirements: 07:11:18 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:11:18 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 07:11:18 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:11:18 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:11:18 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 07:11:18 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:11:18 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 07:11:18 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 07:11:18 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:11:18 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 07:11:18 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 07:11:18 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:11:18 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 07:11:18 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:11:18 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 07:11:18 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:11:18 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 07:11:18 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 07:11:18 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 07:11:18 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 07:11:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:11:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:11:18 INFO - Fetch https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:11:19 INFO - Expected file size: 51225696 07:11:19 INFO - Obtained file size: 51225696 07:11:36 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 07:11:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 07:11:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 07:11:36 INFO - Fetch https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 07:11:37 INFO - Expected file size: 51225696 07:11:37 INFO - Obtained file size: 51225696 07:11:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 07:11:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 07:11:52 INFO - Fetch https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 07:11:53 INFO - Expected file size: 14404449 07:11:53 INFO - Obtained file size: 14404449 07:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:54 INFO - trying https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:11:54 INFO - Downloading https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 07:11:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 07:11:55 INFO - Downloaded 59545204 bytes. 07:11:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:11:55 INFO - mkdir: /builds/slave/test/properties 07:11:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:11:55 INFO - Writing to file /builds/slave/test/properties/build_url 07:11:55 INFO - Contents: 07:11:55 INFO - build_url:https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 07:11:56 INFO - Running post-action listener: _resource_record_post_action 07:11:56 INFO - Running post-action listener: set_extra_try_arguments 07:11:56 INFO - [mozharness: 2017-05-18 14:11:56.852294Z] Finished download-and-extract step (success) 07:11:56 INFO - [mozharness: 2017-05-18 14:11:56.852413Z] Running populate-webroot step. 07:11:56 INFO - Running pre-action listener: _resource_record_pre_action 07:11:56 INFO - Running main action method: populate_webroot 07:11:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:11:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:11:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:11:56 INFO - '--tppagecycles', 07:11:56 INFO - '7'], 07:11:56 INFO - 'tests': ['tsvgm']}}, 07:11:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:11:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:11:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:11:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:11:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:11:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:11:56 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 07:11:56 INFO - 'talos_options': ['--disable-e10s'], 07:11:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:11:56 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 07:11:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:11:56 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 07:11:56 INFO - 'talos_options': ['--disable-e10s'], 07:11:56 INFO - 'tests': ['damp', 'tps']}, 07:11:56 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 07:11:56 INFO - 'tests': ['damp', 'tps']}, 07:11:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:11:56 INFO - 'tests': ['dromaeo_dom']}, 07:11:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:11:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:11:56 INFO - 'tests': ['basic_compositor_video']}, 07:11:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:11:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:11:56 INFO - 'tests': ['a11yr', 07:11:56 INFO - 'ts_paint', 07:11:56 INFO - 'tpaint', 07:11:56 INFO - 'sessionrestore', 07:11:56 INFO - 'sessionrestore_no_auto_restore', 07:11:56 INFO - 'tabpaint']}, 07:11:56 INFO - 'other-e10s': {'tests': ['a11yr', 07:11:56 INFO - 'ts_paint', 07:11:56 INFO - 'tpaint', 07:11:56 INFO - 'sessionrestore', 07:11:56 INFO - 'sessionrestore_no_auto_restore', 07:11:56 INFO - 'tabpaint']}, 07:11:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:11:56 INFO - 'ts_paint', 07:11:56 INFO - 'tpaint', 07:11:56 INFO - 'sessionrestore', 07:11:56 INFO - 'sessionrestore_no_auto_restore', 07:11:56 INFO - 'tabpaint']}, 07:11:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:11:56 INFO - 'ts_paint', 07:11:56 INFO - 'tpaint', 07:11:56 INFO - 'sessionrestore', 07:11:56 INFO - 'sessionrestore_no_auto_restore', 07:11:56 INFO - 'tabpaint']}, 07:11:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:11:56 INFO - 'tests': ['a11yr', 07:11:56 INFO - 'ts_paint', 07:11:56 INFO - 'tpaint', 07:11:56 INFO - 'sessionrestore', 07:11:56 INFO - 'sessionrestore_no_auto_restore', 07:11:56 INFO - 'tabpaint']}, 07:11:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:11:56 INFO - 'tests': ['a11yr', 07:11:56 INFO - 'ts_paint', 07:11:56 INFO - 'tpaint', 07:11:56 INFO - 'sessionrestore', 07:11:56 INFO - 'sessionrestore_no_auto_restore', 07:11:56 INFO - 'tabpaint']}, 07:11:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:11:56 INFO - 'tests': ['tsvgx', 07:11:56 INFO - 'tsvgr_opacity', 07:11:56 INFO - 'tart', 07:11:56 INFO - 'tscrollx', 07:11:56 INFO - 'cart', 07:11:56 INFO - 'tsvg_static']}, 07:11:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:11:56 INFO - 'tsvgr_opacity', 07:11:56 INFO - 'tart', 07:11:56 INFO - 'tscrollx', 07:11:56 INFO - 'cart', 07:11:56 INFO - 'tsvg_static']}, 07:11:56 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 07:11:56 INFO - 'talos_options': ['--disable-e10s'], 07:11:56 INFO - 'tests': ['tp5o']}, 07:11:56 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 07:11:56 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 07:11:56 INFO - 'talos_options': ['--disable-e10s', 07:11:56 INFO - '--xperf_path', 07:11:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:11:56 INFO - 'tests': ['tp5n']}, 07:11:56 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 07:11:56 INFO - 'talos_options': ['--xperf_path', 07:11:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:11:56 INFO - 'tests': ['tp5n']}}, 07:11:56 INFO - 'talos.zip': {'path': '', 07:11:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:11:56 INFO - Running post-action listener: _resource_record_post_action 07:11:56 INFO - [mozharness: 2017-05-18 14:11:56.871109Z] Finished populate-webroot step (success) 07:11:56 INFO - [mozharness: 2017-05-18 14:11:56.871255Z] Running create-virtualenv step. 07:11:56 INFO - Running pre-action listener: _resource_record_pre_action 07:11:56 INFO - Running main action method: create_virtualenv 07:11:56 INFO - Creating virtualenv /builds/slave/test/build/venv 07:11:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 07:11:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:11:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:11:56 INFO - Using real prefix '/tools/python27' 07:11:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:11:57 INFO - Installing distribute.............................................................................................................................................................................................done. 07:12:00 INFO - Installing pip.................done. 07:12:00 INFO - Return code: 0 07:12:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:12:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:12:00 INFO - Reading from file tmpfile_stdout 07:12:00 INFO - Output received: 07:12:00 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 07:12:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:12: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')]} 07:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12: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')]} 07:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b278b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0334cdf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b056d30>, '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 0x10af7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b04bbe8>, '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.yw1x8aIdKc/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.cxsGs203Mw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 07:12:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 07:12:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yw1x8aIdKc/Render', 07:12:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:00 INFO - 'HOME': '/Users/cltbld', 07:12:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:00 INFO - 'LOGNAME': 'cltbld', 07:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:00 INFO - 'MOZ_NO_REMOTE': '1', 07:12:00 INFO - 'NO_EM_RESTART': '1', 07:12:00 INFO - 'PAGER': '/bin/cat', 07:12:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:00 INFO - 'PWD': '/builds/slave/test', 07:12:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:00 INFO - 'SHELL': '/bin/bash', 07:12:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cxsGs203Mw/Listeners', 07:12:00 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 07:12:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:00 INFO - 'USER': 'cltbld', 07:12:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:00 INFO - 'XPC_FLAGS': '0x0', 07:12:00 INFO - 'XPC_SERVICE_NAME': '0', 07:12:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:01 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 07:12:01 INFO - Cleaning up... 07:12:01 INFO - Return code: 0 07:12:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:12:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b278b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0334cdf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b056d30>, '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 0x10af7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b04bbe8>, '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.yw1x8aIdKc/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.cxsGs203Mw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 07:12:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 07:12:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yw1x8aIdKc/Render', 07:12:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:01 INFO - 'HOME': '/Users/cltbld', 07:12:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:01 INFO - 'LOGNAME': 'cltbld', 07:12:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:01 INFO - 'MOZ_NO_REMOTE': '1', 07:12:01 INFO - 'NO_EM_RESTART': '1', 07:12:01 INFO - 'PAGER': '/bin/cat', 07:12:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:01 INFO - 'PWD': '/builds/slave/test', 07:12:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:01 INFO - 'SHELL': '/bin/bash', 07:12:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cxsGs203Mw/Listeners', 07:12:01 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 07:12:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:01 INFO - 'USER': 'cltbld', 07:12:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:01 INFO - 'XPC_FLAGS': '0x0', 07:12:01 INFO - 'XPC_SERVICE_NAME': '0', 07:12:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:01 INFO - Downloading/unpacking psutil>=3.1.1 07:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:12:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:12:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:12:03 INFO - Installing collected packages: psutil 07:12:03 INFO - Running setup.py install for psutil 07:12:03 INFO - building 'psutil._psutil_osx' extension 07:12:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:12:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 07:12:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 07:12:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 07:12:04 INFO - building 'psutil._psutil_posix' extension 07:12:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 07:12:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:12:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:12:04 INFO - ^ 07:12:04 INFO - 1 warning generated. 07:12:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 07:12:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:12:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:12:04 INFO - Successfully installed psutil 07:12:04 INFO - Cleaning up... 07:12:04 INFO - Return code: 0 07:12:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:12:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b278b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0334cdf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b056d30>, '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 0x10af7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b04bbe8>, '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.yw1x8aIdKc/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.cxsGs203Mw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:12:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:12:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yw1x8aIdKc/Render', 07:12:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:04 INFO - 'HOME': '/Users/cltbld', 07:12:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:04 INFO - 'LOGNAME': 'cltbld', 07:12:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:04 INFO - 'MOZ_NO_REMOTE': '1', 07:12:04 INFO - 'NO_EM_RESTART': '1', 07:12:04 INFO - 'PAGER': '/bin/cat', 07:12:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:04 INFO - 'PWD': '/builds/slave/test', 07:12:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:04 INFO - 'SHELL': '/bin/bash', 07:12:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cxsGs203Mw/Listeners', 07:12:04 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 07:12:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:04 INFO - 'USER': 'cltbld', 07:12:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:04 INFO - 'XPC_FLAGS': '0x0', 07:12:04 INFO - 'XPC_SERVICE_NAME': '0', 07:12:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:12:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:12:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:12:06 INFO - Installing collected packages: mozsystemmonitor 07:12:06 INFO - Running setup.py install for mozsystemmonitor 07:12:06 INFO - Successfully installed mozsystemmonitor 07:12:06 INFO - Cleaning up... 07:12:06 INFO - Return code: 0 07:12:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b278b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0334cdf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b056d30>, '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 0x10af7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b04bbe8>, '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.yw1x8aIdKc/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.cxsGs203Mw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 07:12:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 07:12:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yw1x8aIdKc/Render', 07:12:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:06 INFO - 'HOME': '/Users/cltbld', 07:12:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:06 INFO - 'LOGNAME': 'cltbld', 07:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:06 INFO - 'MOZ_NO_REMOTE': '1', 07:12:06 INFO - 'NO_EM_RESTART': '1', 07:12:06 INFO - 'PAGER': '/bin/cat', 07:12:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:06 INFO - 'PWD': '/builds/slave/test', 07:12:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:06 INFO - 'SHELL': '/bin/bash', 07:12:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cxsGs203Mw/Listeners', 07:12:06 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 07:12:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:06 INFO - 'USER': 'cltbld', 07:12:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:06 INFO - 'XPC_FLAGS': '0x0', 07:12:06 INFO - 'XPC_SERVICE_NAME': '0', 07:12:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:06 INFO - Downloading/unpacking jsonschema==2.5.1 07:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:12:08 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:08 INFO - Downloading functools32-3.2.3-2.tar.gz 07:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:12:08 INFO - warning: no files found matching '*.txt' 07:12:08 INFO - no previously-included directories found matching 'build' 07:12:08 INFO - no previously-included directories found matching 'dist' 07:12:08 INFO - no previously-included directories found matching '.git*' 07:12:08 INFO - Installing collected packages: jsonschema, functools32 07:12:08 INFO - Running setup.py install for functools32 07:12:08 INFO - warning: no files found matching '*.txt' 07:12:08 INFO - no previously-included directories found matching 'build' 07:12:08 INFO - no previously-included directories found matching 'dist' 07:12:08 INFO - no previously-included directories found matching '.git*' 07:12:08 INFO - Successfully installed jsonschema functools32 07:12:08 INFO - Cleaning up... 07:12:08 INFO - Return code: 0 07:12:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:09 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 0x10b278b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0334cdf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b056d30>, '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 0x10af7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b04bbe8>, '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.yw1x8aIdKc/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.cxsGs203Mw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:12:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:12:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yw1x8aIdKc/Render', 07:12:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:09 INFO - 'HOME': '/Users/cltbld', 07:12:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:09 INFO - 'LOGNAME': 'cltbld', 07:12:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:09 INFO - 'MOZ_NO_REMOTE': '1', 07:12:09 INFO - 'NO_EM_RESTART': '1', 07:12:09 INFO - 'PAGER': '/bin/cat', 07:12:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:09 INFO - 'PWD': '/builds/slave/test', 07:12:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:09 INFO - 'SHELL': '/bin/bash', 07:12:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cxsGs203Mw/Listeners', 07:12:09 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 07:12:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:09 INFO - 'USER': 'cltbld', 07:12:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:09 INFO - 'XPC_FLAGS': '0x0', 07:12:09 INFO - 'XPC_SERVICE_NAME': '0', 07:12:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:09 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:12:09 INFO - Cleaning up... 07:12:09 INFO - Return code: 0 07:12:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:12:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:10 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 0x10b278b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0334cdf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b056d30>, '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 0x10af7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b04bbe8>, '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.yw1x8aIdKc/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.cxsGs203Mw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:12:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:12:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yw1x8aIdKc/Render', 07:12:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:10 INFO - 'HOME': '/Users/cltbld', 07:12:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:10 INFO - 'LOGNAME': 'cltbld', 07:12:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:10 INFO - 'MOZ_NO_REMOTE': '1', 07:12:10 INFO - 'NO_EM_RESTART': '1', 07:12:10 INFO - 'PAGER': '/bin/cat', 07:12:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:10 INFO - 'PWD': '/builds/slave/test', 07:12:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:10 INFO - 'SHELL': '/bin/bash', 07:12:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cxsGs203Mw/Listeners', 07:12:10 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 07:12:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:10 INFO - 'USER': 'cltbld', 07:12:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:10 INFO - 'XPC_FLAGS': '0x0', 07:12:10 INFO - 'XPC_SERVICE_NAME': '0', 07:12:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:10 INFO - Downloading/unpacking blobuploader==1.2.4 07:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:12 INFO - Downloading blobuploader-1.2.4.tar.gz 07:12:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:12:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:12:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:13 INFO - Downloading docopt-0.6.1.tar.gz 07:12:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:12:13 INFO - Installing collected packages: blobuploader, requests, docopt 07:12:13 INFO - Running setup.py install for blobuploader 07:12:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:12:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:12:13 INFO - Running setup.py install for requests 07:12:14 INFO - Running setup.py install for docopt 07:12:14 INFO - Successfully installed blobuploader requests docopt 07:12:14 INFO - Cleaning up... 07:12:14 INFO - Return code: 0 07:12:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:14 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 0x10b278b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0334cdf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b056d30>, '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 0x10af7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b04bbe8>, '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.yw1x8aIdKc/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.cxsGs203Mw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:12:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:12:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yw1x8aIdKc/Render', 07:12:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:14 INFO - 'HOME': '/Users/cltbld', 07:12:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:14 INFO - 'LOGNAME': 'cltbld', 07:12:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:14 INFO - 'MOZ_NO_REMOTE': '1', 07:12:14 INFO - 'NO_EM_RESTART': '1', 07:12:14 INFO - 'PAGER': '/bin/cat', 07:12:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:14 INFO - 'PWD': '/builds/slave/test', 07:12:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:14 INFO - 'SHELL': '/bin/bash', 07:12:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cxsGs203Mw/Listeners', 07:12:14 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 07:12:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:14 INFO - 'USER': 'cltbld', 07:12:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:14 INFO - 'XPC_FLAGS': '0x0', 07:12:14 INFO - 'XPC_SERVICE_NAME': '0', 07:12:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:14 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-A12GYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:14 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-LQBuz_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:14 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-CjjMIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:12:14 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-OWxtY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:12:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:12:14 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-JeFfRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:12:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-zHI4bF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:12:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-Cf0Z_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:12:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-lklHEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:12:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-vYEcSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:12:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-1v_FiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:12:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-V34geY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:12:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-LpQ2xC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:12:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-8nEFws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:12:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-OiIShW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:12:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:15 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-B4UtFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:12:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-zYU1NR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:12:16 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-jYa81Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:12:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:12:16 INFO - Running setup.py install for manifestparser 07:12:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:12:16 INFO - Running setup.py install for mozcrash 07:12:16 INFO - Running setup.py install for mozdebug 07:12:16 INFO - Running setup.py install for mozdevice 07:12:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:12:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:12:16 INFO - Running setup.py install for mozfile 07:12:17 INFO - Running setup.py install for mozhttpd 07:12:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:12:17 INFO - Running setup.py install for mozinfo 07:12:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:12:17 INFO - Running setup.py install for mozInstall 07:12:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:12:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:12:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:12:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:12:17 INFO - Running setup.py install for mozleak 07:12:17 INFO - Running setup.py install for mozlog 07:12:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:12:17 INFO - Running setup.py install for moznetwork 07:12:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:12:17 INFO - Running setup.py install for mozprocess 07:12:18 INFO - Running setup.py install for mozprofile 07:12:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:12:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:12:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:12:18 INFO - Running setup.py install for mozrunner 07:12:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:12:18 INFO - Running setup.py install for mozscreenshot 07:12:18 INFO - Running setup.py install for moztest 07:12:18 INFO - Running setup.py install for mozversion 07:12:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:12:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:12:18 INFO - Cleaning up... 07:12:18 INFO - Return code: 0 07:12:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:18 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 0x10b278b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0334cdf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b056d30>, '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 0x10af7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b04bbe8>, '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.yw1x8aIdKc/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.cxsGs203Mw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:12:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:12:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yw1x8aIdKc/Render', 07:12:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:18 INFO - 'HOME': '/Users/cltbld', 07:12:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:18 INFO - 'LOGNAME': 'cltbld', 07:12:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:18 INFO - 'MOZ_NO_REMOTE': '1', 07:12:18 INFO - 'NO_EM_RESTART': '1', 07:12:18 INFO - 'PAGER': '/bin/cat', 07:12:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:18 INFO - 'PWD': '/builds/slave/test', 07:12:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:18 INFO - 'SHELL': '/bin/bash', 07:12:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cxsGs203Mw/Listeners', 07:12:18 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 07:12:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:18 INFO - 'USER': 'cltbld', 07:12:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:18 INFO - 'XPC_FLAGS': '0x0', 07:12:18 INFO - 'XPC_SERVICE_NAME': '0', 07:12:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:12:19 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-0G3d7H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:12:19 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-T8wRUg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:12:19 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-DnX52t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:12:19 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-r9CTWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:12:19 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-UF9RmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:12:19 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-B4iK9M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 07:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:12:19 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-C2i7s8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 07:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:12:19 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-i4A2dL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:12:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 07:12:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:12:19 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-QDBESM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:12:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-CHrah4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:12:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-jfgSRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:12:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-H4Y3Ax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 07:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:12:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-7DoW3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:12:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-bKxIaA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-ZcJ2Gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 07:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:12:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-amiZ4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 07:12:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:12:20 INFO - Running setup.py (path:/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/pip-EZN4vs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 07:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:12:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 07:12:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:12:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:12:22 INFO - Downloading blessings-1.6.tar.gz 07:12:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:12:22 INFO - Installing collected packages: blessings 07:12:22 INFO - Running setup.py install for blessings 07:12:22 INFO - Successfully installed blessings 07:12:22 INFO - Cleaning up... 07:12:22 INFO - Return code: 0 07:12:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:12:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:12:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:12:22 INFO - Reading from file tmpfile_stdout 07:12:22 INFO - Current package versions: 07:12:22 INFO - blessings == 1.6 07:12:22 INFO - blobuploader == 1.2.4 07:12:22 INFO - docopt == 0.6.1 07:12:22 INFO - functools32 == 3.2.3-2 07:12:22 INFO - jsonschema == 2.5.1 07:12:22 INFO - manifestparser == 1.1 07:12:22 INFO - mozInstall == 1.12 07:12:22 INFO - mozcrash == 1.0 07:12:22 INFO - mozdebug == 0.1 07:12:22 INFO - mozdevice == 0.49 07:12:22 INFO - mozfile == 1.2 07:12:22 INFO - mozhttpd == 0.7 07:12:22 INFO - mozinfo == 0.9 07:12:22 INFO - mozleak == 0.1 07:12:22 INFO - mozlog == 3.4 07:12:22 INFO - moznetwork == 0.27 07:12:22 INFO - mozprocess == 0.25 07:12:22 INFO - mozprofile == 0.28 07:12:22 INFO - mozrunner == 6.13 07:12:22 INFO - mozscreenshot == 0.1 07:12:22 INFO - mozsystemmonitor == 0.3 07:12:22 INFO - moztest == 0.8 07:12:22 INFO - mozversion == 1.4 07:12:22 INFO - psutil == 3.1.1 07:12:22 INFO - requests == 1.2.3 07:12:22 INFO - wsgiref == 0.1.2 07:12:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:22 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 0x10b278b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0334cdf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b056d30>, '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 0x10af7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b04bbe8>, '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.yw1x8aIdKc/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.cxsGs203Mw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 07:12:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:12:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yw1x8aIdKc/Render', 07:12:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:22 INFO - 'HOME': '/Users/cltbld', 07:12:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:22 INFO - 'LOGNAME': 'cltbld', 07:12:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:22 INFO - 'MOZ_NO_REMOTE': '1', 07:12:22 INFO - 'NO_EM_RESTART': '1', 07:12:22 INFO - 'PAGER': '/bin/cat', 07:12:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:22 INFO - 'PWD': '/builds/slave/test', 07:12:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:22 INFO - 'SHELL': '/bin/bash', 07:12:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cxsGs203Mw/Listeners', 07:12:22 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 07:12:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:22 INFO - 'USER': 'cltbld', 07:12:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:22 INFO - 'XPC_FLAGS': '0x0', 07:12:22 INFO - 'XPC_SERVICE_NAME': '0', 07:12:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 07:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 07:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 07:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 07:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 07:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 07:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 07:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 07:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 07:12:23 INFO - Cleaning up... 07:12:23 INFO - Return code: 0 07:12:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:12:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:12:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10b278b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b27b768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc0334cdf00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b056d30>, '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 0x10af7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b04bbe8>, '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.yw1x8aIdKc/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.cxsGs203Mw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 07:12:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:12:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yw1x8aIdKc/Render', 07:12:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:23 INFO - 'HOME': '/Users/cltbld', 07:12:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:23 INFO - 'LOGNAME': 'cltbld', 07:12:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:23 INFO - 'MOZ_NO_REMOTE': '1', 07:12:23 INFO - 'NO_EM_RESTART': '1', 07:12:23 INFO - 'PAGER': '/bin/cat', 07:12:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:23 INFO - 'PWD': '/builds/slave/test', 07:12:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:23 INFO - 'SHELL': '/bin/bash', 07:12:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cxsGs203Mw/Listeners', 07:12:23 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 07:12:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:23 INFO - 'USER': 'cltbld', 07:12:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:23 INFO - 'XPC_FLAGS': '0x0', 07:12:23 INFO - 'XPC_SERVICE_NAME': '0', 07:12:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:12:23 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:12:23 INFO - Cleaning up... 07:12:23 INFO - Return code: 0 07:12:23 INFO - Running post-action listener: _resource_record_post_action 07:12:23 INFO - Running post-action listener: _start_resource_monitoring 07:12:23 INFO - Starting resource monitoring. 07:12:23 INFO - [mozharness: 2017-05-18 14:12:23.557534Z] Finished create-virtualenv step (success) 07:12:23 INFO - [mozharness: 2017-05-18 14:12:23.558476Z] Running install step. 07:12:23 INFO - Running pre-action listener: _resource_record_pre_action 07:12:23 INFO - Running main action method: install 07:12:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:12:23 INFO - Reading from file tmpfile_stdout 07:12:23 INFO - Detecting whether we're running mozinstall >=1.0... 07:12:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:12:23 INFO - Reading from file tmpfile_stdout 07:12:23 INFO - Output received: 07:12:23 INFO - Usage: mozinstall [options] installer 07:12:23 INFO - Options: 07:12:23 INFO - -h, --help show this help message and exit 07:12:23 INFO - -d DEST, --destination=DEST 07:12:23 INFO - Directory to install application into. [default: 07:12:23 INFO - "/builds/slave/test"] 07:12:23 INFO - --app=APP Application being installed. [default: firefox] 07:12:23 INFO - mkdir: /builds/slave/test/build/application 07:12:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:12:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 07:12:38 INFO - Reading from file tmpfile_stdout 07:12:38 INFO - Output received: 07:12:38 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 07:12:38 INFO - Running post-action listener: _resource_record_post_action 07:12:38 INFO - [mozharness: 2017-05-18 14:12:38.521534Z] Finished install step (success) 07:12:38 INFO - [mozharness: 2017-05-18 14:12:38.521635Z] Running run-tests step. 07:12:38 INFO - Running pre-action listener: _resource_record_pre_action 07:12:38 INFO - Running main action method: run_tests 07:12:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:12:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:12:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:12:38 INFO - 2017-05-18 07:12:38.536 screenresolution[1629:7900] starting screenresolution argv=screenresolution get 07:12:38 INFO - 2017-05-18 07:12:38.538 screenresolution[1629:7900] Display 0: 1600x1200x32@60 07:12:38 INFO - 2017-05-18 07:12:38.544 screenresolution[1630:7902] starting screenresolution argv=screenresolution list 07:12:38 INFO - Available Modes on Display 0 07:12:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:12:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:12:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:12:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:12:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:12:38 INFO - Intel Iris: 07:12:38 INFO - Chipset Model: Intel Iris 07:12:38 INFO - Type: GPU 07:12:38 INFO - Bus: Built-In 07:12:38 INFO - VRAM (Dynamic, Max): 1536 MB 07:12:38 INFO - Vendor: Intel (0x8086) 07:12:38 INFO - Device ID: 0x0a2e 07:12:38 INFO - Revision ID: 0x0009 07:12:38 INFO - Displays: 07:12:38 INFO - Display: 07:12:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:12:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:12:38 INFO - Display Serial Number: 1600x1200 60 07:12:38 INFO - Main Display: Yes 07:12:38 INFO - Mirror: Off 07:12:38 INFO - Online: Yes 07:12:38 INFO - Rotation: Supported 07:12:38 INFO - Return code: 0 07:12:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:12:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:12:38 INFO - Python 2.7.3 07:12:38 INFO - Return code: 0 07:12:38 INFO - grabbing minidump binary from tooltool 07:12:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:12:38 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 0x10b056d30>, '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 0x10af7ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b04bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:12:38 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 07:12:38 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 07:12:38 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 07:12:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:12:38 INFO - Return code: 0 07:12:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:12:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:12:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:12:38 INFO - ENV: RUST_BACKTRACE is now 1 07:12:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:12:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:12:38 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/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 07:12:38 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/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0176 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 07:12:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yw1x8aIdKc/Render', 07:12:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:12:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:12:38 INFO - 'HOME': '/Users/cltbld', 07:12:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:12:38 INFO - 'LOGNAME': 'cltbld', 07:12:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:12:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:12:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:12:38 INFO - 'MOZ_NO_REMOTE': '1', 07:12:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:12:38 INFO - 'NO_EM_RESTART': '1', 07:12:38 INFO - 'PAGER': '/bin/cat', 07:12:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:12:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:12:38 INFO - 'PWD': '/builds/slave/test', 07:12:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:12:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:12:38 INFO - 'RUST_BACKTRACE': '1', 07:12:38 INFO - 'SHELL': '/bin/bash', 07:12:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.cxsGs203Mw/Listeners', 07:12:38 INFO - 'TMPDIR': '/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/', 07:12:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:12:38 INFO - 'USER': 'cltbld', 07:12:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:12:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:12:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:12:38 INFO - 'XPC_FLAGS': '0x0', 07:12:38 INFO - 'XPC_SERVICE_NAME': '0', 07:12:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:12:38 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/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0176', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 07:12:39 INFO - mozversion application_buildid: 20170518053719 07:12:39 INFO - mozversion application_changeset: 5a67d411498982aec919e3c4d3e045ce3c250d75 07:12:39 INFO - mozversion application_display_name: Firefox 07:12:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:12:39 INFO - mozversion application_name: Firefox 07:12:39 INFO - mozversion application_remotingname: firefox 07:12:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 07:12:39 INFO - mozversion application_vendor: Mozilla 07:12:39 INFO - mozversion application_version: 54.0 07:12:39 INFO - mozversion platform_buildid: 20170518053719 07:12:39 INFO - mozversion platform_changeset: 5a67d411498982aec919e3c4d3e045ce3c250d75 07:12:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 07:12:39 INFO - mozversion platform_version: 54.0 07:12:39 INFO - using testdate: 1495116759 07:12:39 INFO - actual date: 1495116759 07:12:39 INFO - starting webserver on 'localhost:49225' 07:12:39 INFO - SUITE-START | Running 2 tests 07:12:39 INFO - TEST-START | dromaeo_css 07:12:39 INFO - operating with platform_type : mac_ 07:12:39 INFO - Initialising browser for dromaeo_css test... 07:12:39 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmprgX654/profile http://localhost:49225/getInfo.html) 07:12:41 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 07:12:41 INFO - PROCESS | 1640 | colorDepth:24 07:12:41 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 07:12:41 INFO - PROCESS | 1640 | __metrics 07:12:41 INFO - PROCESS | 1640 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:12:41 INFO - PROCESS | 1640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:12:42 INFO - TEST-INFO | 1640: exit 0 07:12:42 INFO - Browser initialized. 07:12:42 INFO - Running cycle 1/1 for dromaeo_css test... 07:12:42 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmprgX654/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:12:54 INFO - PROCESS | 1643 | RSS: Main: 165113856 07:12:54 INFO - PROCESS | 1643 | 07:15:58 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49225/tests/dromaeo/cssquery-ext.html) 07:16:00 INFO - PROCESS | 1643 | RSS: Main: 317739008 07:16:00 INFO - PROCESS | 1643 | 07:19:03 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-ext.html (next: http://localhost:49225/tests/dromaeo/cssquery-jquery.html) 07:19:04 INFO - PROCESS | 1643 | RSS: Main: 301805568 07:19:04 INFO - PROCESS | 1643 | 07:22:06 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49225/tests/dromaeo/cssquery-mootools.html) 07:22:06 INFO - PROCESS | 1643 | RSS: Main: 298602496 07:22:06 INFO - PROCESS | 1643 | 07:25:09 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49225/tests/dromaeo/cssquery-prototype.html) 07:25:09 INFO - PROCESS | 1643 | RSS: Main: 269344768 07:25:09 INFO - PROCESS | 1643 | 07:28:13 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49225/tests/dromaeo/cssquery-yui.html) 07:28:14 INFO - PROCESS | 1643 | RSS: Main: 267956224 07:28:14 INFO - PROCESS | 1643 | 07:31:17 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49225/tests/dromaeo/cssquery-yui.html (next: http://localhost:49225/tests/dromaeo/cssquery-dojo.html) 07:31:17 INFO - PROCESS | 1643 | RSS: Main: 416825344 07:31:17 INFO - PROCESS | 1643 | 07:31:17 INFO - PROCESS | 1643 | __start_tp_report 07:31:17 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 07:31:17 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 07:31:17 INFO - PROCESS | 1643 | |i|pagename|runs| 07:31:17 INFO - PROCESS | 1643 | |0;dojo.html;3152.243461569223;3587.3901436755764;3591.8204089795513;3629.0927268182954;3634.1096431374317;4958.562017015061;6120.724567394467;6139.079138129283;6165.845853853656;6187.78342758003;6124.020156774917;6168.105624684418;6174.845628859281;6188.628682279055;6201.410865967726;5504.64219825712;5531.834044978657;5543.334799824027;5603.663780173182;5682.374938756733;5426.403095659474;5427.3215848019;5469.535089517396;5503.862403439906;5649.209110724501;5512.80705175319;5514.972425137868;5516.365617953927;5519.69641669708;5521.5306698930635;5504.2294078829;5507.5593952483705;5524.14375771756;5526.806561770319;5533.3083364579425;5363.329583802025;5463.508284254436;5472.425395333512;5496.31296087989;5499.092649712793;6137.591499645469;6139.093519790236;6140.7189816757555;6144.9553575892205;6161.661108639023;6522.15212022434;6522.706478208444;6524.641144737037;6539.93460065398;6547.869042619169;6623.53635245533;6637.269900310962;6640.169978752656;6643.402093811532;6653.53425260232;5166.948330516684;6114.91462006927;6170.938290617126;6308.716107775161;6335.429811317003;5699.316082070095;6551.475881929434;6692.498062645321;6739.494537909676;6748.122744043243;6500.284968653445;6507.121538592324;6511.967440162864;6512.511561632802;6523.575967562093;6634.402903738686;6640.0703901453835;6642.402183803384;6654.900176497356;6668.0331351954665;6300.558960872695;6337.2395312562785;6456.580322279131;6472.611643301323;6490.772822951174;6550.672466376662;6559.409653131241;6573.967130164318;6578.881590129689;6582.210134783857;5243.397009343974;5297.973510132501;5298.682079075268;5301.973490132525;5307.177387504866;4442.911141777211;4613.561711637389;4653.744044077541;4662.137504561667;4713.670043097019;6039.396060394013;6075.908861367083;6086.847828804315;6259.436650701458;6282.685865706788;5546.584006199551;6577.3422657733845;6626.56927299724;6635.469162466991;6652.7006284716335;6202.5658203925295;6224.564280500412;6262.060690896311;6276.592021518587;6287.811365659038;5637.915431268534;5674.290713660793;5836.824895253149;5846.561507886926;5846.649201047951;6626.204855417381;6739.494537909774;6772.221194562689;6838.076859623887;6874.0032695257905;6651.800445986628;6656.134702488645;6656.30108838565;6661.16735408074;6666.799995999933;6795.116634837439;6809.897851532132;6824.112865327675;6834.589924604539;6851.9657401712675;6336.619802811846;6359.841003974938;6398.104265402941;6435.903461447989;6505.154329937078;5722.399148089391;6400.9039864403;6572.375624315682;6608.306127856506;6620.966895165493;6266.749330026748;6375.713092910737;6393.712282947372;6456.7740129095255;6468.547201696025;6265.530085243534;6341.524385671001;6372.904406433814;6444.355564443518;6455.61266324022;6305.085762564494;6336;6339.144215531029;6344.270408903035;6351.015592583157;6424.2933277338525;6439.903401448888;6456;6456.709448074942;6457.483401327789;6174.475169610633;6234.189555357774;6252.9374706254175;6283.654399008098;6345.96827015862 07:31:17 INFO - PROCESS | 1643 | |1;ext.html;787.3071696906721;794.4240425691374;824.3775949158385;900.3157600223828;903.3270213690793;30042.549361759564;41573.58426415735;41911.37132943005;41941.95145121373;42060.36909446361;43757.56242437575;43871;44024.11951760971;44055.33916991248;44196;7915.96042019792;8343.374246931475;8588.441751286178;9602.663906763248;9754.268429867789;8099.311558517533;11635.24370915892;11793.587224447147;12119.697007574838;12130.332831694253;11799.882001180007;11895.405229738522;11906.928376446118;11916.7616647667;11921.642350729491;11795.764084718257;11846.585369512026;11848.170628055996;11854.466549005316;11862.881371186264;11566.074714022858;11604.30374177552;11609.767804643945;11612;11629.302241865515;41060.58939410598;41267.79366103181;41271.7936410316;41325.79337103295;41349.79325103355;3687.9858039039477;3778.8110594470168;4015.3374693175883;4021.77880216588;4037.777922214277;28831.56752648712;29412;30694.386112277854;30915.22711932197;30960.38079238425;3273.0835366097294;3308.4871844864083;3325.085601459594;3438.9828050859837;3446.2418267981257;324999;329501;329970;331765;335356;53448.73275633675;53639;53701.462985369646;53741.73129134408;53797;55301.893962121336;55322;55506;55512;55548.88902221933;15069.924650376897;15388.538343849703;15391.384344626313;15402.383904643913;15411.383544658313;11644.3595602241;13218.537351192663;13223.933880330536;13228.338583070808;13228.537001204912;10679.519421626046;10708.78582428347;10728.678139655824;10733.463326833627;10736.087432568163;4862.334474772805;5452.727363631802;5532.30846144232;5569.916451253234;5619.016672082369;1172.4489489939715;1175.3594291111365;1180.740237147826;1195.3126952002633;1196.0073139294366;27662.845960301995;27887.302817429725;27903.16290511288;28022.71977280201;28107;1269.4795133995017;1275.5981865712456;1276.9297688627214;1322.4842311498523;1324.9602511924659;14039.719205615831;14312.49906253297;14466.718220212906;14471;14658.486952956808;471553;472216;472452;473811;473906;48529;49348.75325623421;57426;58065;61224.693876530335;1489.8882583806258;1527.6868242010362;1527.8472152784632;1533.9309731061905;1534.539638108563;29627.963021294152;30767.69232307738;30874.30191339408;31278.53082203723;31477.685223147477;47339.579812605676;47535.81160471016;47557.28664070111;47584.7620761894;47596.7620161897;465338;465356;465662;465752;466497;34898.82550587333;35650.46524302086;35718.821405892806;35751.64248357587;35776;36735.265294694495;45034.09931801411;45140;45999;46983.53016469791;25951.221463355374;31343.529847052767;31499.52750708787;31526.527102093027;31586.684133158375;23039;29126.1262162127;30509.237269067562;30535.694643053284;31542.36915261636;34584;35205;35878.103047424025;35925.46111808273;36341.63658363382;28828;29215.7078429213;30089.097327080224;30127.849360753054;30128.849355753082;22870.742228171683;23835.06721390951;24078.39804004914;24313.878430607732;24321.75678243195 07:31:17 INFO - PROCESS | 1643 | |2;jquery.html;436.4958472963728;444.19600523053276;448.78234056482603;449.2856358390159;458.9013362127144;19170;21396.35810925673;22055;22605.660915086246;22882;23100.075996960142;23670.289891303244;24245.51508969824;24345.513089738193;24621.507569848593;6928.48036397271;7230.457715671319;7236.71053157873;7612.809679757995;7923.801904952394;7672.386209103286;7796.493227940194;7955.72154974576;7961.840763184733;7975.32209762168;6701.3298670132845;7852.685892564291;7954.244346787046;7963.960180199091;7977.481463704869;6642.169728783926;7693.846123077535;7899.921000790005;7971.880421793678;7983.640736166886;7502.099748030217;7644.961775191144;7718.151003389623;7785.416093792932;7790.649420776022;22248.332550023526;25886.093986710563;27149.50677712725;27439.45121097587;27662.86168569144;2982.373701522683;2988.7907846450757;3097.1792474994145;3105.736012438945;3166.794158379698;16072.116033618146;16254.75617865733;16350.10074445905;16383.918080409641;17745.29018839245;2131.79747923947;2192.9122835086578;2205.106931692651;2231.921882734113;2311.167979527369;100142;135708.28583428275;177725;178102;179704.10147949308;19600.70598941017;20245.595088098155;20657.89671051635;20849.79150208509;20890.95545222733;31761.04716858498;32031.839840800647;32326;32511;32563.185920351705;10488.63289784869;10575.312604680672;10752.354858708502;10765.730856728487;10950.123990080774;9011.774705632411;9027.954860225656;9058;9210.493422861808;9218.492982885871;7594.544327340377;9323.20752736025;9482.525873706287;9488.28837837165;9520.761980950532;4072.959270407258;4108.506979162519;4146.5024197096545;4149.792510374469;4162.167566486714;934.8083642853198;1091.3342860854873;1091.7871015151968;1120.8679233973583;1135.5571327182406;20417.081231344626;21874;22922.7707722924;22976.65535016976;22982;899.9280057595377;988.604558176735;1010.0858722855827;1143.5825923538016;1146.873843877173;9735.853962190573;11541.701769165831;11738;11750.177487575787;11776.70558236051;771897;779428;790170.0982990211;798781;800239;145979.5402045987;161500.15499534798;167209;167557;168117;1219.7682440336307;1247.781638213309;1274.5539061328461;1275.3304515129446;1275.5790589105388;19771.308004219783;19826.40520784379;19927.800721993175;20113.09491072876;20176.192952281745;62059.6897015512;62104.689476552325;62303;62308.37691623207;62613.37386626076;74598;88335;90697;91773;99266.5036674812;11515.25150865191;11607.941960290145;11674.053937906932;11761.529538818351;11949;11033.337999720043;11438.656840294805;13026.80459793085;13160.341982901009;13500.5274815381;11010.064144547803;11161.497732602214;11354.205205635526;11475.885241147482;11595;10330.070293673605;10433.9478302608;10496.737581560217;10705.143588513056;10872.728181795203;11508;11542.942285288857;11828.112891532997;11905.523779049088;11931.582394616147;9764.707058788248;11618.825717614412;11700.2979821211;11811.291322520676;11930.343831089373;10279.972002799661;10887.78224435491;11218.158638102012;11444.484998174936;11739.765204696028 07:31:17 INFO - PROCESS | 1643 | |3;mootools.html;1416.5537855575499;1621.7810595569595;1646.5389690886557;1649.4474351092383;1655.9917200413995;9676.225901927846;10120.949395253032;10132.797344053115;10296.330738501993;10319.84520232197;10703.250772445932;11009.889901100987;11018.889811101886;11255.887441125586;11283.37941413224;2474.2206205045445;2514.245726282117;2515.5220508103494;2515.76100270474;2519.1938579654516;2067.7311949446553;2367.396313939943;2502.5370306493264;2507.523570521595;2511.7488251174827;2146.64580344243;2424.369663887393;2500.6624105745673;2503.68703912011;2504.536660717773;2468.037465388482;2483.838550494212;2487.0176280369333;2488;2494.6756921600168;2439.573074711936;2456.65606815046;2466.4943686544216;2469.703635563744;2481.4540801023927;11389.145814063979;11535.596254131066;11540.538378464851;11556;11595.01442377399;5977.3723759005115;6165.075238714228;6167.105769663419;6167.629942203482;6168.321484636686;3717.8141092945516;3789.7157713171623;3854.2291541691775;3870.4774855394444;3948.1511475033008;1257.5912828330738;1272.6881913931054;1277.9933715273817;1313.238321773369;1319.564543700586;13205.405756740874;14316.928415357961;14930.47743328989;16072.678546429006;16075;4933.580645645088;5189.247559103909;5204.71374074422;5232.869178270497;5239.502247286578;11950.760984780256;12015.279083255033;12023.63929082129;12048.578299759467;12056.578019769266;6113.20528331323;6123;6125.663088530174;6128.662923539159;6131.785387511505;5350.277712508761;5489.094299440548;5610.859728506738;5611.663300202001;5620.494155526022;3626.5285512883684;3940.0499747180966;4083.714140010171;4090.2433049885867;4138.772367519755;1002.2382988928467;1071.6356438810842;1073.1629329123296;1090.7927493776315;1093.4696672114019;777.8833175023678;833.9874901876477;956.6269155029544;978.7406337320616;1014.8376259798397;5528.364238112588;5997.550183736237;6108.633481991094;6599.274079851212;6804.8298792529595;1031.664708969588;1032.7314898126542;1051.0908064524285;1053.373242920461;1123.1688550472754;4102.897427564275;4229.661627069827;4249.171411574775;4314.1371725655;4468.419105516327;13988.370523326475;14612.707745845024;14621.19583422922;14637.780433293508;14647.267636618126;13943.790843137362;14188.645283867985;14474.203918784357;14518.128912265298;14526.854731452762;10665.573377064986;10757.408342541079;10766.300190487593;10790;10894.618688346029;6627.768028118992;6637.2699003108655;6670.166229221348;6752.763653272308;6773.93226067733;12293.323867187391;12931.806022909852;12964.092513523963;12974.286414247306;12987.870121299044;13046.282454465096;13062.673433164247;13069.477220911058;13133.277669728257;13318.467261309439;9389.154976052188;9660.178884794872;9686.612535498502;9687.370320929398;9688.12806847387;10574;10586;10598.25812193139;10669.093127084285;10698;9003.324750643596;9116.589753460978;9152.862707059532;9225.308101892519;9357.298202634693;8943.463392196489;9014.053524379846;9033.638654453749;9048.049954754655;9109.498977556297;8092.150324215872;8261.256486916207;8263.049749278909;8273.627686753991;8294.336453083859;5938.524918006636;5944.613600115978;6020;6127.662978536045;6152;5754;5754.223179870832;5912.852178695567;6015.2781666200335;6134.723937422737 07:31:17 INFO - PROCESS | 1643 | |4;prototype.html;295.58175182117304;391.7199202570163;393.311704517095;399.24542614458676;400.39140506430243;5172.612054095948;5392.892142157155;5396.8111116110895;5403.730433218057;5422.728863556821;4599.436191694823;5060.443351231362;5284.788608455658;5284.894302113956;5304;4171.916561668772;4325.113351762881;4411.169364713656;4420.182266280733;4453.599176074153;3704.68510176634;4065;4423.535528769481;4442.73343599385;4469.955300446986;4061.6547593454443;4151.433423234433;4467.687261891669;4475.1944649963;4484.372187893681;3957.8720064781687;4166.729162604436;4364.210673623831;4427.402300689398;4435.828382706348;3648.616895226016;3761.586225515191;3920.313945059632;4058.857939972087;4108.253992053395;4415.918100065504;4595.0591621362355;4716.528347165256;4942.515526553807;5254.710990895499;4451.399061126771;5065.442801291855;5094.184930411154;5151.5106064923775;5183.792648294064;4718.433787945434;5623.437656234385;5630.4369563043365;5681.062624666925;5741.856453588652;4429.357743127261;4602.493725690171;4630.976845115787;4643.349931009661;4649.3026046093355;4883.315256236664;5580.414056524047;5615.444972537526;5676.375598684142;5920.230370051909;3794.937417523098;4247.469066366704;4560.016392869046;5090.211017292326;5177.58579313654;4777.856664300077;5243.475652434726;5246.842594722164;5257.132573125392;5269.920951185735;5008.899822003613;5309.973450132725;5392.865178370572;5436.429174936575;5509.889802203934;5661.66030038199;5674.963894316521;5692.260006199168;5700.629459085146;5737.282839645044;1629.7636842657985;1674.6399524102376;1706.67573161099;1779.146009915248;1808.2766893242808;1169.0589075793898;1290.341925617923;1374.979375309371;1391.547746982235;1452.9175764055847;4748.525147485224;4755.881102972384;5281.551068159172;5291.232771248147;5305.62860599762;5138.614603904647;5797;5808.651480911159;5870.53035757147;5892.410758924073;4602.976985115053;5125.282460455539;5191.082166330387;5228.312060610236;5237.816676416307;2479.652848601197;2850.401415702684;3033.7572994160414;3042.6044614200014;3046.573479712842;5327.936436549;5450.367187890261;5652.3217213934595;5978.491828194652;6099.523271735157;5368;5985.850353741104;6008.969955150284;6049.637021778707;6091.7563297467605;5671.092625180034;5888.263967004124;5976.8804623908145;6092.6543280837195;6126.4575201291855;5370.19447082925;5432.614683255744;5468.9179662304305;5618.2134501170685;5876;4319.524852266311;5633.577481688808;5815.41845815415;5824.330202026652;5870;5933.821985340447;5988.1675727535285;6025.228394938377;6030.788922387871;6086.391360863879;3956.7644280981153;4367.497737760198;4511.639068874417;4603.861884143481;4671.874297194836;4789.473157952692;5238.721156297023;5912.231409916683;5969.358426432766;5976.581639285208;4644.674872758875;5696.914546281892;5739.770409183586;5744.281964754406;5756.21557317233;5695.715214239189;5725.0267454531995;5735.619717817226;5736.311642602915;5773.134029895549;4946.40313835225;4981.37732783402;5195;5492.533134683436;5545.223668686468;5266.499682530153;5419.127862233023;5615.887682246251;5654.660720356792;5722.341930677859;4778.665493415567;5396.379416367168;5577;5601.831945041656;5612.165856242571 07:31:17 INFO - PROCESS | 1643 | |5;yui.html;760.2093822424679;792.4769652029662;821.942464027518;891.2335391563255;906.6328137104471;15678.608034799121;16418.097004664738;16644.16779161043;17211.053392063448;17394.304227830886;15860.286287117078;15929.522114336589;16129.354825806982;16166.191690415462;16638.001719896816;998.8474836726865;1025.1183981775666;1025.5077562769839;1025.7897131088146;1026.7484466305766;976.692341912299;989.7624570103195;1009.7021378693303;1009.7929924365524;1010.1867996263005;1063.4257500949477;1066.248294952057;1067.01301296301;1068.385678235014;1068.7595291059527;1064.6273804168518;1064.9467526623716;1065.6270305393089;1066.3761699405857;1066.9626563070333;784.5802495664792;787.3465024030041;787.9290863822283;788.0582703669118;788.3811208201535;17470;17476.737848932276;17544.38594649194;18234.817651823443;18489.7226541602;14730.263486825615;14982.250887455693;15056.924715376463;15086.773698394532;15329.386824527006;12198.634040978784;12201.816972745328;12335.5065797368;12347.073969452327;12426.813597796041;3767.5290588676417;3874.6512813846607;3875.302445559798;3958.1489979654225;3958.44581758554;106502.40246396232;116982.8301716989;119667;120873.39563302127;120947.39526302312;10711.78576428467;11564.248323858923;11600.94199528997;11637.301761894314;12249.265044097383;18595.628087438174;22391.664125038136;22629.660555091687;22901.770982290298;22958.081676733076;8025.3980951428875;8117.8376432472205;8163.55100469468;8188.713395031145;8324.29243514296;3939.3411190517463;4594.609458196024;4625.791839367236;4796.832110876103;4892.241702536113;3559.768615040014;3631.94552081719;3641.4719865619336;3754.568224654145;3769.378052621342;561.6237121128853;678.1794029224595;681.6182937554886;693.3517161454057;702.4520873718508;560.6327855254815;590.3240789296232;597.7937611524018;617.5677026081761;624.5066397545888;10028.548715307828;10566.841497377545;10672.78654426907;11042.227044106996;11241.269317494336;692.4114502672788;733.083645443196;746.2574738135589;747.9102507699002;767.0833354141747;4731.810727570859;5225;5246.422893481714;5248.6588371755715;5257.342832145981;103972;104152.47923760333;105887;106317.34206644895;108648;73131;87746.56126719453;93630;94893;95349.09301814063;49656.50343496519;52374.73812630989;53557.747652978775;68877;74034.62982685052;9471.573779180091;11058.834117488082;11186.384748838893;11373;11519.76960460803;50099;51675.483245167066;51868.96262074813;52919.20621190762;53039.73480132574;66015.66992165009;66128;69038.65480672565;72798.63600681962;74145;13239.536616218771;13677.658058548615;13749.518766843112;13874.95937804689;14377.56867293983;15713.450029248921;15970.840291596935;16360.754588680942;16369.017858928502;16853.494395167672;14364.784528231876;14535.636609084435;14552;14944;15132.243387830784;14197.290135493391;14737.1894545801;14740.189289589176;14793.334299956312;15055.774163387778;13955.23246221427;14370.928145359207;14475.420983160555;14491.275436227936;15001.474948376754;10432.269741118353;10607.469626518798;10694.41180735067;10755.085817705583;10983.505742241778;9654.565544550653;10703.464826758474;11572.24780389272;11858;11881.346525941157 07:31:17 INFO - PROCESS | 1643 | __end_tp_report 07:31:17 INFO - PROCESS | 1643 | __start_cc_report 07:31:17 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,1452 07:31:17 INFO - PROCESS | 1643 | __end_cc_report 07:31:17 INFO - PROCESS | 1643 | __startTimestamp1495117877582__endTimestamp 07:31:17 INFO - PROCESS | 1643 | 07:31:17 INFO - PROCESS | 1643 | ------- Summary: start ------- 07:31:17 INFO - PROCESS | 1643 | Number of tests: 6 07:31:17 INFO - PROCESS | 1643 | 07:31:17 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:6074.68 Median:6300.56 stddev:692.45 (11.0%) stddev-sans-first:655.52 07:31:17 INFO - PROCESS | 1643 | Values: 3152.2 3587.4 3591.8 3629.1 3634.1 4958.6 6120.7 6139.1 6165.8 6187.8 6124.0 6168.1 6174.8 6188.6 6201.4 5504.6 5531.8 5543.3 5603.7 5682.4 5426.4 5427.3 5469.5 5503.9 5649.2 5512.8 5515.0 5516.4 5519.7 5521.5 5504.2 5507.6 5524.1 5526.8 5533.3 5363.3 5463.5 5472.4 5496.3 5499.1 6137.6 6139.1 6140.7 6145.0 6161.7 6522.2 6522.7 6524.6 6539.9 6547.9 6623.5 6637.3 6640.2 6643.4 6653.5 5166.9 6114.9 6170.9 6308.7 6335.4 5699.3 6551.5 6692.5 6739.5 6748.1 6500.3 6507.1 6512.0 6512.5 6523.6 6634.4 6640.1 6642.4 6654.9 6668.0 6300.6 6337.2 6456.6 6472.6 6490.8 6550.7 6559.4 6574.0 6578.9 6582.2 5243.4 5298.0 5298.7 5302.0 5307.2 4442.9 4613.6 4653.7 4662.1 4713.7 6039.4 6075.9 6086.8 6259.4 6282.7 5546.6 6577.3 6626.6 6635.5 6652.7 6202.6 6224.6 6262.1 6276.6 6287.8 5637.9 5674.3 5836.8 5846.6 5846.6 6626.2 6739.5 6772.2 6838.1 6874.0 6651.8 6656.1 6656.3 6661.2 6666.8 6795.1 6809.9 6824.1 6834.6 6852.0 6336.6 6359.8 6398.1 6435.9 6505.2 5722.4 6400.9 6572.4 6608.3 6621.0 6266.7 6375.7 6393.7 6456.8 6468.5 6265.5 6341.5 6372.9 6444.4 6455.6 6305.1 6336.0 6339.1 6344.3 6351.0 6424.3 6439.9 6456.0 6456.7 6457.5 6174.5 6234.2 6252.9 6283.7 6346.0 07:31:17 INFO - PROCESS | 1643 | 07:31:17 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:56894.79 Median:164136.04 stddev:113506.87 (69.2%) stddev-sans-first:113747.11 07:31:17 INFO - PROCESS | 1643 | Values: 787.3 794.4 824.4 900.3 903.3 30042.5 41573.6 41911.4 41942.0 42060.4 43757.6 43871.0 44024.1 44055.3 44196.0 7916.0 8343.4 8588.4 9602.7 9754.3 8099.3 11635.2 11793.6 12119.7 12130.3 11799.9 11895.4 11906.9 11916.8 11921.6 11795.8 11846.6 11848.2 11854.5 11862.9 11566.1 11604.3 11609.8 11612.0 11629.3 41060.6 41267.8 41271.8 41325.8 41349.8 3688.0 3778.8 4015.3 4021.8 4037.8 28831.6 29412.0 30694.4 30915.2 30960.4 3273.1 3308.5 3325.1 3439.0 3446.2 324999.0 329501.0 329970.0 331765.0 335356.0 53448.7 53639.0 53701.5 53741.7 53797.0 55301.9 55322.0 55506.0 55512.0 55548.9 15069.9 15388.5 15391.4 15402.4 15411.4 11644.4 13218.5 13223.9 13228.3 13228.5 10679.5 10708.8 10728.7 10733.5 10736.1 4862.3 5452.7 5532.3 5569.9 5619.0 1172.4 1175.4 1180.7 1195.3 1196.0 27662.8 27887.3 27903.2 28022.7 28107.0 1269.5 1275.6 1276.9 1322.5 1325.0 14039.7 14312.5 14466.7 14471.0 14658.5 471553.0 472216.0 472452.0 473811.0 473906.0 48529.0 49348.8 57426.0 58065.0 61224.7 1489.9 1527.7 1527.8 1533.9 1534.5 29628.0 30767.7 30874.3 31278.5 31477.7 47339.6 47535.8 47557.3 47584.8 47596.8 465338.0 465356.0 465662.0 465752.0 466497.0 34898.8 35650.5 35718.8 35751.6 35776.0 36735.3 45034.1 45140.0 45999.0 46983.5 25951.2 31343.5 31499.5 31526.5 31586.7 23039.0 29126.1 30509.2 30535.7 31542.4 34584.0 35205.0 35878.1 35925.5 36341.6 28828.0 29215.7 30089.1 30127.8 30128.8 22870.7 23835.1 24078.4 24313.9 24321.8 07:31:17 INFO - PROCESS | 1643 | 07:31:17 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:44743.21 Median:22743.83 stddev:131405.63 (577.8%) stddev-sans-first:131732.14 07:31:17 INFO - PROCESS | 1643 | Values: 436.5 444.2 448.8 449.3 458.9 19170.0 21396.4 22055.0 22605.7 22882.0 23100.1 23670.3 24245.5 24345.5 24621.5 6928.5 7230.5 7236.7 7612.8 7923.8 7672.4 7796.5 7955.7 7961.8 7975.3 6701.3 7852.7 7954.2 7964.0 7977.5 6642.2 7693.8 7899.9 7971.9 7983.6 7502.1 7645.0 7718.2 7785.4 7790.6 22248.3 25886.1 27149.5 27439.5 27662.9 2982.4 2988.8 3097.2 3105.7 3166.8 16072.1 16254.8 16350.1 16383.9 17745.3 2131.8 2192.9 2205.1 2231.9 2311.2 100142.0 135708.3 177725.0 178102.0 179704.1 19600.7 20245.6 20657.9 20849.8 20891.0 31761.0 32031.8 32326.0 32511.0 32563.2 10488.6 10575.3 10752.4 10765.7 10950.1 9011.8 9028.0 9058.0 9210.5 9218.5 7594.5 9323.2 9482.5 9488.3 9520.8 4073.0 4108.5 4146.5 4149.8 4162.2 934.8 1091.3 1091.8 1120.9 1135.6 20417.1 21874.0 22922.8 22976.7 22982.0 899.9 988.6 1010.1 1143.6 1146.9 9735.9 11541.7 11738.0 11750.2 11776.7 771897.0 779428.0 790170.1 798781.0 800239.0 145979.5 161500.2 167209.0 167557.0 168117.0 1219.8 1247.8 1274.6 1275.3 1275.6 19771.3 19826.4 19927.8 20113.1 20176.2 62059.7 62104.7 62303.0 62308.4 62613.4 74598.0 88335.0 90697.0 91773.0 99266.5 11515.3 11607.9 11674.1 11761.5 11949.0 11033.3 11438.7 13026.8 13160.3 13500.5 11010.1 11161.5 11354.2 11475.9 11595.0 10330.1 10433.9 10496.7 10705.1 10872.7 11508.0 11542.9 11828.1 11905.5 11931.6 9764.7 11618.8 11700.3 11811.3 11930.3 10280.0 10887.8 11218.2 11444.5 11739.8 07:31:17 INFO - PROCESS | 1643 | 07:31:17 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:6942.92 Median:2501.60 stddev:4331.63 (173.2%) stddev-sans-first:4323.87 07:31:17 INFO - PROCESS | 1643 | Values: 1416.6 1621.8 1646.5 1649.4 1656.0 9676.2 10120.9 10132.8 10296.3 10319.8 10703.3 11009.9 11018.9 11255.9 11283.4 2474.2 2514.2 2515.5 2515.8 2519.2 2067.7 2367.4 2502.5 2507.5 2511.7 2146.6 2424.4 2500.7 2503.7 2504.5 2468.0 2483.8 2487.0 2488.0 2494.7 2439.6 2456.7 2466.5 2469.7 2481.5 11389.1 11535.6 11540.5 11556.0 11595.0 5977.4 6165.1 6167.1 6167.6 6168.3 3717.8 3789.7 3854.2 3870.5 3948.2 1257.6 1272.7 1278.0 1313.2 1319.6 13205.4 14316.9 14930.5 16072.7 16075.0 4933.6 5189.2 5204.7 5232.9 5239.5 11950.8 12015.3 12023.6 12048.6 12056.6 6113.2 6123.0 6125.7 6128.7 6131.8 5350.3 5489.1 5610.9 5611.7 5620.5 3626.5 3940.0 4083.7 4090.2 4138.8 1002.2 1071.6 1073.2 1090.8 1093.5 777.9 834.0 956.6 978.7 1014.8 5528.4 5997.6 6108.6 6599.3 6804.8 1031.7 1032.7 1051.1 1053.4 1123.2 4102.9 4229.7 4249.2 4314.1 4468.4 13988.4 14612.7 14621.2 14637.8 14647.3 13943.8 14188.6 14474.2 14518.1 14526.9 10665.6 10757.4 10766.3 10790.0 10894.6 6627.8 6637.3 6670.2 6752.8 6773.9 12293.3 12931.8 12964.1 12974.3 12987.9 13046.3 13062.7 13069.5 13133.3 13318.5 9389.2 9660.2 9686.6 9687.4 9688.1 10574.0 10586.0 10598.3 10669.1 10698.0 9003.3 9116.6 9152.9 9225.3 9357.3 8943.5 9014.1 9033.6 9048.0 9109.5 8092.2 8261.3 8263.0 8273.6 8294.3 5938.5 5944.6 6020.0 6127.7 6152.0 5754.0 5754.2 5912.9 6015.3 6134.7 07:31:17 INFO - PROCESS | 1643 | 07:31:17 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4774.37 Median:5238.27 stddev:1314.82 (25.1%) stddev-sans-first:1274.81 07:31:17 INFO - PROCESS | 1643 | Values: 295.6 391.7 393.3 399.2 400.4 5172.6 5392.9 5396.8 5403.7 5422.7 4599.4 5060.4 5284.8 5284.9 5304.0 4171.9 4325.1 4411.2 4420.2 4453.6 3704.7 4065.0 4423.5 4442.7 4470.0 4061.7 4151.4 4467.7 4475.2 4484.4 3957.9 4166.7 4364.2 4427.4 4435.8 3648.6 3761.6 3920.3 4058.9 4108.3 4415.9 4595.1 4716.5 4942.5 5254.7 4451.4 5065.4 5094.2 5151.5 5183.8 4718.4 5623.4 5630.4 5681.1 5741.9 4429.4 4602.5 4631.0 4643.3 4649.3 4883.3 5580.4 5615.4 5676.4 5920.2 3794.9 4247.5 4560.0 5090.2 5177.6 4777.9 5243.5 5246.8 5257.1 5269.9 5008.9 5310.0 5392.9 5436.4 5509.9 5661.7 5675.0 5692.3 5700.6 5737.3 1629.8 1674.6 1706.7 1779.1 1808.3 1169.1 1290.3 1375.0 1391.5 1452.9 4748.5 4755.9 5281.6 5291.2 5305.6 5138.6 5797.0 5808.7 5870.5 5892.4 4603.0 5125.3 5191.1 5228.3 5237.8 2479.7 2850.4 3033.8 3042.6 3046.6 5327.9 5450.4 5652.3 5978.5 6099.5 5368.0 5985.9 6009.0 6049.6 6091.8 5671.1 5888.3 5976.9 6092.7 6126.5 5370.2 5432.6 5468.9 5618.2 5876.0 4319.5 5633.6 5815.4 5824.3 5870.0 5933.8 5988.2 6025.2 6030.8 6086.4 3956.8 4367.5 4511.6 4603.9 4671.9 4789.5 5238.7 5912.2 5969.4 5976.6 4644.7 5696.9 5739.8 5744.3 5756.2 5695.7 5725.0 5735.6 5736.3 5773.1 4946.4 4981.4 5195.0 5492.5 5545.2 5266.5 5419.1 5615.9 5654.7 5722.3 4778.7 5396.4 5577.0 5601.8 5612.2 07:31:17 INFO - PROCESS | 1643 | 07:31:17 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20972.33 Median:16641.08 stddev:29826.17 (179.2%) stddev-sans-first:29871.23 07:31:17 INFO - PROCESS | 1643 | Values: 760.2 792.5 821.9 891.2 906.6 15678.6 16418.1 16644.2 17211.1 17394.3 15860.3 15929.5 16129.4 16166.2 16638.0 998.8 1025.1 1025.5 1025.8 1026.7 976.7 989.8 1009.7 1009.8 1010.2 1063.4 1066.2 1067.0 1068.4 1068.8 1064.6 1064.9 1065.6 1066.4 1067.0 784.6 787.3 787.9 788.1 788.4 17470.0 17476.7 17544.4 18234.8 18489.7 14730.3 14982.3 15056.9 15086.8 15329.4 12198.6 12201.8 12335.5 12347.1 12426.8 3767.5 3874.7 3875.3 3958.1 3958.4 106502.4 116982.8 119667.0 120873.4 120947.4 10711.8 11564.2 11600.9 11637.3 12249.3 18595.6 22391.7 22629.7 22901.8 22958.1 8025.4 8117.8 8163.6 8188.7 8324.3 3939.3 4594.6 4625.8 4796.8 4892.2 3559.8 3631.9 3641.5 3754.6 3769.4 561.6 678.2 681.6 693.4 702.5 560.6 590.3 597.8 617.6 624.5 10028.5 10566.8 10672.8 11042.2 11241.3 692.4 733.1 746.3 747.9 767.1 4731.8 5225.0 5246.4 5248.7 5257.3 103972.0 104152.5 105887.0 106317.3 108648.0 73131.0 87746.6 93630.0 94893.0 95349.1 49656.5 52374.7 53557.7 68877.0 74034.6 9471.6 11058.8 11186.4 11373.0 11519.8 50099.0 51675.5 51869.0 52919.2 53039.7 66015.7 66128.0 69038.7 72798.6 74145.0 13239.5 13677.7 13749.5 13875.0 14377.6 15713.5 15970.8 16360.8 16369.0 16853.5 14364.8 14535.6 14552.0 14944.0 15132.2 14197.3 14737.2 14740.2 14793.3 15055.8 13955.2 14370.9 14475.4 14491.3 15001.5 10432.3 10607.5 10694.4 10755.1 10983.5 9654.6 10703.5 11572.2 11858.0 11881.3 07:31:17 INFO - PROCESS | 1643 | -------- Summary: end -------- 07:31:17 INFO - PROCESS | 1643 | 07:31:18 INFO - TEST-INFO | 1643: exit 0 07:31:18 INFO - TEST-OK | dromaeo_css | took 1119023ms 07:31:18 INFO - TEST-START | kraken 07:31:18 INFO - operating with platform_type : mac_ 07:31:18 INFO - Initialising browser for kraken test... 07:31:18 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpbSlgLn/profile http://localhost:49225/getInfo.html) 07:31:20 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 07:31:20 INFO - PROCESS | 1654 | colorDepth:24 07:31:20 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 07:31:20 INFO - PROCESS | 1654 | __metrics 07:31:20 INFO - PROCESS | 1654 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:31:20 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:31:20 INFO - TEST-INFO | 1654: exit 0 07:31:20 INFO - Browser initialized. 07:31:20 INFO - Running cycle 1/1 for kraken test... 07:31:21 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/T/tmpbSlgLn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:31:33 INFO - PROCESS | 1656 | RSS: Main: 165064704 07:31:33 INFO - PROCESS | 1656 | 07:31:35 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection) 07:31:36 INFO - PROCESS | 1656 | RSS: Main: 215891968 07:31:36 INFO - PROCESS | 1656 | 07:31:39 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-dft) 07:31:39 INFO - PROCESS | 1656 | RSS: Main: 231280640 07:31:39 INFO - PROCESS | 1656 | 07:31:42 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-fft) 07:31:43 INFO - PROCESS | 1656 | RSS: Main: 328105984 07:31:43 INFO - PROCESS | 1656 | 07:31:45 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator) 07:31:46 INFO - PROCESS | 1656 | RSS: Main: 230350848 07:31:46 INFO - PROCESS | 1656 | 07:31:48 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:31:48 INFO - PROCESS | 1656 | RSS: Main: 231792640 07:31:48 INFO - PROCESS | 1656 | 07:31:51 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom) 07:31:52 INFO - PROCESS | 1656 | RSS: Main: 271912960 07:31:52 INFO - PROCESS | 1656 | 07:31:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate) 07:31:55 INFO - PROCESS | 1656 | RSS: Main: 272515072 07:31:55 INFO - PROCESS | 1656 | 07:31:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial) 07:31:59 INFO - PROCESS | 1656 | RSS: Main: 268365824 07:31:59 INFO - PROCESS | 1656 | 07:32:00 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:32:01 INFO - PROCESS | 1656 | RSS: Main: 247848960 07:32:01 INFO - PROCESS | 1656 | 07:32:03 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:32:03 INFO - PROCESS | 1656 | RSS: Main: 250511360 07:32:03 INFO - PROCESS | 1656 | 07:32:06 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:32:06 INFO - PROCESS | 1656 | RSS: Main: 260059136 07:32:06 INFO - PROCESS | 1656 | 07:32:09 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:32:10 INFO - PROCESS | 1656 | RSS: Main: 272707584 07:32:10 INFO - PROCESS | 1656 | 07:32:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:32:13 INFO - PROCESS | 1656 | RSS: Main: 265883648 07:32:13 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49225/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49225/tests/kraken/driver.html?testName=ai-astar) 07:32:15 INFO - PROCESS | 1656 | RSS: Main: 310321152 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | __start_tp_report 07:32:15 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 07:32:15 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 07:32:15 INFO - PROCESS | 1656 | |i|pagename|runs| 07:32:15 INFO - PROCESS | 1656 | |0;ai-astar;105;115;114;112;115;116;114;119;115;114 07:32:15 INFO - PROCESS | 1656 | |1;audio-beat-detection;142;106;107;143;108;106;108;104;126;106 07:32:15 INFO - PROCESS | 1656 | |2;audio-dft;182;184;182;183;180;183;183;183;182;184 07:32:15 INFO - PROCESS | 1656 | |3;audio-fft;85;58;59;92;61;58;59;57;73;58 07:32:15 INFO - PROCESS | 1656 | |4;audio-oscillator;92;67;85;70;90;97;80;69;67;82 07:32:15 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;88;88;87;87;107;136;98;90;86;86 07:32:15 INFO - PROCESS | 1656 | |6;imaging-darkroom;93;94;94;94;94;94;94;94;94;93 07:32:15 INFO - PROCESS | 1656 | |7;imaging-desaturate;84;84;84;86;83;82;83;85;84;83 07:32:15 INFO - PROCESS | 1656 | |8;json-parse-financial;64;62;62;60;63;63;64;63;63;65 07:32:15 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;63;65;64;54;58;57;58;59;57;57 07:32:15 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;82;85;84;91;80;83;82;86;88;82 07:32:15 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;131;128;158;136;136;132;132;138;174;135 07:32:15 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;160;213;168;161;160;163;164;172;162;163 07:32:15 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;63;64;65;61;57;74;82;80;78;76 07:32:15 INFO - PROCESS | 1656 | __end_tp_report 07:32:15 INFO - PROCESS | 1656 | __start_cc_report 07:32:15 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2061 07:32:15 INFO - PROCESS | 1656 | __end_cc_report 07:32:15 INFO - PROCESS | 1656 | __startTimestamp1495117935886__endTimestamp 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | ------- Summary: start ------- 07:32:15 INFO - PROCESS | 1656 | Number of tests: 14 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:113.90 Median:115.00 stddev:3.60 (3.1%) stddev-sans-first:1.90 07:32:15 INFO - PROCESS | 1656 | Values: 105.0 115.0 114.0 112.0 115.0 116.0 114.0 119.0 115.0 114.0 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:115.60 Median:108.00 stddev:15.48 (14.3%) stddev-sans-first:13.14 07:32:15 INFO - PROCESS | 1656 | Values: 142.0 106.0 107.0 143.0 108.0 106.0 108.0 104.0 126.0 106.0 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.00 stddev:1.17 (0.6%) stddev-sans-first:1.22 07:32:15 INFO - PROCESS | 1656 | Values: 182.0 184.0 182.0 183.0 180.0 183.0 183.0 183.0 182.0 184.0 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:66.00 Median:60.00 stddev:12.83 (21.4%) stddev-sans-first:11.62 07:32:15 INFO - PROCESS | 1656 | Values: 85.0 58.0 59.0 92.0 61.0 58.0 59.0 57.0 73.0 58.0 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:79.90 Median:83.50 stddev:11.16 (13.4%) stddev-sans-first:10.94 07:32:15 INFO - PROCESS | 1656 | Values: 92.0 67.0 85.0 70.0 90.0 97.0 80.0 69.0 67.0 82.0 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:95.30 Median:87.50 stddev:15.80 (18.1%) stddev-sans-first:16.53 07:32:15 INFO - PROCESS | 1656 | Values: 88.0 88.0 87.0 87.0 107.0 136.0 98.0 90.0 86.0 86.0 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 07:32:15 INFO - PROCESS | 1656 | Values: 93.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:83.80 Median:84.00 stddev:1.14 (1.4%) stddev-sans-first:1.20 07:32:15 INFO - PROCESS | 1656 | Values: 84.0 84.0 84.0 86.0 83.0 82.0 83.0 85.0 84.0 83.0 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:63.00 stddev:1.37 (2.2%) stddev-sans-first:1.39 07:32:15 INFO - PROCESS | 1656 | Values: 64.0 62.0 62.0 60.0 63.0 63.0 64.0 63.0 63.0 65.0 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:59.20 Median:58.50 stddev:3.58 (6.1%) stddev-sans-first:3.53 07:32:15 INFO - PROCESS | 1656 | Values: 63.0 65.0 64.0 54.0 58.0 57.0 58.0 59.0 57.0 57.0 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:84.50 stddev:3.30 (3.9%) stddev-sans-first:3.40 07:32:15 INFO - PROCESS | 1656 | Values: 82.0 85.0 84.0 91.0 80.0 83.0 82.0 86.0 88.0 82.0 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:140.00 Median:136.00 stddev:14.51 (10.7%) stddev-sans-first:15.02 07:32:15 INFO - PROCESS | 1656 | Values: 131.0 128.0 158.0 136.0 136.0 132.0 132.0 138.0 174.0 135.0 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:163.50 stddev:16.04 (9.8%) stddev-sans-first:16.71 07:32:15 INFO - PROCESS | 1656 | Values: 160.0 213.0 168.0 161.0 160.0 163.0 164.0 172.0 162.0 163.0 07:32:15 INFO - PROCESS | 1656 | 07:32:15 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:75.00 stddev:8.94 (11.9%) stddev-sans-first:9.12 07:32:15 INFO - PROCESS | 1656 | Values: 63.0 64.0 65.0 61.0 57.0 74.0 82.0 80.0 78.0 76.0 07:32:15 INFO - PROCESS | 1656 | -------- Summary: end -------- 07:32:15 INFO - PROCESS | 1656 | 07:32:16 INFO - TEST-INFO | 1656: exit 0 07:32:16 INFO - TEST-OK | kraken | took 58605ms 07:32:16 INFO - SUITE-END | took 1177s 07:32:17 INFO - Completed test suite (00:19:37) 07:32:17 INFO - javascript benchmark 07:32:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8007.91513048352, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3152.243461569223, 3587.3901436755764, 3591.8204089795513, 3629.0927268182954, 3634.1096431374317, 4958.562017015061, 6120.724567394467, 6139.079138129283, 6165.845853853656, 6187.78342758003, 6124.020156774917, 6168.105624684418, 6174.845628859281, 6188.628682279055, 6201.410865967726, 5504.64219825712, 5531.834044978657, 5543.334799824027, 5603.663780173182, 5682.374938756733, 5426.403095659474, 5427.3215848019, 5469.535089517396, 5503.862403439906, 5649.209110724501, 5512.80705175319, 5514.972425137868, 5516.365617953927, 5519.69641669708, 5521.5306698930635, 5504.2294078829, 5507.5593952483705, 5524.14375771756, 5526.806561770319, 5533.3083364579425, 5363.329583802025, 5463.508284254436, 5472.425395333512, 5496.31296087989, 5499.092649712793, 6137.591499645469, 6139.093519790236, 6140.7189816757555, 6144.9553575892205, 6161.661108639023, 6522.15212022434, 6522.706478208444, 6524.641144737037, 6539.93460065398, 6547.869042619169, 6623.53635245533, 6637.269900310962, 6640.169978752656, 6643.402093811532, 6653.53425260232, 5166.948330516684, 6114.91462006927, 6170.938290617126, 6308.716107775161, 6335.429811317003, 5699.316082070095, 6551.475881929434, 6692.498062645321, 6739.494537909676, 6748.122744043243, 6500.284968653445, 6507.121538592324, 6511.967440162864, 6512.511561632802, 6523.575967562093, 6634.402903738686, 6640.0703901453835, 6642.402183803384, 6654.900176497356, 6668.0331351954665, 6300.558960872695, 6337.2395312562785, 6456.580322279131, 6472.611643301323, 6490.772822951174, 6550.672466376662, 6559.409653131241, 6573.967130164318, 6578.881590129689, 6582.210134783857, 5243.397009343974, 5297.973510132501, 5298.682079075268, 5301.973490132525, 5307.177387504866, 4442.911141777211, 4613.561711637389, 4653.744044077541, 4662.137504561667, 4713.670043097019, 6039.396060394013, 6075.908861367083, 6086.847828804315, 6259.436650701458, 6282.685865706788, 5546.584006199551, 6577.3422657733845, 6626.56927299724, 6635.469162466991, 6652.7006284716335, 6202.5658203925295, 6224.564280500412, 6262.060690896311, 6276.592021518587, 6287.811365659038, 5637.915431268534, 5674.290713660793, 5836.824895253149, 5846.561507886926, 5846.649201047951, 6626.204855417381, 6739.494537909774, 6772.221194562689, 6838.076859623887, 6874.0032695257905, 6651.800445986628, 6656.134702488645, 6656.30108838565, 6661.16735408074, 6666.799995999933, 6795.116634837439, 6809.897851532132, 6824.112865327675, 6834.589924604539, 6851.9657401712675, 6336.619802811846, 6359.841003974938, 6398.104265402941, 6435.903461447989, 6505.154329937078, 5722.399148089391, 6400.9039864403, 6572.375624315682, 6608.306127856506, 6620.966895165493, 6266.749330026748, 6375.713092910737, 6393.712282947372, 6456.7740129095255, 6468.547201696025, 6265.530085243534, 6341.524385671001, 6372.904406433814, 6444.355564443518, 6455.61266324022, 6305.085762564494, 6336.0, 6339.144215531029, 6344.270408903035, 6351.015592583157, 6424.2933277338525, 6439.903401448888, 6456.0, 6456.709448074942, 6457.483401327789, 6174.475169610633, 6234.189555357774, 6252.9374706254175, 6283.654399008098, 6345.96827015862], "value": 6030.372671204436, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.3071696906721, 794.4240425691374, 824.3775949158385, 900.3157600223828, 903.3270213690793, 30042.549361759564, 41573.58426415735, 41911.37132943005, 41941.95145121373, 42060.36909446361, 43757.56242437575, 43871.0, 44024.11951760971, 44055.33916991248, 44196.0, 7915.96042019792, 8343.374246931475, 8588.441751286178, 9602.663906763248, 9754.268429867789, 8099.311558517533, 11635.24370915892, 11793.587224447147, 12119.697007574838, 12130.332831694253, 11799.882001180007, 11895.405229738522, 11906.928376446118, 11916.7616647667, 11921.642350729491, 11795.764084718257, 11846.585369512026, 11848.170628055996, 11854.466549005316, 11862.881371186264, 11566.074714022858, 11604.30374177552, 11609.767804643945, 11612.0, 11629.302241865515, 41060.58939410598, 41267.79366103181, 41271.7936410316, 41325.79337103295, 41349.79325103355, 3687.9858039039477, 3778.8110594470168, 4015.3374693175883, 4021.77880216588, 4037.777922214277, 28831.56752648712, 29412.0, 30694.386112277854, 30915.22711932197, 30960.38079238425, 3273.0835366097294, 3308.4871844864083, 3325.085601459594, 3438.9828050859837, 3446.2418267981257, 324999.0, 329501.0, 329970.0, 331765.0, 335356.0, 53448.73275633675, 53639.0, 53701.462985369646, 53741.73129134408, 53797.0, 55301.893962121336, 55322.0, 55506.0, 55512.0, 55548.88902221933, 15069.924650376897, 15388.538343849703, 15391.384344626313, 15402.383904643913, 15411.383544658313, 11644.3595602241, 13218.537351192663, 13223.933880330536, 13228.338583070808, 13228.537001204912, 10679.519421626046, 10708.78582428347, 10728.678139655824, 10733.463326833627, 10736.087432568163, 4862.334474772805, 5452.727363631802, 5532.30846144232, 5569.916451253234, 5619.016672082369, 1172.4489489939715, 1175.3594291111365, 1180.740237147826, 1195.3126952002633, 1196.0073139294366, 27662.845960301995, 27887.302817429725, 27903.16290511288, 28022.71977280201, 28107.0, 1269.4795133995017, 1275.5981865712456, 1276.9297688627214, 1322.4842311498523, 1324.9602511924659, 14039.719205615831, 14312.49906253297, 14466.718220212906, 14471.0, 14658.486952956808, 471553.0, 472216.0, 472452.0, 473811.0, 473906.0, 48529.0, 49348.75325623421, 57426.0, 58065.0, 61224.693876530335, 1489.8882583806258, 1527.6868242010362, 1527.8472152784632, 1533.9309731061905, 1534.539638108563, 29627.963021294152, 30767.69232307738, 30874.30191339408, 31278.53082203723, 31477.685223147477, 47339.579812605676, 47535.81160471016, 47557.28664070111, 47584.7620761894, 47596.7620161897, 465338.0, 465356.0, 465662.0, 465752.0, 466497.0, 34898.82550587333, 35650.46524302086, 35718.821405892806, 35751.64248357587, 35776.0, 36735.265294694495, 45034.09931801411, 45140.0, 45999.0, 46983.53016469791, 25951.221463355374, 31343.529847052767, 31499.52750708787, 31526.527102093027, 31586.684133158375, 23039.0, 29126.1262162127, 30509.237269067562, 30535.694643053284, 31542.36915261636, 34584.0, 35205.0, 35878.103047424025, 35925.46111808273, 36341.63658363382, 28828.0, 29215.7078429213, 30089.097327080224, 30127.849360753054, 30128.849355753082, 22870.742228171683, 23835.06721390951, 24078.39804004914, 24313.878430607732, 24321.75678243195], "value": 19447.495713884586, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [436.4958472963728, 444.19600523053276, 448.78234056482603, 449.2856358390159, 458.9013362127144, 19170.0, 21396.35810925673, 22055.0, 22605.660915086246, 22882.0, 23100.075996960142, 23670.289891303244, 24245.51508969824, 24345.513089738193, 24621.507569848593, 6928.48036397271, 7230.457715671319, 7236.71053157873, 7612.809679757995, 7923.801904952394, 7672.386209103286, 7796.493227940194, 7955.72154974576, 7961.840763184733, 7975.32209762168, 6701.3298670132845, 7852.685892564291, 7954.244346787046, 7963.960180199091, 7977.481463704869, 6642.169728783926, 7693.846123077535, 7899.921000790005, 7971.880421793678, 7983.640736166886, 7502.099748030217, 7644.961775191144, 7718.151003389623, 7785.416093792932, 7790.649420776022, 22248.332550023526, 25886.093986710563, 27149.50677712725, 27439.45121097587, 27662.86168569144, 2982.373701522683, 2988.7907846450757, 3097.1792474994145, 3105.736012438945, 3166.794158379698, 16072.116033618146, 16254.75617865733, 16350.10074445905, 16383.918080409641, 17745.29018839245, 2131.79747923947, 2192.9122835086578, 2205.106931692651, 2231.921882734113, 2311.167979527369, 100142.0, 135708.28583428275, 177725.0, 178102.0, 179704.10147949308, 19600.70598941017, 20245.595088098155, 20657.89671051635, 20849.79150208509, 20890.95545222733, 31761.04716858498, 32031.839840800647, 32326.0, 32511.0, 32563.185920351705, 10488.63289784869, 10575.312604680672, 10752.354858708502, 10765.730856728487, 10950.123990080774, 9011.774705632411, 9027.954860225656, 9058.0, 9210.493422861808, 9218.492982885871, 7594.544327340377, 9323.20752736025, 9482.525873706287, 9488.28837837165, 9520.761980950532, 4072.959270407258, 4108.506979162519, 4146.5024197096545, 4149.792510374469, 4162.167566486714, 934.8083642853198, 1091.3342860854873, 1091.7871015151968, 1120.8679233973583, 1135.5571327182406, 20417.081231344626, 21874.0, 22922.7707722924, 22976.65535016976, 22982.0, 899.9280057595377, 988.604558176735, 1010.0858722855827, 1143.5825923538016, 1146.873843877173, 9735.853962190573, 11541.701769165831, 11738.0, 11750.177487575787, 11776.70558236051, 771897.0, 779428.0, 790170.0982990211, 798781.0, 800239.0, 145979.5402045987, 161500.15499534798, 167209.0, 167557.0, 168117.0, 1219.7682440336307, 1247.781638213309, 1274.5539061328461, 1275.3304515129446, 1275.5790589105388, 19771.308004219783, 19826.40520784379, 19927.800721993175, 20113.09491072876, 20176.192952281745, 62059.6897015512, 62104.689476552325, 62303.0, 62308.37691623207, 62613.37386626076, 74598.0, 88335.0, 90697.0, 91773.0, 99266.5036674812, 11515.25150865191, 11607.941960290145, 11674.053937906932, 11761.529538818351, 11949.0, 11033.337999720043, 11438.656840294805, 13026.80459793085, 13160.341982901009, 13500.5274815381, 11010.064144547803, 11161.497732602214, 11354.205205635526, 11475.885241147482, 11595.0, 10330.070293673605, 10433.9478302608, 10496.737581560217, 10705.143588513056, 10872.728181795203, 11508.0, 11542.942285288857, 11828.112891532997, 11905.523779049088, 11931.582394616147, 9764.707058788248, 11618.825717614412, 11700.2979821211, 11811.291322520676, 11930.343831089373, 10279.972002799661, 10887.78224435491, 11218.158638102012, 11444.484998174936, 11739.765204696028], "value": 12064.700109888645, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1416.5537855575499, 1621.7810595569595, 1646.5389690886557, 1649.4474351092383, 1655.9917200413995, 9676.225901927846, 10120.949395253032, 10132.797344053115, 10296.330738501993, 10319.84520232197, 10703.250772445932, 11009.889901100987, 11018.889811101886, 11255.887441125586, 11283.37941413224, 2474.2206205045445, 2514.245726282117, 2515.5220508103494, 2515.76100270474, 2519.1938579654516, 2067.7311949446553, 2367.396313939943, 2502.5370306493264, 2507.523570521595, 2511.7488251174827, 2146.64580344243, 2424.369663887393, 2500.6624105745673, 2503.68703912011, 2504.536660717773, 2468.037465388482, 2483.838550494212, 2487.0176280369333, 2488.0, 2494.6756921600168, 2439.573074711936, 2456.65606815046, 2466.4943686544216, 2469.703635563744, 2481.4540801023927, 11389.145814063979, 11535.596254131066, 11540.538378464851, 11556.0, 11595.01442377399, 5977.3723759005115, 6165.075238714228, 6167.105769663419, 6167.629942203482, 6168.321484636686, 3717.8141092945516, 3789.7157713171623, 3854.2291541691775, 3870.4774855394444, 3948.1511475033008, 1257.5912828330738, 1272.6881913931054, 1277.9933715273817, 1313.238321773369, 1319.564543700586, 13205.405756740874, 14316.928415357961, 14930.47743328989, 16072.678546429006, 16075.0, 4933.580645645088, 5189.247559103909, 5204.71374074422, 5232.869178270497, 5239.502247286578, 11950.760984780256, 12015.279083255033, 12023.63929082129, 12048.578299759467, 12056.578019769266, 6113.20528331323, 6123.0, 6125.663088530174, 6128.662923539159, 6131.785387511505, 5350.277712508761, 5489.094299440548, 5610.859728506738, 5611.663300202001, 5620.494155526022, 3626.5285512883684, 3940.0499747180966, 4083.714140010171, 4090.2433049885867, 4138.772367519755, 1002.2382988928467, 1071.6356438810842, 1073.1629329123296, 1090.7927493776315, 1093.4696672114019, 777.8833175023678, 833.9874901876477, 956.6269155029544, 978.7406337320616, 1014.8376259798397, 5528.364238112588, 5997.550183736237, 6108.633481991094, 6599.274079851212, 6804.8298792529595, 1031.664708969588, 1032.7314898126542, 1051.0908064524285, 1053.373242920461, 1123.1688550472754, 4102.897427564275, 4229.661627069827, 4249.171411574775, 4314.1371725655, 4468.419105516327, 13988.370523326475, 14612.707745845024, 14621.19583422922, 14637.780433293508, 14647.267636618126, 13943.790843137362, 14188.645283867985, 14474.203918784357, 14518.128912265298, 14526.854731452762, 10665.573377064986, 10757.408342541079, 10766.300190487593, 10790.0, 10894.618688346029, 6627.768028118992, 6637.2699003108655, 6670.166229221348, 6752.763653272308, 6773.93226067733, 12293.323867187391, 12931.806022909852, 12964.092513523963, 12974.286414247306, 12987.870121299044, 13046.282454465096, 13062.673433164247, 13069.477220911058, 13133.277669728257, 13318.467261309439, 9389.154976052188, 9660.178884794872, 9686.612535498502, 9687.370320929398, 9688.12806847387, 10574.0, 10586.0, 10598.25812193139, 10669.093127084285, 10698.0, 9003.324750643596, 9116.589753460978, 9152.862707059532, 9225.308101892519, 9357.298202634693, 8943.463392196489, 9014.053524379846, 9033.638654453749, 9048.049954754655, 9109.498977556297, 8092.150324215872, 8261.256486916207, 8263.049749278909, 8273.627686753991, 8294.336453083859, 5938.524918006636, 5944.613600115978, 6020.0, 6127.662978536045, 6152.0, 5754.0, 5754.223179870832, 5912.852178695567, 6015.2781666200335, 6134.723937422737], "value": 5300.10769172918, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.58175182117304, 391.7199202570163, 393.311704517095, 399.24542614458676, 400.39140506430243, 5172.612054095948, 5392.892142157155, 5396.8111116110895, 5403.730433218057, 5422.728863556821, 4599.436191694823, 5060.443351231362, 5284.788608455658, 5284.894302113956, 5304.0, 4171.916561668772, 4325.113351762881, 4411.169364713656, 4420.182266280733, 4453.599176074153, 3704.68510176634, 4065.0, 4423.535528769481, 4442.73343599385, 4469.955300446986, 4061.6547593454443, 4151.433423234433, 4467.687261891669, 4475.1944649963, 4484.372187893681, 3957.8720064781687, 4166.729162604436, 4364.210673623831, 4427.402300689398, 4435.828382706348, 3648.616895226016, 3761.586225515191, 3920.313945059632, 4058.857939972087, 4108.253992053395, 4415.918100065504, 4595.0591621362355, 4716.528347165256, 4942.515526553807, 5254.710990895499, 4451.399061126771, 5065.442801291855, 5094.184930411154, 5151.5106064923775, 5183.792648294064, 4718.433787945434, 5623.437656234385, 5630.4369563043365, 5681.062624666925, 5741.856453588652, 4429.357743127261, 4602.493725690171, 4630.976845115787, 4643.349931009661, 4649.3026046093355, 4883.315256236664, 5580.414056524047, 5615.444972537526, 5676.375598684142, 5920.230370051909, 3794.937417523098, 4247.469066366704, 4560.016392869046, 5090.211017292326, 5177.58579313654, 4777.856664300077, 5243.475652434726, 5246.842594722164, 5257.132573125392, 5269.920951185735, 5008.899822003613, 5309.973450132725, 5392.865178370572, 5436.429174936575, 5509.889802203934, 5661.66030038199, 5674.963894316521, 5692.260006199168, 5700.629459085146, 5737.282839645044, 1629.7636842657985, 1674.6399524102376, 1706.67573161099, 1779.146009915248, 1808.2766893242808, 1169.0589075793898, 1290.341925617923, 1374.979375309371, 1391.547746982235, 1452.9175764055847, 4748.525147485224, 4755.881102972384, 5281.551068159172, 5291.232771248147, 5305.62860599762, 5138.614603904647, 5797.0, 5808.651480911159, 5870.53035757147, 5892.410758924073, 4602.976985115053, 5125.282460455539, 5191.082166330387, 5228.312060610236, 5237.816676416307, 2479.652848601197, 2850.401415702684, 3033.7572994160414, 3042.6044614200014, 3046.573479712842, 5327.936436549, 5450.367187890261, 5652.3217213934595, 5978.491828194652, 6099.523271735157, 5368.0, 5985.850353741104, 6008.969955150284, 6049.637021778707, 6091.7563297467605, 5671.092625180034, 5888.263967004124, 5976.8804623908145, 6092.6543280837195, 6126.4575201291855, 5370.19447082925, 5432.614683255744, 5468.9179662304305, 5618.2134501170685, 5876.0, 4319.524852266311, 5633.577481688808, 5815.41845815415, 5824.330202026652, 5870.0, 5933.821985340447, 5988.1675727535285, 6025.228394938377, 6030.788922387871, 6086.391360863879, 3956.7644280981153, 4367.497737760198, 4511.639068874417, 4603.861884143481, 4671.874297194836, 4789.473157952692, 5238.721156297023, 5912.231409916683, 5969.358426432766, 5976.581639285208, 4644.674872758875, 5696.914546281892, 5739.770409183586, 5744.281964754406, 5756.21557317233, 5695.715214239189, 5725.0267454531995, 5735.619717817226, 5736.311642602915, 5773.134029895549, 4946.40313835225, 4981.37732783402, 5195.0, 5492.533134683436, 5545.223668686468, 5266.499682530153, 5419.127862233023, 5615.887682246251, 5654.660720356792, 5722.341930677859, 4778.665493415567, 5396.379416367168, 5577.0, 5601.831945041656, 5612.165856242571], "value": 4396.706627543856, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [760.2093822424679, 792.4769652029662, 821.942464027518, 891.2335391563255, 906.6328137104471, 15678.608034799121, 16418.097004664738, 16644.16779161043, 17211.053392063448, 17394.304227830886, 15860.286287117078, 15929.522114336589, 16129.354825806982, 16166.191690415462, 16638.001719896816, 998.8474836726865, 1025.1183981775666, 1025.5077562769839, 1025.7897131088146, 1026.7484466305766, 976.692341912299, 989.7624570103195, 1009.7021378693303, 1009.7929924365524, 1010.1867996263005, 1063.4257500949477, 1066.248294952057, 1067.01301296301, 1068.385678235014, 1068.7595291059527, 1064.6273804168518, 1064.9467526623716, 1065.6270305393089, 1066.3761699405857, 1066.9626563070333, 784.5802495664792, 787.3465024030041, 787.9290863822283, 788.0582703669118, 788.3811208201535, 17470.0, 17476.737848932276, 17544.38594649194, 18234.817651823443, 18489.7226541602, 14730.263486825615, 14982.250887455693, 15056.924715376463, 15086.773698394532, 15329.386824527006, 12198.634040978784, 12201.816972745328, 12335.5065797368, 12347.073969452327, 12426.813597796041, 3767.5290588676417, 3874.6512813846607, 3875.302445559798, 3958.1489979654225, 3958.44581758554, 106502.40246396232, 116982.8301716989, 119667.0, 120873.39563302127, 120947.39526302312, 10711.78576428467, 11564.248323858923, 11600.94199528997, 11637.301761894314, 12249.265044097383, 18595.628087438174, 22391.664125038136, 22629.660555091687, 22901.770982290298, 22958.081676733076, 8025.3980951428875, 8117.8376432472205, 8163.55100469468, 8188.713395031145, 8324.29243514296, 3939.3411190517463, 4594.609458196024, 4625.791839367236, 4796.832110876103, 4892.241702536113, 3559.768615040014, 3631.94552081719, 3641.4719865619336, 3754.568224654145, 3769.378052621342, 561.6237121128853, 678.1794029224595, 681.6182937554886, 693.3517161454057, 702.4520873718508, 560.6327855254815, 590.3240789296232, 597.7937611524018, 617.5677026081761, 624.5066397545888, 10028.548715307828, 10566.841497377545, 10672.78654426907, 11042.227044106996, 11241.269317494336, 692.4114502672788, 733.083645443196, 746.2574738135589, 747.9102507699002, 767.0833354141747, 4731.810727570859, 5225.0, 5246.422893481714, 5248.6588371755715, 5257.342832145981, 103972.0, 104152.47923760333, 105887.0, 106317.34206644895, 108648.0, 73131.0, 87746.56126719453, 93630.0, 94893.0, 95349.09301814063, 49656.50343496519, 52374.73812630989, 53557.747652978775, 68877.0, 74034.62982685052, 9471.573779180091, 11058.834117488082, 11186.384748838893, 11373.0, 11519.76960460803, 50099.0, 51675.483245167066, 51868.96262074813, 52919.20621190762, 53039.73480132574, 66015.66992165009, 66128.0, 69038.65480672565, 72798.63600681962, 74145.0, 13239.536616218771, 13677.658058548615, 13749.518766843112, 13874.95937804689, 14377.56867293983, 15713.450029248921, 15970.840291596935, 16360.754588680942, 16369.017858928502, 16853.494395167672, 14364.784528231876, 14535.636609084435, 14552.0, 14944.0, 15132.243387830784, 14197.290135493391, 14737.1894545801, 14740.189289589176, 14793.334299956312, 15055.774163387778, 13955.23246221427, 14370.928145359207, 14475.420983160555, 14491.275436227936, 15001.474948376754, 10432.269741118353, 10607.469626518798, 10694.41180735067, 10755.085817705583, 10983.505742241778, 9654.565544550653, 10703.464826758474, 11572.24780389272, 11858.0, 11881.346525941157], "value": 7997.242398054173, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1415.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 114.0, 112.0, 115.0, 116.0, 114.0, 119.0, 115.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 106.0, 107.0, 143.0, 108.0, 106.0, 108.0, 104.0, 126.0, 106.0], "value": 115.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 184.0, 182.0, 183.0, 180.0, 183.0, 183.0, 183.0, 182.0, 184.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 58.0, 59.0, 92.0, 61.0, 58.0, 59.0, 57.0, 73.0, 58.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 67.0, 85.0, 70.0, 90.0, 97.0, 80.0, 69.0, 67.0, 82.0], "value": 79.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 87.0, 87.0, 107.0, 136.0, 98.0, 90.0, 86.0, 86.0], "value": 95.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 86.0, 83.0, 82.0, 83.0, 85.0, 84.0, 83.0], "value": 83.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 62.0, 60.0, 63.0, 63.0, 64.0, 63.0, 63.0, 65.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 64.0, 54.0, 58.0, 57.0, 58.0, 59.0, 57.0, 57.0], "value": 59.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 84.0, 91.0, 80.0, 83.0, 82.0, 86.0, 88.0, 82.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 128.0, 158.0, 136.0, 136.0, 132.0, 132.0, 138.0, 174.0, 135.0], "value": 140.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 213.0, 168.0, 161.0, 160.0, 163.0, 164.0, 172.0, 162.0, 163.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 64.0, 65.0, 61.0, 57.0, 74.0, 82.0, 80.0, 78.0, 76.0], "value": 70.0, "unit": "score"}]}]} 07:32:17 INFO - Return code: 0 07:32:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:32:17 INFO - # TBPL SUCCESS # 07:32:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:32:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:32:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:32:17 INFO - 2017-05-18 07:32:17.188 screenresolution[1660:10257] starting screenresolution argv=screenresolution get 07:32:17 INFO - 2017-05-18 07:32:17.190 screenresolution[1660:10257] Display 0: 1600x1200x32@60 07:32:17 INFO - 2017-05-18 07:32:17.196 screenresolution[1661:10260] starting screenresolution argv=screenresolution list 07:32:17 INFO - Available Modes on Display 0 07:32:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:32:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:32:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:32:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:32:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:32:17 INFO - Intel Iris: 07:32:17 INFO - Chipset Model: Intel Iris 07:32:17 INFO - Type: GPU 07:32:17 INFO - Bus: Built-In 07:32:17 INFO - VRAM (Dynamic, Max): 1536 MB 07:32:17 INFO - Vendor: Intel (0x8086) 07:32:17 INFO - Device ID: 0x0a2e 07:32:17 INFO - Revision ID: 0x0009 07:32:17 INFO - Displays: 07:32:17 INFO - Display: 07:32:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:32:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:32:17 INFO - Display Serial Number: 1600x1200 60 07:32:17 INFO - Main Display: Yes 07:32:17 INFO - Mirror: Off 07:32:17 INFO - Online: Yes 07:32:17 INFO - Rotation: Supported 07:32:17 INFO - Return code: 0 07:32:17 INFO - Running post-action listener: _resource_record_post_action 07:32:17 INFO - [mozharness: 2017-05-18 14:32:17.432816Z] Finished run-tests step (success) 07:32:17 INFO - Running post-run listener: _resource_record_post_run 07:32:17 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 41333760; Write bytes: 423923712; Read time: 414; Write time: 1646 07:32:17 INFO - TinderboxPrint: CPU usage
53.7% 07:32:17 INFO - TinderboxPrint: I/O read bytes / time
41,333,760 / 414 07:32:17 INFO - TinderboxPrint: I/O write bytes / time
423,923,712 / 1,646 07:32:17 INFO - TinderboxPrint: CPU idle
2,208.0 (46.3%) 07:32:17 INFO - TinderboxPrint: CPU system
947.2 (19.8%) 07:32:17 INFO - TinderboxPrint: CPU user
1,618.5 (33.9%) 07:32:17 INFO - TinderboxPrint: Swap in / out
161,611,776 / 0 07:32:17 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 108700160; Write bytes: 105289728; Read time: 9897; Write time: 263 07:32:17 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 40334336; Write bytes: 297797632; Read time: 399; Write time: 1316 07:32:17 INFO - Running post-run listener: _upload_blobber_files 07:32:17 INFO - Blob upload gear active. 07:32:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:32:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:32:17 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'] 07:32:17 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 07:32:18 INFO - (blobuploader) - INFO - Open directory for files ... 07:32:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:32:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:32:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:19 INFO - (blobuploader) - INFO - Done attempting. 07:32:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:32:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:32:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:20 INFO - (blobuploader) - INFO - Done attempting. 07:32:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 07:32:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 07:32:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:21 INFO - (blobuploader) - INFO - Done attempting. 07:32:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:32:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:32:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:32:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:32:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:32:23 INFO - (blobuploader) - INFO - Done attempting. 07:32:23 INFO - (blobuploader) - INFO - Iteration through files over. 07:32:23 INFO - Return code: 0 07:32:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:32:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:32:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2a4866751aaf7d3a5ada7d92ebac81eefad34b52893e302b15dceaf7f8aa462656672c92fac7162c43d91b92ece01ba3472204a573a4bef6e0326cde7259830d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de558f38d7e860a37710bb7311d0e7e265f8ceda59c8bd6a5e2fc4f85dc16939396d1fcb0c3670dbd66577c85f966aca2bfa2d98f89c267609754b4721266b7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/befd6759042db2d3ac2d1444d67b64b835ad92b325b6c86cde48ae8b47c91904ccd0b1cdc3655868aa00eed97ba090e2284a8f754cd77b7d0d88ba7ab478fbf9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3ebc6485ee1af4afc0a90ebad51abaa37838af4a835e8dc8599679eb1da9140b6514d9dc34af2d3d742659c7272fb6c5f9048596f2650a2f3b3c2d5d4a554138"} 07:32:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:32:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:32:23 INFO - Contents: 07:32:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2a4866751aaf7d3a5ada7d92ebac81eefad34b52893e302b15dceaf7f8aa462656672c92fac7162c43d91b92ece01ba3472204a573a4bef6e0326cde7259830d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de558f38d7e860a37710bb7311d0e7e265f8ceda59c8bd6a5e2fc4f85dc16939396d1fcb0c3670dbd66577c85f966aca2bfa2d98f89c267609754b4721266b7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/befd6759042db2d3ac2d1444d67b64b835ad92b325b6c86cde48ae8b47c91904ccd0b1cdc3655868aa00eed97ba090e2284a8f754cd77b7d0d88ba7ab478fbf9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3ebc6485ee1af4afc0a90ebad51abaa37838af4a835e8dc8599679eb1da9140b6514d9dc34af2d3d742659c7272fb6c5f9048596f2650a2f3b3c2d5d4a554138"} 07:32:23 INFO - Running post-run listener: copy_logs_to_upload_dir 07:32:23 INFO - Copying logs to upload dir... 07:32:23 INFO - mkdir: /builds/slave/test/build/upload/logs 07:32:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.420349 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-18 07:32:23.546007) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-18 07:32:23.549724) ========= 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.yw1x8aIdKc/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.cxsGs203Mw/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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/2a4866751aaf7d3a5ada7d92ebac81eefad34b52893e302b15dceaf7f8aa462656672c92fac7162c43d91b92ece01ba3472204a573a4bef6e0326cde7259830d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de558f38d7e860a37710bb7311d0e7e265f8ceda59c8bd6a5e2fc4f85dc16939396d1fcb0c3670dbd66577c85f966aca2bfa2d98f89c267609754b4721266b7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/befd6759042db2d3ac2d1444d67b64b835ad92b325b6c86cde48ae8b47c91904ccd0b1cdc3655868aa00eed97ba090e2284a8f754cd77b7d0d88ba7ab478fbf9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3ebc6485ee1af4afc0a90ebad51abaa37838af4a835e8dc8599679eb1da9140b6514d9dc34af2d3d742659c7272fb6c5f9048596f2650a2f3b3c2d5d4a554138"} build_url:https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011145 build_url: 'https://queue.taskcluster.net/v1/task/EZaWPBzYQSSmBShVc-uW8w/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/2a4866751aaf7d3a5ada7d92ebac81eefad34b52893e302b15dceaf7f8aa462656672c92fac7162c43d91b92ece01ba3472204a573a4bef6e0326cde7259830d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/de558f38d7e860a37710bb7311d0e7e265f8ceda59c8bd6a5e2fc4f85dc16939396d1fcb0c3670dbd66577c85f966aca2bfa2d98f89c267609754b4721266b7a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/befd6759042db2d3ac2d1444d67b64b835ad92b325b6c86cde48ae8b47c91904ccd0b1cdc3655868aa00eed97ba090e2284a8f754cd77b7d0d88ba7ab478fbf9", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3ebc6485ee1af4afc0a90ebad51abaa37838af4a835e8dc8599679eb1da9140b6514d9dc34af2d3d742659c7272fb6c5f9048596f2650a2f3b3c2d5d4a554138"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-18 07:32:23.582675) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 07:32:23.582970) ========= 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.yw1x8aIdKc/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.cxsGs203Mw/Listeners TMPDIR=/var/folders/8q/1qn5byls3hb2_j9sdd_5df0000000w/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.005030 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-18 07:32:23.630848) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 07:32:23.631129) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-18 07:32:23.631434) ========= ========= Total master_lag: 0.16 =========