builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0199 starttime: 1495502609.51 results: success (0) buildid: 20170522172523 builduid: 9c9ded593bfd415392f526a295a2f702 revision: a6edfa473c7dde50edc14a85374eb400e5ada494 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:29.512316) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:29.512829) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:29.513111) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:29.536763) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:29.537177) ========= 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.cUZXsDLcQv/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.bvLplIQ1gI/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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-22 18:23:29-- 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% 8.32M=0.001s 2017-05-22 18:23:29 (8.32 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.109842 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:29.670068) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:29.670379) ========= 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.cUZXsDLcQv/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.bvLplIQ1gI/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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.022790 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:29.719782) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 18:23:29.720202) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a6edfa473c7dde50edc14a85374eb400e5ada494 --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 a6edfa473c7dde50edc14a85374eb400e5ada494 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cUZXsDLcQv/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.bvLplIQ1gI/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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-22 18:23:29,796 truncating revision to first 12 chars 2017-05-22 18:23:29,796 Setting DEBUG logging. 2017-05-22 18:23:29,797 attempt 1/10 2017-05-22 18:23:29,797 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a6edfa473c7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-22 18:23:30,864 unpacking tar archive at: mozilla-beta-a6edfa473c7d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.326459 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-22 18:23:31.069492) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:31.070070) ========= script_repo_revision: a6edfa473c7dde50edc14a85374eb400e5ada494 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:31.070862) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:31.071329) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-22 18:23:31.090243) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-05-22 18:23:31.090655) ========= /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.cUZXsDLcQv/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.bvLplIQ1gI/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 18:23:31 INFO - MultiFileLogger online at 20170522 18:23:31 in /builds/slave/test 18:23:31 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 18:23:31 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:23:31 INFO - {'append_to_log': False, 18:23:31 INFO - 'base_work_dir': '/builds/slave/test', 18:23:31 INFO - 'blob_upload_branch': 'Mozilla-Beta', 18:23:31 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:23:31 INFO - 'branch': 'Mozilla-Beta', 18:23:31 INFO - 'buildbot_json_path': 'buildprops.json', 18:23:31 INFO - 'config_files': ('talos/mac_config.py',), 18:23:31 INFO - 'default_actions': ('clobber', 18:23:31 INFO - 'read-buildbot-config', 18:23:31 INFO - 'download-and-extract', 18:23:31 INFO - 'populate-webroot', 18:23:31 INFO - 'create-virtualenv', 18:23:31 INFO - 'install', 18:23:31 INFO - 'run-tests'), 18:23:31 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:23:31 INFO - 'download_minidump_stackwalk': True, 18:23:31 INFO - 'download_symbols': 'ondemand', 18:23:31 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:23:31 INFO - 'tooltool.py': '/tools/tooltool.py', 18:23:31 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:23:31 INFO - '/tools/misc-python/virtualenv.py')}, 18:23:31 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:23:31 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:23:31 INFO - 'gecko_profile': False, 18:23:31 INFO - 'gecko_profile_interval': 0, 18:23:31 INFO - 'installer_path': 'installer.exe', 18:23:31 INFO - 'log_level': 'info', 18:23:31 INFO - 'log_name': 'talos', 18:23:31 INFO - 'log_to_console': True, 18:23:31 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:23:31 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:23:31 INFO - 'opt_config_files': (), 18:23:31 INFO - 'pip_index': False, 18:23:31 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:23:31 INFO - 'cmd': ('bash', 18:23:31 INFO - '-c', 18:23:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:23:31 INFO - 'enabled': True, 18:23:31 INFO - 'halt_on_failure': False, 18:23:31 INFO - 'name': 'check_screen_resolution'},), 18:23:31 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:23:31 INFO - 'cmd': ('bash', 18:23:31 INFO - '-c', 18:23:31 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:23:31 INFO - 'enabled': True, 18:23:31 INFO - 'halt_on_failure': False, 18:23:31 INFO - 'name': 'check_screen_resolution'},), 18:23:31 INFO - 'run_cmd_checks_enabled': True, 18:23:31 INFO - 'suite': 'dromaeojs', 18:23:31 INFO - 'system_bits': '32', 18:23:31 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:23:31 INFO - 'title': 't-yosemite-r7-0199', 18:23:31 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:23:31 INFO - 'use_talos_json': True, 18:23:31 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:23:31 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:23:31 INFO - 'work_dir': 'build'} 18:23:31 INFO - [mozharness: 2017-05-23 01:23:31.293053Z] Running clobber step. 18:23:31 INFO - Running pre-action listener: _resource_record_pre_action 18:23:31 INFO - Running main action method: clobber 18:23:31 INFO - rmtree: /builds/slave/test/build 18:23:31 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:23:34 INFO - Running post-action listener: _resource_record_post_action 18:23:34 INFO - [mozharness: 2017-05-23 01:23:34.795843Z] Finished clobber step (success) 18:23:34 INFO - [mozharness: 2017-05-23 01:23:34.796077Z] Running read-buildbot-config step. 18:23:34 INFO - Running pre-action listener: _resource_record_pre_action 18:23:34 INFO - Running main action method: read_buildbot_config 18:23:34 INFO - Using buildbot properties: 18:23:34 INFO - { 18:23:34 INFO - "project": "", 18:23:34 INFO - "product": "firefox", 18:23:34 INFO - "script_repo_revision": "production", 18:23:34 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 18:23:34 INFO - "repository": "", 18:23:34 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 18:23:34 INFO - "buildid": "20170522172523", 18:23:34 INFO - "pgo_build": "False", 18:23:34 INFO - "basedir": "/builds/slave/test", 18:23:34 INFO - "buildnumber": 4, 18:23:34 INFO - "slavename": "t-yosemite-r7-0199", 18:23:34 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 18:23:34 INFO - "platform": "yosemite_r7", 18:23:34 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 18:23:34 INFO - "branch": "mozilla-beta", 18:23:34 INFO - "repo_path": "releases/mozilla-beta", 18:23:34 INFO - "revision": "a6edfa473c7dde50edc14a85374eb400e5ada494", 18:23:34 INFO - "stage_platform": "macosx64", 18:23:34 INFO - "builduid": "9c9ded593bfd415392f526a295a2f702", 18:23:34 INFO - "slavebuilddir": "test" 18:23:34 INFO - } 18:23:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 18:23:34 INFO - Running post-action listener: _resource_record_post_action 18:23:34 INFO - [mozharness: 2017-05-23 01:23:34.800988Z] Finished read-buildbot-config step (success) 18:23:34 INFO - [mozharness: 2017-05-23 01:23:34.801196Z] Running download-and-extract step. 18:23:34 INFO - Running pre-action listener: _resource_record_pre_action 18:23:34 INFO - Running main action method: download_and_extract 18:23:34 INFO - mkdir: /builds/slave/test/build/tests 18:23:34 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')]} 18:23:34 INFO - trying https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 18:23:34 INFO - Downloading https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/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 18:23:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/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 18:23:35 INFO - Downloaded 1287 bytes. 18:23:35 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 18:23:35 INFO - Using the following test package requirements: 18:23:35 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:35 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 18:23:35 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 18:23:35 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:35 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 18:23:35 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:35 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 18:23:35 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 18:23:35 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:35 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 18:23:35 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 18:23:35 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:35 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 18:23:35 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:35 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 18:23:35 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:35 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 18:23:35 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 18:23:35 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 18:23:35 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 18:23:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 18:23:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 18:23:35 INFO - Fetch https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 18:23:37 INFO - Expected file size: 51396036 18:23:37 INFO - Obtained file size: 51396036 18:23:53 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 18:23:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 18:23:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 18:23:53 INFO - Fetch https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 18:23:55 INFO - Expected file size: 51396036 18:23:55 INFO - Obtained file size: 51396036 18:24:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 18:24:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 18:24:10 INFO - Fetch https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 18:24:11 INFO - Expected file size: 14404449 18:24:11 INFO - Obtained file size: 14404449 18:24:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:11 INFO - trying https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 18:24:11 INFO - Downloading https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 18:24:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/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 18:24:13 INFO - Downloaded 59650219 bytes. 18:24:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 18:24:13 INFO - mkdir: /builds/slave/test/properties 18:24:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:24:13 INFO - Writing to file /builds/slave/test/properties/build_url 18:24:13 INFO - Contents: 18:24:13 INFO - build_url:https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 18:24:15 INFO - Running post-action listener: _resource_record_post_action 18:24:15 INFO - Running post-action listener: set_extra_try_arguments 18:24:15 INFO - [mozharness: 2017-05-23 01:24:15.000729Z] Finished download-and-extract step (success) 18:24:15 INFO - [mozharness: 2017-05-23 01:24:15.000886Z] Running populate-webroot step. 18:24:15 INFO - Running pre-action listener: _resource_record_pre_action 18:24:15 INFO - Running main action method: populate_webroot 18:24:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:24:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:24:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:24:15 INFO - '--tppagecycles', 18:24:15 INFO - '7'], 18:24:15 INFO - 'tests': ['tsvgm']}}, 18:24:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:24:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:24:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:24:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:24:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:24:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:24:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:24:15 INFO - 'talos_options': ['--disable-e10s'], 18:24:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:24:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:24:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:24:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:24:15 INFO - 'talos_options': ['--disable-e10s'], 18:24:15 INFO - 'tests': ['damp', 'tps']}, 18:24:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:24:15 INFO - 'tests': ['damp', 'tps']}, 18:24:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:24:15 INFO - 'tests': ['dromaeo_dom']}, 18:24:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:24:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:24:15 INFO - 'tests': ['basic_compositor_video']}, 18:24:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:24:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:24:15 INFO - 'tests': ['a11yr', 18:24:15 INFO - 'ts_paint', 18:24:15 INFO - 'tpaint', 18:24:15 INFO - 'sessionrestore', 18:24:15 INFO - 'sessionrestore_no_auto_restore', 18:24:15 INFO - 'tabpaint']}, 18:24:15 INFO - 'other-e10s': {'tests': ['a11yr', 18:24:15 INFO - 'ts_paint', 18:24:15 INFO - 'tpaint', 18:24:15 INFO - 'sessionrestore', 18:24:15 INFO - 'sessionrestore_no_auto_restore', 18:24:15 INFO - 'tabpaint']}, 18:24:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:24:15 INFO - 'ts_paint', 18:24:15 INFO - 'tpaint', 18:24:15 INFO - 'sessionrestore', 18:24:15 INFO - 'sessionrestore_no_auto_restore', 18:24:15 INFO - 'tabpaint']}, 18:24:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:24:15 INFO - 'ts_paint', 18:24:15 INFO - 'tpaint', 18:24:15 INFO - 'sessionrestore', 18:24:15 INFO - 'sessionrestore_no_auto_restore', 18:24:15 INFO - 'tabpaint']}, 18:24:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:24:15 INFO - 'tests': ['a11yr', 18:24:15 INFO - 'ts_paint', 18:24:15 INFO - 'tpaint', 18:24:15 INFO - 'sessionrestore', 18:24:15 INFO - 'sessionrestore_no_auto_restore', 18:24:15 INFO - 'tabpaint']}, 18:24:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:24:15 INFO - 'tests': ['a11yr', 18:24:15 INFO - 'ts_paint', 18:24:15 INFO - 'tpaint', 18:24:15 INFO - 'sessionrestore', 18:24:15 INFO - 'sessionrestore_no_auto_restore', 18:24:15 INFO - 'tabpaint']}, 18:24:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:24:15 INFO - 'tests': ['tsvgx', 18:24:15 INFO - 'tsvgr_opacity', 18:24:15 INFO - 'tart', 18:24:15 INFO - 'tscrollx', 18:24:15 INFO - 'cart', 18:24:15 INFO - 'tsvg_static']}, 18:24:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:24:15 INFO - 'tsvgr_opacity', 18:24:15 INFO - 'tart', 18:24:15 INFO - 'tscrollx', 18:24:15 INFO - 'cart', 18:24:15 INFO - 'tsvg_static']}, 18:24:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:24:15 INFO - 'talos_options': ['--disable-e10s'], 18:24:15 INFO - 'tests': ['tp5o']}, 18:24:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:24:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:24:15 INFO - 'talos_options': ['--disable-e10s', 18:24:15 INFO - '--xperf_path', 18:24:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:24:15 INFO - 'tests': ['tp5n']}, 18:24:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:24:15 INFO - 'talos_options': ['--xperf_path', 18:24:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:24:15 INFO - 'tests': ['tp5n']}}, 18:24:15 INFO - 'talos.zip': {'path': '', 18:24:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:24:15 INFO - Running post-action listener: _resource_record_post_action 18:24:15 INFO - [mozharness: 2017-05-23 01:24:15.018984Z] Finished populate-webroot step (success) 18:24:15 INFO - [mozharness: 2017-05-23 01:24:15.019119Z] Running create-virtualenv step. 18:24:15 INFO - Running pre-action listener: _resource_record_pre_action 18:24:15 INFO - Running main action method: create_virtualenv 18:24:15 INFO - Creating virtualenv /builds/slave/test/build/venv 18:24:15 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 18:24:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:24:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:24:15 INFO - Using real prefix '/tools/python27' 18:24:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:24:15 INFO - Installing distribute.............................................................................................................................................................................................done. 18:24:18 INFO - Installing pip.................done. 18:24:18 INFO - Return code: 0 18:24:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:24:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:24:18 INFO - Reading from file tmpfile_stdout 18:24:18 INFO - Output received: 18:24:18 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) 18:24:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:24: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')]} 18:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24: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')]} 18:24:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:18 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 0x104cbab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82955de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98d30>, '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 0x1049bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a8dbe8>, '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.cUZXsDLcQv/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.bvLplIQ1gI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 18:24:18 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 18:24:18 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 18:24:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUZXsDLcQv/Render', 18:24:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:18 INFO - 'HOME': '/Users/cltbld', 18:24:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:18 INFO - 'LOGNAME': 'cltbld', 18:24:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:18 INFO - 'MOZ_NO_REMOTE': '1', 18:24:18 INFO - 'NO_EM_RESTART': '1', 18:24:18 INFO - 'PAGER': '/bin/cat', 18:24:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:18 INFO - 'PWD': '/builds/slave/test', 18:24:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:18 INFO - 'SHELL': '/bin/bash', 18:24:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bvLplIQ1gI/Listeners', 18:24:18 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 18:24:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:18 INFO - 'USER': 'cltbld', 18:24:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:18 INFO - 'XPC_FLAGS': '0x0', 18:24:18 INFO - 'XPC_SERVICE_NAME': '0', 18:24:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:19 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 18:24:19 INFO - Cleaning up... 18:24:19 INFO - Return code: 0 18:24:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104cbab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82955de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98d30>, '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 0x1049bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a8dbe8>, '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.cUZXsDLcQv/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.bvLplIQ1gI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 18:24:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:24:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:24:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUZXsDLcQv/Render', 18:24:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:19 INFO - 'HOME': '/Users/cltbld', 18:24:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:19 INFO - 'LOGNAME': 'cltbld', 18:24:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:19 INFO - 'MOZ_NO_REMOTE': '1', 18:24:19 INFO - 'NO_EM_RESTART': '1', 18:24:19 INFO - 'PAGER': '/bin/cat', 18:24:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:19 INFO - 'PWD': '/builds/slave/test', 18:24:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:19 INFO - 'SHELL': '/bin/bash', 18:24:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bvLplIQ1gI/Listeners', 18:24:19 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 18:24:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:19 INFO - 'USER': 'cltbld', 18:24:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:19 INFO - 'XPC_FLAGS': '0x0', 18:24:19 INFO - 'XPC_SERVICE_NAME': '0', 18:24:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:19 INFO - Downloading/unpacking psutil>=3.1.1 18:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:24:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:24:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:24:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:24:21 INFO - Installing collected packages: psutil 18:24:21 INFO - Running setup.py install for psutil 18:24:21 INFO - building 'psutil._psutil_osx' extension 18:24:21 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 18:24:21 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 18:24:21 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 18:24:21 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 18:24:21 INFO - building 'psutil._psutil_posix' extension 18:24:21 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 18:24:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:24:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:24:22 INFO - ^ 18:24:22 INFO - 1 warning generated. 18:24:22 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 18:24:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:24:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:24:22 INFO - Successfully installed psutil 18:24:22 INFO - Cleaning up... 18:24:22 INFO - Return code: 0 18:24:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:24: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')]} 18:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24: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')]} 18:24:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:22 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 0x104cbab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82955de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98d30>, '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 0x1049bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a8dbe8>, '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.cUZXsDLcQv/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.bvLplIQ1gI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 18:24:22 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 18:24:22 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 18:24:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUZXsDLcQv/Render', 18:24:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:22 INFO - 'HOME': '/Users/cltbld', 18:24:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:22 INFO - 'LOGNAME': 'cltbld', 18:24:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:22 INFO - 'MOZ_NO_REMOTE': '1', 18:24:22 INFO - 'NO_EM_RESTART': '1', 18:24:22 INFO - 'PAGER': '/bin/cat', 18:24:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:22 INFO - 'PWD': '/builds/slave/test', 18:24:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:22 INFO - 'SHELL': '/bin/bash', 18:24:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bvLplIQ1gI/Listeners', 18:24:22 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 18:24:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:22 INFO - 'USER': 'cltbld', 18:24:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:22 INFO - 'XPC_FLAGS': '0x0', 18:24:22 INFO - 'XPC_SERVICE_NAME': '0', 18:24:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:24:22 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 18:24:22 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 18:24:22 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 18:24:22 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 18:24:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:24:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:24:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:24:23 INFO - Installing collected packages: mozsystemmonitor 18:24:23 INFO - Running setup.py install for mozsystemmonitor 18:24:24 INFO - Successfully installed mozsystemmonitor 18:24:24 INFO - Cleaning up... 18:24:24 INFO - Return code: 0 18:24:24 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:24:24 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')]} 18:24:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:24 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')]} 18:24:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:24 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 0x104cbab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82955de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98d30>, '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 0x1049bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a8dbe8>, '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.cUZXsDLcQv/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.bvLplIQ1gI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 18:24:24 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 18:24:24 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 18:24:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUZXsDLcQv/Render', 18:24:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:24 INFO - 'HOME': '/Users/cltbld', 18:24:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:24 INFO - 'LOGNAME': 'cltbld', 18:24:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:24 INFO - 'MOZ_NO_REMOTE': '1', 18:24:24 INFO - 'NO_EM_RESTART': '1', 18:24:24 INFO - 'PAGER': '/bin/cat', 18:24:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:24 INFO - 'PWD': '/builds/slave/test', 18:24:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:24 INFO - 'SHELL': '/bin/bash', 18:24:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bvLplIQ1gI/Listeners', 18:24:24 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 18:24:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:24 INFO - 'USER': 'cltbld', 18:24:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:24 INFO - 'XPC_FLAGS': '0x0', 18:24:24 INFO - 'XPC_SERVICE_NAME': '0', 18:24:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:24 INFO - Downloading/unpacking jsonschema==2.5.1 18:24:24 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 18:24:24 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 18:24:24 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 18:24:24 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 18:24:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:24:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:24:25 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 18:24:25 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 18:24:25 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 18:24:25 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 18:24:26 INFO - Downloading functools32-3.2.3-2.tar.gz 18:24:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:24:26 INFO - warning: no files found matching '*.txt' 18:24:26 INFO - no previously-included directories found matching 'build' 18:24:26 INFO - no previously-included directories found matching 'dist' 18:24:26 INFO - no previously-included directories found matching '.git*' 18:24:26 INFO - Installing collected packages: jsonschema, functools32 18:24:26 INFO - Running setup.py install for functools32 18:24:26 INFO - warning: no files found matching '*.txt' 18:24:26 INFO - no previously-included directories found matching 'build' 18:24:26 INFO - no previously-included directories found matching 'dist' 18:24:26 INFO - no previously-included directories found matching '.git*' 18:24:26 INFO - Successfully installed jsonschema functools32 18:24:26 INFO - Cleaning up... 18:24:26 INFO - Return code: 0 18:24:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:24:26 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')]} 18:24:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:26 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')]} 18:24:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:27 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 0x104cbab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82955de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98d30>, '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 0x1049bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a8dbe8>, '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.cUZXsDLcQv/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.bvLplIQ1gI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 18:24:27 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 18:24:27 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 18:24:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUZXsDLcQv/Render', 18:24:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:27 INFO - 'HOME': '/Users/cltbld', 18:24:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:27 INFO - 'LOGNAME': 'cltbld', 18:24:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:27 INFO - 'MOZ_NO_REMOTE': '1', 18:24:27 INFO - 'NO_EM_RESTART': '1', 18:24:27 INFO - 'PAGER': '/bin/cat', 18:24:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:27 INFO - 'PWD': '/builds/slave/test', 18:24:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:27 INFO - 'SHELL': '/bin/bash', 18:24:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bvLplIQ1gI/Listeners', 18:24:27 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 18:24:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:27 INFO - 'USER': 'cltbld', 18:24:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:27 INFO - 'XPC_FLAGS': '0x0', 18:24:27 INFO - 'XPC_SERVICE_NAME': '0', 18:24:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:24:27 INFO - Cleaning up... 18:24:27 INFO - Return code: 0 18:24:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:24:27 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')]} 18:24:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:27 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')]} 18:24:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:27 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 0x104cbab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82955de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98d30>, '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 0x1049bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a8dbe8>, '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.cUZXsDLcQv/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.bvLplIQ1gI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 18:24:27 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 18:24:27 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 18:24:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUZXsDLcQv/Render', 18:24:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:27 INFO - 'HOME': '/Users/cltbld', 18:24:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:27 INFO - 'LOGNAME': 'cltbld', 18:24:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:27 INFO - 'MOZ_NO_REMOTE': '1', 18:24:27 INFO - 'NO_EM_RESTART': '1', 18:24:27 INFO - 'PAGER': '/bin/cat', 18:24:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:28 INFO - 'PWD': '/builds/slave/test', 18:24:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:28 INFO - 'SHELL': '/bin/bash', 18:24:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bvLplIQ1gI/Listeners', 18:24:28 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 18:24:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:28 INFO - 'USER': 'cltbld', 18:24:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:28 INFO - 'XPC_FLAGS': '0x0', 18:24:28 INFO - 'XPC_SERVICE_NAME': '0', 18:24:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:28 INFO - Downloading/unpacking blobuploader==1.2.4 18:24:28 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 18:24:28 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 18:24:28 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 18:24:28 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 18:24:30 INFO - Downloading blobuploader-1.2.4.tar.gz 18:24:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:24:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:24:30 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 18:24:30 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 18:24:30 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 18:24:30 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 18:24:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:24:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:24:30 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 18:24:30 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 18:24:30 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 18:24:30 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 18:24:31 INFO - Downloading docopt-0.6.1.tar.gz 18:24:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:24:31 INFO - Installing collected packages: blobuploader, requests, docopt 18:24:31 INFO - Running setup.py install for blobuploader 18:24:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:24:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:24:31 INFO - Running setup.py install for requests 18:24:31 INFO - Running setup.py install for docopt 18:24:31 INFO - Successfully installed blobuploader requests docopt 18:24:31 INFO - Cleaning up... 18:24:31 INFO - Return code: 0 18:24:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:31 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')]} 18:24:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:31 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')]} 18:24:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:31 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 0x104cbab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82955de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98d30>, '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 0x1049bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a8dbe8>, '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.cUZXsDLcQv/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.bvLplIQ1gI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 18:24:31 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 18:24:31 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 18:24:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUZXsDLcQv/Render', 18:24:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:31 INFO - 'HOME': '/Users/cltbld', 18:24:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:31 INFO - 'LOGNAME': 'cltbld', 18:24:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:31 INFO - 'MOZ_NO_REMOTE': '1', 18:24:31 INFO - 'NO_EM_RESTART': '1', 18:24:31 INFO - 'PAGER': '/bin/cat', 18:24:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:31 INFO - 'PWD': '/builds/slave/test', 18:24:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:31 INFO - 'SHELL': '/bin/bash', 18:24:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bvLplIQ1gI/Listeners', 18:24:31 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 18:24:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:31 INFO - 'USER': 'cltbld', 18:24:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:31 INFO - 'XPC_FLAGS': '0x0', 18:24:31 INFO - 'XPC_SERVICE_NAME': '0', 18:24:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:32 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-QCDJDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:32 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-yDmtMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:32 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-rHTwz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:32 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-_wBvt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:32 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-631Agh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:32 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-7reegQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:32 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-Vqu43D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:32 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-rsFfn_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:33 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-TqaQId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:33 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-434Qnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:33 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-TAdNMr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:24:33 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-0pwXSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:33 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-kVAABS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:33 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-9A6H3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:33 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-_QPUHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:33 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-1HJm9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:33 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-O2X8CN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:24:34 INFO - Running setup.py install for manifestparser 18:24:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:24:34 INFO - Running setup.py install for mozcrash 18:24:34 INFO - Running setup.py install for mozdebug 18:24:34 INFO - Running setup.py install for mozdevice 18:24:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:24:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:24:34 INFO - Running setup.py install for mozfile 18:24:34 INFO - Running setup.py install for mozhttpd 18:24:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:24:34 INFO - Running setup.py install for mozinfo 18:24:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:24:35 INFO - Running setup.py install for mozInstall 18:24:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:24:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:24:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:24:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:24:35 INFO - Running setup.py install for mozleak 18:24:35 INFO - Running setup.py install for mozlog 18:24:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:24:35 INFO - Running setup.py install for moznetwork 18:24:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:24:35 INFO - Running setup.py install for mozprocess 18:24:35 INFO - Running setup.py install for mozprofile 18:24:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:24:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:24:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:24:36 INFO - Running setup.py install for mozrunner 18:24:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:24:36 INFO - Running setup.py install for mozscreenshot 18:24:36 INFO - Running setup.py install for moztest 18:24:36 INFO - Running setup.py install for mozversion 18:24:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:24:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:24:36 INFO - Cleaning up... 18:24:36 INFO - Return code: 0 18:24:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:36 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')]} 18:24:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:36 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')]} 18:24:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:36 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 0x104cbab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82955de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98d30>, '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 0x1049bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a8dbe8>, '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.cUZXsDLcQv/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.bvLplIQ1gI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 18:24:36 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 18:24:36 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 18:24:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUZXsDLcQv/Render', 18:24:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:36 INFO - 'HOME': '/Users/cltbld', 18:24:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:36 INFO - 'LOGNAME': 'cltbld', 18:24:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:36 INFO - 'MOZ_NO_REMOTE': '1', 18:24:36 INFO - 'NO_EM_RESTART': '1', 18:24:36 INFO - 'PAGER': '/bin/cat', 18:24:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:36 INFO - 'PWD': '/builds/slave/test', 18:24:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:36 INFO - 'SHELL': '/bin/bash', 18:24:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bvLplIQ1gI/Listeners', 18:24:36 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 18:24:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:36 INFO - 'USER': 'cltbld', 18:24:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:36 INFO - 'XPC_FLAGS': '0x0', 18:24:36 INFO - 'XPC_SERVICE_NAME': '0', 18:24:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:24:36 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-XZ2_9G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:24:37 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)) 18:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:24:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-GA2G_J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:24:37 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)) 18:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:24:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-dS992S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:24:37 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)) 18:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:24:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-LYLyxx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:24:37 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)) 18:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:24:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-QoDgLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:24:37 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)) 18:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:24:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-YN8X6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:24:37 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)) 18:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:24:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-HyUIfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:24:37 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)) 18:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:24:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-n6AlVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:24:37 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)) 18:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:24:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-2DU682-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:24:37 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)) 18:24:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:24:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-MbUsWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:24:38 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)) 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:24:38 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-tVZvSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:24:38 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)) 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:24:38 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-3X4Qce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:24:38 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)) 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:24:38 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-X3gJ_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:24:38 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)) 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:24:38 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-TRKzAg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:24:38 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)) 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:38 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-P7eazS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:24:38 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)) 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:24:38 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-55Gkgy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:24:38 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)) 18:24:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:24:38 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-phsyKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:24:38 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)) 18:24:38 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)) 18:24:38 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)) 18:24:38 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)) 18:24:38 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)) 18:24:38 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)) 18:24:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:24:38 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 18:24:38 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 18:24:38 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 18:24:38 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 18:24:40 INFO - Downloading blessings-1.6.tar.gz 18:24:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:24:40 INFO - Installing collected packages: blessings 18:24:40 INFO - Running setup.py install for blessings 18:24:40 INFO - Successfully installed blessings 18:24:40 INFO - Cleaning up... 18:24:40 INFO - Return code: 0 18:24:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:24:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:40 INFO - Reading from file tmpfile_stdout 18:24:40 INFO - Current package versions: 18:24:40 INFO - blessings == 1.6 18:24:40 INFO - blobuploader == 1.2.4 18:24:40 INFO - docopt == 0.6.1 18:24:40 INFO - functools32 == 3.2.3-2 18:24:40 INFO - jsonschema == 2.5.1 18:24:40 INFO - manifestparser == 1.1 18:24:40 INFO - mozInstall == 1.12 18:24:40 INFO - mozcrash == 1.0 18:24:40 INFO - mozdebug == 0.1 18:24:40 INFO - mozdevice == 0.49 18:24:40 INFO - mozfile == 1.2 18:24:40 INFO - mozhttpd == 0.7 18:24:40 INFO - mozinfo == 0.9 18:24:40 INFO - mozleak == 0.1 18:24:40 INFO - mozlog == 3.4 18:24:40 INFO - moznetwork == 0.27 18:24:40 INFO - mozprocess == 0.25 18:24:40 INFO - mozprofile == 0.28 18:24:40 INFO - mozrunner == 6.13 18:24:40 INFO - mozscreenshot == 0.1 18:24:40 INFO - mozsystemmonitor == 0.3 18:24:40 INFO - moztest == 0.8 18:24:40 INFO - mozversion == 1.4 18:24:40 INFO - psutil == 3.1.1 18:24:40 INFO - requests == 1.2.3 18:24:40 INFO - wsgiref == 0.1.2 18:24:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:24:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:40 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 0x104cbab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82955de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98d30>, '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 0x1049bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a8dbe8>, '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.cUZXsDLcQv/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.bvLplIQ1gI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 18:24:40 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 18:24:40 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 18:24:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUZXsDLcQv/Render', 18:24:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:40 INFO - 'HOME': '/Users/cltbld', 18:24:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:40 INFO - 'LOGNAME': 'cltbld', 18:24:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:40 INFO - 'MOZ_NO_REMOTE': '1', 18:24:40 INFO - 'NO_EM_RESTART': '1', 18:24:40 INFO - 'PAGER': '/bin/cat', 18:24:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:40 INFO - 'PWD': '/builds/slave/test', 18:24:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:40 INFO - 'SHELL': '/bin/bash', 18:24:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bvLplIQ1gI/Listeners', 18:24:40 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 18:24:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:40 INFO - 'USER': 'cltbld', 18:24:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:40 INFO - 'XPC_FLAGS': '0x0', 18:24:40 INFO - 'XPC_SERVICE_NAME': '0', 18:24:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:41 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)) 18:24:41 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)) 18:24:41 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)) 18:24:41 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)) 18:24:41 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)) 18:24:41 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)) 18:24:41 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)) 18:24:41 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)) 18:24:41 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)) 18:24:41 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)) 18:24:41 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)) 18:24:41 INFO - Cleaning up... 18:24:41 INFO - Return code: 0 18:24:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:24:41 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')]} 18:24:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:24:41 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')]} 18:24:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:24:41 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 0x104cbab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cbd768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb82955de70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a98d30>, '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 0x1049bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a8dbe8>, '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.cUZXsDLcQv/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.bvLplIQ1gI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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 18:24:41 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 18:24:41 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 18:24:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUZXsDLcQv/Render', 18:24:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:41 INFO - 'HOME': '/Users/cltbld', 18:24:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:41 INFO - 'LOGNAME': 'cltbld', 18:24:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:41 INFO - 'MOZ_NO_REMOTE': '1', 18:24:41 INFO - 'NO_EM_RESTART': '1', 18:24:41 INFO - 'PAGER': '/bin/cat', 18:24:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:41 INFO - 'PWD': '/builds/slave/test', 18:24:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:41 INFO - 'SHELL': '/bin/bash', 18:24:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bvLplIQ1gI/Listeners', 18:24:41 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 18:24:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:41 INFO - 'USER': 'cltbld', 18:24:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:41 INFO - 'XPC_FLAGS': '0x0', 18:24:41 INFO - 'XPC_SERVICE_NAME': '0', 18:24:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:24:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:24:41 INFO - Cleaning up... 18:24:41 INFO - Return code: 0 18:24:41 INFO - Running post-action listener: _resource_record_post_action 18:24:41 INFO - Running post-action listener: _start_resource_monitoring 18:24:41 INFO - Starting resource monitoring. 18:24:41 INFO - [mozharness: 2017-05-23 01:24:41.349721Z] Finished create-virtualenv step (success) 18:24:41 INFO - [mozharness: 2017-05-23 01:24:41.350791Z] Running install step. 18:24:41 INFO - Running pre-action listener: _resource_record_pre_action 18:24:41 INFO - Running main action method: install 18:24:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:24:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:24:41 INFO - Reading from file tmpfile_stdout 18:24:41 INFO - Detecting whether we're running mozinstall >=1.0... 18:24:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:24:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:24:41 INFO - Reading from file tmpfile_stdout 18:24:41 INFO - Output received: 18:24:41 INFO - Usage: mozinstall [options] installer 18:24:41 INFO - Options: 18:24:41 INFO - -h, --help show this help message and exit 18:24:41 INFO - -d DEST, --destination=DEST 18:24:41 INFO - Directory to install application into. [default: 18:24:41 INFO - "/builds/slave/test"] 18:24:41 INFO - --app=APP Application being installed. [default: firefox] 18:24:41 INFO - mkdir: /builds/slave/test/build/application 18:24:41 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'] 18:24:41 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 18:24:56 INFO - Reading from file tmpfile_stdout 18:24:56 INFO - Output received: 18:24:56 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 18:24:56 INFO - Running post-action listener: _resource_record_post_action 18:24:56 INFO - [mozharness: 2017-05-23 01:24:56.562700Z] Finished install step (success) 18:24:56 INFO - [mozharness: 2017-05-23 01:24:56.562832Z] Running run-tests step. 18:24:56 INFO - Running pre-action listener: _resource_record_pre_action 18:24:56 INFO - Running main action method: run_tests 18:24:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:24:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:24:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:24:56 INFO - 2017-05-22 18:24:56.577 screenresolution[1914:18559] starting screenresolution argv=screenresolution get 18:24:56 INFO - 2017-05-22 18:24:56.580 screenresolution[1914:18559] Display 0: 1600x1200x32@60 18:24:56 INFO - 2017-05-22 18:24:56.585 screenresolution[1915:18562] starting screenresolution argv=screenresolution list 18:24:56 INFO - Available Modes on Display 0 18:24:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:24:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:24:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:24:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:24:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:24:56 INFO - Intel Iris: 18:24:56 INFO - Chipset Model: Intel Iris 18:24:56 INFO - Type: GPU 18:24:56 INFO - Bus: Built-In 18:24:56 INFO - VRAM (Dynamic, Max): 1536 MB 18:24:56 INFO - Vendor: Intel (0x8086) 18:24:56 INFO - Device ID: 0x0a2e 18:24:56 INFO - Revision ID: 0x0009 18:24:56 INFO - Displays: 18:24:56 INFO - Display: 18:24:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:24:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:24:56 INFO - Display Serial Number: 1600x1200 60 18:24:56 INFO - Main Display: Yes 18:24:56 INFO - Mirror: Off 18:24:56 INFO - Online: Yes 18:24:56 INFO - Rotation: Supported 18:24:56 INFO - Return code: 0 18:24:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:24:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:24:56 INFO - Python 2.7.3 18:24:56 INFO - Return code: 0 18:24:56 INFO - grabbing minidump binary from tooltool 18:24:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:24:56 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 0x104a98d30>, '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 0x1049bfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a8dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:24:56 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 18:24:56 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 18:24:56 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 18:24:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:24:56 INFO - Return code: 0 18:24:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:24:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:24:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:24:56 INFO - ENV: RUST_BACKTRACE is now 1 18:24:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:24:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:24:56 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/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0199', '--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 18:24:56 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/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0199 --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 18:24:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cUZXsDLcQv/Render', 18:24:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:24:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:24:56 INFO - 'HOME': '/Users/cltbld', 18:24:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:24:56 INFO - 'LOGNAME': 'cltbld', 18:24:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:24:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:24:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:24:56 INFO - 'MOZ_NO_REMOTE': '1', 18:24:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:24:56 INFO - 'NO_EM_RESTART': '1', 18:24:56 INFO - 'PAGER': '/bin/cat', 18:24:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:24:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:24:56 INFO - 'PWD': '/builds/slave/test', 18:24:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:24:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:24:56 INFO - 'RUST_BACKTRACE': '1', 18:24:56 INFO - 'SHELL': '/bin/bash', 18:24:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bvLplIQ1gI/Listeners', 18:24:56 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 18:24:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:24:56 INFO - 'USER': 'cltbld', 18:24:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:24:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:24:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:24:56 INFO - 'XPC_FLAGS': '0x0', 18:24:56 INFO - 'XPC_SERVICE_NAME': '0', 18:24:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:24:56 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/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0199', '--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 18:24:57 INFO - mozversion application_buildid: 20170522172523 18:24:57 INFO - mozversion application_changeset: a6edfa473c7dde50edc14a85374eb400e5ada494 18:24:57 INFO - mozversion application_display_name: Firefox 18:24:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:24:57 INFO - mozversion application_name: Firefox 18:24:57 INFO - mozversion application_remotingname: firefox 18:24:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 18:24:57 INFO - mozversion application_vendor: Mozilla 18:24:57 INFO - mozversion application_version: 54.0 18:24:57 INFO - mozversion platform_buildid: 20170522172523 18:24:57 INFO - mozversion platform_changeset: a6edfa473c7dde50edc14a85374eb400e5ada494 18:24:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 18:24:57 INFO - mozversion platform_version: 54.0 18:24:57 INFO - using testdate: 1495502697 18:24:57 INFO - actual date: 1495502697 18:24:57 INFO - starting webserver on 'localhost:55813' 18:24:57 INFO - SUITE-START | Running 2 tests 18:24:57 INFO - TEST-START | dromaeo_css 18:24:57 INFO - operating with platform_type : mac_ 18:24:57 INFO - Initialising browser for dromaeo_css test... 18:24:57 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmpNZwyBF/profile http://localhost:55813/getInfo.html) 18:24:59 INFO - PROCESS | 1925 | __metrics Screen width/height:1600/1200 18:24:59 INFO - PROCESS | 1925 | colorDepth:24 18:24:59 INFO - PROCESS | 1925 | Browser inner width/height: 1024/689 18:24:59 INFO - PROCESS | 1925 | __metrics 18:24:59 INFO - PROCESS | 1925 | 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" 18:24:59 INFO - PROCESS | 1925 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:25:00 INFO - TEST-INFO | 1925: exit 0 18:25:00 INFO - Browser initialized. 18:25:00 INFO - Running cycle 1/1 for dromaeo_css test... 18:25:00 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmpNZwyBF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:25:12 INFO - PROCESS | 1928 | RSS: Main: 165355520 18:25:12 INFO - PROCESS | 1928 | 18:28:16 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55813/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55813/tests/dromaeo/cssquery-ext.html) 18:28:18 INFO - PROCESS | 1928 | RSS: Main: 286597120 18:28:18 INFO - PROCESS | 1928 | 18:31:21 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55813/tests/dromaeo/cssquery-ext.html (next: http://localhost:55813/tests/dromaeo/cssquery-jquery.html) 18:31:22 INFO - PROCESS | 1928 | RSS: Main: 302383104 18:31:22 INFO - PROCESS | 1928 | 18:34:24 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55813/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55813/tests/dromaeo/cssquery-mootools.html) 18:34:25 INFO - PROCESS | 1928 | RSS: Main: 276017152 18:34:25 INFO - PROCESS | 1928 | 18:37:27 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55813/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55813/tests/dromaeo/cssquery-prototype.html) 18:37:28 INFO - PROCESS | 1928 | RSS: Main: 269533184 18:37:28 INFO - PROCESS | 1928 | 18:40:31 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55813/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55813/tests/dromaeo/cssquery-yui.html) 18:40:32 INFO - PROCESS | 1928 | RSS: Main: 268075008 18:40:32 INFO - PROCESS | 1928 | 18:43:35 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:55813/tests/dromaeo/cssquery-yui.html (next: http://localhost:55813/tests/dromaeo/cssquery-dojo.html) 18:43:35 INFO - PROCESS | 1928 | RSS: Main: 348254208 18:43:35 INFO - PROCESS | 1928 | 18:43:35 INFO - PROCESS | 1928 | __start_tp_report 18:43:35 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 18:43:35 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 18:43:35 INFO - PROCESS | 1928 | |i|pagename|runs| 18:43:35 INFO - PROCESS | 1928 | |0;dojo.html;3190.4257233697926;3539.94690079649;3558.2705545363197;3605.3870841956864;3605.4051081571542;5168.844934651962;6174.7530098796105;6175.629462232264;6185.505159587234;6211.844703882394;6072.210612620364;6159.8152055438295;6203.410675985785;6216.751329946808;6221.377862213776;4833.903321933559;5617.269754931853;5640.182173584828;5648.983183027053;5706.115552089433;5516;5568;5577.860553486175;5631.380548139701;5768.8846223075725;5647.858803529924;5660.60375773696;5662.122371032497;5668.914966275509;5735.08238681811;5725.570582206351;5734.311882574076;5745.856353591152;5788.797392091277;5815.82552523425;5552.250446189753;5685.289338832646;5687.971560142214;5694.857628559278;5696.857578560528;6215.81352559424;6223.875522489525;6234.127222188898;6241.594296370721;6251.343608921089;5236.502532259429;6290.308066112724;6299.307076221612;6325.620462772203;6533.052707357454;6617.966910165466;6713.127293451869;6717.932820671828;6718.462522998148;6731.293214212486;6243.906341404882;6254.437100660963;6258.718357673824;6271.404216599416;6275.466585340251;6535.019747037983;6599.70301336441;6879.6560171991205;6896.827579310558;6897.827554311182;6342.682865856688;6347.873042539123;6352.8411789704705;6384.52116091295;6388.393102655334;6626.768063117768;6661.467082633475;6669.1330127083165;6669.933300667029;6671.366220209073;6278.46633036197;6486.416222539994;6531.477481801445;6563.671816409256;6576.539642225094;6381.1385462962835;6485.092087107809;6523.7064332105165;6587.077809106729;6605.768798092044;5172.431032586413;5184.37787465499;5197.66215196011;5199.012187684328;5304.177852432879;4739.312799644033;4787.42550893895;4787.54518320759;4790.568848803624;4803.615710743132;6181.381861813783;6278.99536074235;6281.623102613858;6290.811275661737;6291.937080629226;6335.524835637346;6393.296737358887;6663.700133494004;6682.365175308338;6684.799456016327;6154.599951003171;6155.569110162335;6203.193584833943;6209.813705588839;6243.313235544087;5379.085555455504;5509.5675124467925;5844.211031510692;5849.327327357409;5875.853103672443;6853.314668533107;6883.931160688529;6932.272111428228;6952.756653517305;6957.582545047313;6349.936500634934;6398.776042838478;6544.443722283469;6608.570442921195;6679.732810687518;6914.170299564084;6954.269801670752;6954.652267386711;6967.372936435745;6968.128983877015;6532.640704761284;6559.114519539801;6570.408663220333;6597.80206593803;6608.636524991171;6352.9047064293145;6391.265004524354;6544.901826472512;6688.866222675422;6703.798886033427;6351.587146835441;6427.678616069272;6429.839254018687;6446.033095035782;6448.709808058742;5211.478852114758;6409.647469389229;6427.614343139427;6456.644884531396;6458.289588145206;6225.719842607185;6267;6274.968625156845;6275.780347687809;6317.873642527032;6338.746450142127;6350.904736428864;6428.164338636135;6450.258220304538;6451.741930322734;6306.684665766785;6309.1167236587835;6309.895768240632;6311.305756366704;6322.241331040304 18:43:35 INFO - PROCESS | 1928 | |1;ext.html;741.8553382090493;786.9724559640414;828.0146625515634;891.6522556203082;903.661127077346;29647.999999999975;40657.39013914794;41308.38037429441;41338.58661413385;41451.963700907494;41757.58242417575;41902;41939.532116375936;41939.79030104853;41979.790101049526;8191.344692424622;9707.660231891885;9867.16129129025;9869.259805514579;9870.407775533455;8203.876941845878;11761.470733817;11953.820692689573;12144.696382590462;12193.146479746421;11974.880251197465;11998.64004079879;12009.819852702216;12011.27932324059;12021.398930053507;11928.403579820973;11956.461959211856;11986.340751258676;12005.898967680665;12013.159078864483;11557.768844623144;11619;11640.243384180001;11641.70895727605;11652.766944661143;40105.598944010475;40343.59656403428;40363.394549081786;40373.394399084034;40654.593454065376;3990;4031.1333063391216;4052.756834589934;4052.7770972596495;4061.695372847019;27875.88496460139;28488.14827036236;30062.79748810045;30612.08163755091;30652.540211896838;3281.146901805524;3283.605967283941;3312.569365982407;3410.6759857813468;3424.708899743525;356985;361407;362043;363621.1818940915;364128;54576.45423545714;54772.90454190894;54804.451955480734;54929.72535137299;54933;56632;56658;56660.86678266412;56699;56771;15825.050496970218;15886.205689715467;15897.761533577006;15897.920510397375;15910.284037218122;11869.525218991317;13639.863601363859;13642.317884105954;13647.590572282847;13659.907207423383;11232.438378081064;11254;12338.074644401706;12570;12585.685357865943;4826.179549476527;4908.251154662339;5565.916511252334;5614.270144881221;5633.718314084279;1085.5440714899762;1090.4166271045112;1093.4423444043596;1096.6107032003683;1154.1920655540987;23571.646425303636;25999.960001600102;27795.305117371823;28361.432771344458;28417.431651366856;1149.5804031528432;1151.78691941991;1156.2888823373564;1203.3562044306254;1206.849143857018;14369.281535923372;14415.711685766435;14693.77959330611;14700.55898323052;14718.779218311734;472261;472802;472847;473384;473760;48641.75679121582;49376.25935610969;51639.74180129075;58025.41974580284;59023.11465328024;1490.1580606957034;1520.6046427928597;1536.3931247157534;1543.6989786991428;1543.7838702581648;29190.854045729637;29264;30634.995647307547;30875.382492349578;31095.844520778155;43950.560494394646;44831.32753008642;45616.771916140206;47813.760931195124;47952;471710;473949;478434;483729;491421;34529.48205776865;35101.122471938404;35209.47185792266;35347.29305413826;35351.76268830579;42921.71234862959;43696;44696.77651611851;44771;44880.551194487634;29971.700282997765;30201.546976794925;30576.235594110327;31198.22004449907;31618.683813161573;30044.69955300419;30143.698563014088;30385.69614303917;30732;30888.845555772077;34972.65027349796;35665.46501802527;35925.28149436945;36522.8173859129;36648.81675591605;30371.39257214799;30461.695383046772;30702.385952281275;31411.84294078515;31443.68556314499;23536.6469502961;24431.75568244295;24687.753122468544;24935.50128997446;25043.998240070923 18:43:35 INFO - PROCESS | 1928 | |2;jquery.html;439.3563429575671;439.7163829330081;444.6398417282004;446.57352228621664;456.5320546439901;21217.787822121772;21945.231916882913;22334.999999999978;22739.97670104845;22928;18015.549611259692;22283.108675652955;22754.431139221528;22857.542849143007;22907.427314317152;6818.9659051704675;7121.145462544476;7122.821929451753;7668.961655191716;7979.880301795478;7740.419468539854;8076.838463230732;8126.471779334324;8131.349492040652;8140.918590814075;8190.4266701330935;8242.917570824306;8257.25684688378;8270.669173233064;8276.875846862271;7941;8069.556174410405;8170.223828736261;8175.7138500152205;8198.467099638505;7637;7848.332891704212;7908.723194688216;7946.364290856717;7950.4832185907735;26877.73122268772;27040.864795676094;27417.31456713598;27583.724162758317;27778.583321250197;2968.584398184256;2997.436599387766;3045.162580290416;3090.4128215638957;3122.7970181938103;15524.61188470286;15769.526914192593;16033.919830400773;16081.517554473385;16774.49676509707;2109.799569040939;2136.081484961466;2138.957220855574;2166.8374871884516;2227.7438094619165;162082;165996;167729;172226;173738;20684.069216885277;20783;20939.953002349823;21121.78878211229;21156.990085941812;34011;34199.658003419645;34373.48439773406;34419;34453.82773086119;10704.19718521114;10748.193885458622;10833.945830270955;10873.565057397775;11058.612948546763;9027;9028.458292502472;9050.954745226232;9219.815603687888;9229.35394522377;9381.812363752686;9796.9020309796;9804.754881127887;9820.508974551243;10062.19502439803;4049.959500405017;4139.2135494256;4161.170404125163;4177.895552611149;4179.749215047107;857.9571021448903;972.4116909269932;1036.8133735927454;1083.9891601084046;1096.4627332606965;20069.59860802805;20487.8975605124;21818.018189181526;21834.454138646342;21992.89003554972;1024.6925922223304;1025.6307729217476;1040.8178568750438;1059.502034043998;1068.5885933915342;10973.945130274456;11305.94347028276;11339.716507087389;11366.829497557543;11373.772524549628;771189;784431;787423;796385;800087;143629;168119;171013.7246568846;174434.02263909302;175460.12269938824;1102.8125218712837;1310.8951283897266;1318.9076764626384;1350.1898860683511;1352.3238380809596;19858.801411986275;20016.699749503478;21788;21865.67201491947;21907.795071271234;68534.31465685279;68716.96924546036;69131.65434172798;69600;69671;85428.57285713531;85871.71192431994;87360.6319684026;88100.00992161971;88583.55708221417;10397.84403233937;11576.768464630828;11817.527298908291;11823.113266504875;12105.515779368727;13501.932490337485;13630.250336231602;13709.314534273048;13931.860681393056;13968.371423285773;11540.653780386267;11639.6508104757;11854.348010859485;11878.584249551224;12005.63983080509;10766.138708903425;10781.892181078088;11170.888291116984;11268.830967535658;11320.490577924178;11685.298882067103;11702.88297117018;11909.76180476403;12068.336241506802;12124;9359.719208423758;11293.943530282297;11538.365389903636;11875.287482751062;11964.042876570027;11540.94229528847;11675.941620291844;11771.764564708828;12035.939820300842;12089.335086570323 18:43:35 INFO - PROCESS | 1928 | |3;mootools.html;1424.8860091192694;1615.3296382001463;1642.236360092556;1652.7190377635798;1659.2118743596789;9406.05939406059;9784.315097943147;11145;11414.942925285382;11612.303261804298;11292.887071129286;11334.319940803558;11339.036181924546;11352.262102963301;11396.14528910331;2188.7482939462006;2452.117237794397;2491.0035985605814;2493.2021753039035;2495.1641200197955;2136.9893150534303;2211.5687440949023;2446.070493212577;2515.6981162260554;2527.747225277476;2472.752724727522;2484.7266800651914;2503.7746602805746;2503.849769013865;2504.023430861965;2483.515714435686;2488.9626655600273;2489.5394352044927;2490.4272017436;2491.725910149882;2397.520495900827;2427.2475532584776;2435.1842132885504;2436.9268921932194;2483.981567557311;11507.65477035682;11602.245854019462;11602.535898564047;11624.302541847432;11646.301221926711;5800.303963524362;5971.462568368867;5973.880522389528;5975.9701201494145;5982.97008514959;3557.864688538682;3643.508126402928;3812.218495208476;3861.8841434757;3877.0695033192114;1250.72484053509;1267.5373488676566;1291.4640424223935;1294.1717300927414;1311;15833.337499562494;15905.125218113115;15905.681886362208;15922.522324330288;15925.124118173495;4790.0252657388;4966.875828104254;5011.974940125276;5071.366079240095;5152.92270615941;12430.627081187578;12463.314517701432;12480.313582752855;12489.313087780078;12489.437975290952;6019.277686677627;6035.78874739382;6038.818835434944;6039.7282122304605;6055.031195008777;4745.098431298043;5218.191180367049;5364.892702145935;5472.2338872557875;5541.362743284574;3813.9427908581392;3905.511811023622;4195.727277726999;4211.1998720243055;4241.660667146621;1042.280826229899;1156.1329003247565;1167.6788883057227;1176.2472017908544;1185.7213554814612;951.2580187453797;987.4865070163619;991.6876184002016;1003.7841863999275;1010.4846528270635;6381.744730210832;6496.610203387718;6580.243272023778;6630.204375474974;6675.632840193836;998.9250806189565;1014.3305418423805;1036.8133735927606;1037.906588407047;1040.5920610002152;3646.872359467406;3849.672777813917;3866.149447121685;3900.9609903901164;4026.617471340218;13772.173669579857;14042.648933776532;14172.716545669235;14181.503647372292;14208.218547979961;13693.863061369259;13700.794488082687;13707.697768711956;14070.577882663536;14090.788638170436;10273.434961077213;10305.278630495794;10503.369797812156;10586.629467968583;10638.989296016865;5614.29821272341;6378.2346118466085;6420.101185834081;6540.83647908787;6643.800685979429;12038.879611203776;12340.568080117153;12484.375781211085;12643.557475488315;12661.620151395473;12479.750404991668;12628.873711263137;12873.22760634365;12907.419166137734;12925.224486530838;7809.726659566888;8789.428687135316;8856.158664926821;8993.235574976201;9008.279337653103;10288.897111028793;10325.586976520855;10403.791924161626;10417.895821041995;10435.530401131815;8715.869261961203;8873.955630221808;8961.327900407365;8964.282857371265;8975.910240897507;7547.471676982558;8420.15798420153;8732.781680457785;8801.735947921572;8833.823323533365;7873.645685944261;8035.156308587514;8041;8049.557274349732;8058.3150432213915;5620.241267428844;5771.163181338767;5778.306603207642;5986.102084687332;6030.5778595497895;5893.44012318829;6005.699715014319;6036;6159.106929495288;6278.340774218641 18:43:35 INFO - PROCESS | 1928 | |4;prototype.html;391.3112921258585;392.9057026313684;395.7348576453776;395.8337498250734;398.4501388084443;4524.280773306147;5217.608679349049;5234.18870075138;5257.842264732055;5281.788728450857;4276.828926842923;4826.565609095181;4856.5143485651415;5116.053530096935;5182.481751824816;3915.7288772511874;4311.3101903695415;4360.389545463638;4362.520122786491;4379.868603941887;4072.226277007374;4315.41741864849;4346.434963454746;4400.227856450431;4406.625436837872;3938.684905207591;3991.42124391963;4049.4533238012787;4447.155040542287;4462.772737497182;3668.52309199805;3708.533825006381;4063.862118606795;4308.504521979966;4346.739195648272;3654.2691461707764;3878.5733569307354;4001.519817621904;4001.639852413297;4007.6393124618926;4587.3807036050375;5033.345665063518;5033.489953014081;5044.243363495469;5079.415867175285;4822.445418776815;4911.754412279408;5008.821162794196;5027.421846487628;5034.597232221414;5102.795888164469;5194.961007798455;5249.661336359242;5453.972730136364;5495.587830912697;4202.637889688237;4210;4338.544452832439;4402.977985110054;4429.785107446277;5646.080332686906;5811.401864487258;5843.211166492554;5868.647881127146;5878.588498805086;3802.7338086334244;4197.664186865043;4239.25812982733;4581.633469322446;4618.35343051973;4466.7766611669285;4671;4683.555062269147;4731.479537250969;4735.384400028043;4405.405270288534;4676.69601475903;4952.504749525091;4997.326964680957;5030.974845125751;4595.748083439157;5333.626646134733;5476.12382018883;5515.145152501369;5540.033288182637;1390.57587435831;1591.3395940684718;1596.3933705191953;1598.8001499812524;1611.8307577704406;1146.1003112556602;1191.076915390562;1192.8548593350308;1198.3528894396927;1218.945612045574;4797.376341075707;5137.921036582359;5139.511746384088;5156.716380599103;5161.071007218737;5160.793568257228;5630.4369563043365;5718.599698021098;5730.255066841284;5768.077107662753;4807.134715751198;4818.445878723922;4865.297145998872;5066.859956509756;5264.78940842362;2568.6403903453534;2689.2116742366497;2958.45268625305;2967.4065186962694;2982.9850850745606;5387.434319396485;5699.5517711784605;5927.288725352985;5942.554308426886;5956;5168.483151684876;5173.844784656466;5582.581306401955;5754.309482862083;5777.335606405317;5062.797488100435;5269.973650131801;5810.970945145247;5838.650205596858;5970.805618993817;4839.588634966068;5302.242833726364;5631.155326701028;5749.655020698771;5807.564432667651;5445.1832225166545;5511.917321240265;5703.486686198262;5745.787871669354;5758.913616295843;5299.364076310867;5409.648372855751;5970.5522085844605;6051.4553690168095;6087.752010837679;4577.626711986457;4730.5978991784705;4848.781804818721;4926.359573255454;5058.597166346103;5095.872603184802;5810.186573879576;5846.041576072063;5853.795117170878;5858.209141765978;4718.787654555622;5631.577631677558;5654.208410822406;5665.490105890325;5682.772689092555;5344.385395679391;5601.719914004202;5686.43135686428;5693.402192769865;5747.425257474386;4717.268823332389;5249.527542521191;5523.613347065667;5555.2778138841695;5559.610827242054;4937.9012419752125;5031.446540880427;5610.971945140248;5625.774969001194;5690.459406356389;4665.626749860071;5118.769655365592;5299.629025968299;5507.834764957058;5566.276384070207 18:43:35 INFO - PROCESS | 1928 | |5;yui.html;762.870312046952;776.7669699090268;779.885293520915;813.0771574263209;894.7628878347238;12460.314682692448;13938.51215207468;14748.557543273719;14878.628034299149;14882.553523394288;15859.207039648029;15859.762103568455;15879.809014323913;16075.356985720586;16104.597385065379;974.3763991045719;995.0348162282611;998.3810037776552;1026.420072658949;1042.1454407385947;1019.0624625344701;1033.844923261513;1035.2390992620417;1037.1702637889696;1039.9220058495644;1073.4579037586009;1073.468633026653;1074.7957888001295;1074.8817630060687;1075.42464781342;1068.9417476698052;1069.3476979042778;1070.347088276151;1071.2287153249647;1072.1637123044038;794.0352471746855;796.3310818912141;796.625585974591;797.2904115337354;797.8523973064944;16535;16955.237014334383;17463.21415536291;17733.822661773476;17734.20196091179;14577.48978785748;14840.480583179537;14945.25273736309;15011.774823377658;15139.697206055927;11949.581764638282;12186.451609677586;12387.504499819996;12550.372481375985;12895;3834.2906562286066;3900.2004589059175;3901.707371947087;4048.332025215836;4058.959410405888;104934.47532762287;105953;106590;106689.46655266751;107344.92655073428;11098.61154859576;11140.832887506695;11475.88524114765;11675.83996960565;12113.818292725617;24281.785910704395;24436.511269774506;24464.75535244625;24505.387365316008;24528.754712452646;8089.676412943534;8160.5103693778565;8165;8245.670173193004;8387.077421483631;4281.850135245251;4531.592156705912;4587.610053145453;4670.182718024332;4707.246840505502;3681;3702.8889133326043;3761.191343861083;3765.4351847223134;3765.9058523537133;571.0919637775957;659.5185514574291;676.7597502886503;676.8576312964688;677.1603212017063;595.4254144750337;715.1561157833702;719.4244604316526;734.1263895963785;744.7021191523326;9768.36505627132;10426.843597346045;10715.678529643967;10963.397013164193;11064.944675276733;682.4233522673333;689.6172624193621;734.1777209525366;756.6821934787403;758.74202771057;4266.786660666954;4937.851864444073;5209.53114219722;5231.241469986907;5239.318888544465;88840.6673899892;101900.49049754853;108577.91422085837;110381;110393;72598.63700681462;75317.24682753212;94455;97397.51301243449;101078.91368690634;76060.47879042388;77667;81331.18668813354;82438;83002;10785.514651840844;11099.944500277446;11453;11461.598844040418;11492.597759078391;41762.58237417587;47241.34655287186;48327;49784.257550985545;50197.74901125471;63801.08596742105;64670;65601;66030.66984665046;66893.66553167203;12532.49870005226;12590.748185036065;12942.288174150135;13035.934820325838;13057.21656700601;14688.971771976287;14823.332950017491;15133.167675777497;15328.770068449205;16078.67842643164;13285.66785830362;13386.330683465983;14230.288485575888;14415.639609009439;14876.404943802127;13222.484966562171;13506.459741610226;13923.303834808017;14100.858991409954;14410.279486025867;14001.369938353002;14031.44585373609;14117.152970821784;14177.078489898124;14211.863050956106;10119;10338;10344.637937672145;10639.095676867553;10762.515686793957;11270.436478176221;11346.602868899548;11455.255408398427;11488.655340339803;11535.192536522363 18:43:35 INFO - PROCESS | 1928 | __end_tp_report 18:43:35 INFO - PROCESS | 1928 | __start_cc_report 18:43:35 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,1303 18:43:35 INFO - PROCESS | 1928 | __end_cc_report 18:43:35 INFO - PROCESS | 1928 | __startTimestamp1495503815758__endTimestamp 18:43:35 INFO - PROCESS | 1928 | 18:43:35 INFO - PROCESS | 1928 | ------- Summary: start ------- 18:43:35 INFO - PROCESS | 1928 | Number of tests: 6 18:43:35 INFO - PROCESS | 1928 | 18:43:35 INFO - PROCESS | 1928 | [#0] dojo.html Cycles:165 Average:6114.25 Median:6291.94 stddev:688.30 (10.9%) stddev-sans-first:651.08 18:43:35 INFO - PROCESS | 1928 | Values: 3190.4 3539.9 3558.3 3605.4 3605.4 5168.8 6174.8 6175.6 6185.5 6211.8 6072.2 6159.8 6203.4 6216.8 6221.4 4833.9 5617.3 5640.2 5649.0 5706.1 5516.0 5568.0 5577.9 5631.4 5768.9 5647.9 5660.6 5662.1 5668.9 5735.1 5725.6 5734.3 5745.9 5788.8 5815.8 5552.3 5685.3 5688.0 5694.9 5696.9 6215.8 6223.9 6234.1 6241.6 6251.3 5236.5 6290.3 6299.3 6325.6 6533.1 6618.0 6713.1 6717.9 6718.5 6731.3 6243.9 6254.4 6258.7 6271.4 6275.5 6535.0 6599.7 6879.7 6896.8 6897.8 6342.7 6347.9 6352.8 6384.5 6388.4 6626.8 6661.5 6669.1 6669.9 6671.4 6278.5 6486.4 6531.5 6563.7 6576.5 6381.1 6485.1 6523.7 6587.1 6605.8 5172.4 5184.4 5197.7 5199.0 5304.2 4739.3 4787.4 4787.5 4790.6 4803.6 6181.4 6279.0 6281.6 6290.8 6291.9 6335.5 6393.3 6663.7 6682.4 6684.8 6154.6 6155.6 6203.2 6209.8 6243.3 5379.1 5509.6 5844.2 5849.3 5875.9 6853.3 6883.9 6932.3 6952.8 6957.6 6349.9 6398.8 6544.4 6608.6 6679.7 6914.2 6954.3 6954.7 6967.4 6968.1 6532.6 6559.1 6570.4 6597.8 6608.6 6352.9 6391.3 6544.9 6688.9 6703.8 6351.6 6427.7 6429.8 6446.0 6448.7 5211.5 6409.6 6427.6 6456.6 6458.3 6225.7 6267.0 6275.0 6275.8 6317.9 6338.7 6350.9 6428.2 6450.3 6451.7 6306.7 6309.1 6309.9 6311.3 6322.2 18:43:35 INFO - PROCESS | 1928 | 18:43:35 INFO - PROCESS | 1928 | [#1] ext.html Cycles:180 Average:58175.77 Median:3282.38 stddev:117113.72 (3568.0%) stddev-sans-first:117362.86 18:43:35 INFO - PROCESS | 1928 | Values: 741.9 787.0 828.0 891.7 903.7 29648.0 40657.4 41308.4 41338.6 41452.0 41757.6 41902.0 41939.5 41939.8 41979.8 8191.3 9707.7 9867.2 9869.3 9870.4 8203.9 11761.5 11953.8 12144.7 12193.1 11974.9 11998.6 12009.8 12011.3 12021.4 11928.4 11956.5 11986.3 12005.9 12013.2 11557.8 11619.0 11640.2 11641.7 11652.8 40105.6 40343.6 40363.4 40373.4 40654.6 3990.0 4031.1 4052.8 4052.8 4061.7 27875.9 28488.1 30062.8 30612.1 30652.5 3281.1 3283.6 3312.6 3410.7 3424.7 356985.0 361407.0 362043.0 363621.2 364128.0 54576.5 54772.9 54804.5 54929.7 54933.0 56632.0 56658.0 56660.9 56699.0 56771.0 15825.1 15886.2 15897.8 15897.9 15910.3 11869.5 13639.9 13642.3 13647.6 13659.9 11232.4 11254.0 12338.1 12570.0 12585.7 4826.2 4908.3 5565.9 5614.3 5633.7 1085.5 1090.4 1093.4 1096.6 1154.2 23571.6 26000.0 27795.3 28361.4 28417.4 1149.6 1151.8 1156.3 1203.4 1206.8 14369.3 14415.7 14693.8 14700.6 14718.8 472261.0 472802.0 472847.0 473384.0 473760.0 48641.8 49376.3 51639.7 58025.4 59023.1 1490.2 1520.6 1536.4 1543.7 1543.8 29190.9 29264.0 30635.0 30875.4 31095.8 43950.6 44831.3 45616.8 47813.8 47952.0 471710.0 473949.0 478434.0 483729.0 491421.0 34529.5 35101.1 35209.5 35347.3 35351.8 42921.7 43696.0 44696.8 44771.0 44880.6 29971.7 30201.5 30576.2 31198.2 31618.7 30044.7 30143.7 30385.7 30732.0 30888.8 34972.7 35665.5 35925.3 36522.8 36648.8 30371.4 30461.7 30702.4 31411.8 31443.7 23536.6 24431.8 24687.8 24935.5 25044.0 18:43:35 INFO - PROCESS | 1928 | 18:43:35 INFO - PROCESS | 1928 | [#2] jquery.html Cycles:180 Average:45575.37 Median:21850.06 stddev:131701.77 (602.8%) stddev-sans-first:132027.63 18:43:35 INFO - PROCESS | 1928 | Values: 439.4 439.7 444.6 446.6 456.5 21217.8 21945.2 22335.0 22740.0 22928.0 18015.5 22283.1 22754.4 22857.5 22907.4 6819.0 7121.1 7122.8 7669.0 7979.9 7740.4 8076.8 8126.5 8131.3 8140.9 8190.4 8242.9 8257.3 8270.7 8276.9 7941.0 8069.6 8170.2 8175.7 8198.5 7637.0 7848.3 7908.7 7946.4 7950.5 26877.7 27040.9 27417.3 27583.7 27778.6 2968.6 2997.4 3045.2 3090.4 3122.8 15524.6 15769.5 16033.9 16081.5 16774.5 2109.8 2136.1 2139.0 2166.8 2227.7 162082.0 165996.0 167729.0 172226.0 173738.0 20684.1 20783.0 20940.0 21121.8 21157.0 34011.0 34199.7 34373.5 34419.0 34453.8 10704.2 10748.2 10833.9 10873.6 11058.6 9027.0 9028.5 9051.0 9219.8 9229.4 9381.8 9796.9 9804.8 9820.5 10062.2 4050.0 4139.2 4161.2 4177.9 4179.7 858.0 972.4 1036.8 1084.0 1096.5 20069.6 20487.9 21818.0 21834.5 21992.9 1024.7 1025.6 1040.8 1059.5 1068.6 10973.9 11305.9 11339.7 11366.8 11373.8 771189.0 784431.0 787423.0 796385.0 800087.0 143629.0 168119.0 171013.7 174434.0 175460.1 1102.8 1310.9 1318.9 1350.2 1352.3 19858.8 20016.7 21788.0 21865.7 21907.8 68534.3 68717.0 69131.7 69600.0 69671.0 85428.6 85871.7 87360.6 88100.0 88583.6 10397.8 11576.8 11817.5 11823.1 12105.5 13501.9 13630.3 13709.3 13931.9 13968.4 11540.7 11639.7 11854.3 11878.6 12005.6 10766.1 10781.9 11170.9 11268.8 11320.5 11685.3 11702.9 11909.8 12068.3 12124.0 9359.7 11293.9 11538.4 11875.3 11964.0 11540.9 11675.9 11771.8 12035.9 12089.3 18:43:35 INFO - PROCESS | 1928 | 18:43:35 INFO - PROCESS | 1928 | [#3] mootools.html Cycles:180 Average:6860.41 Median:2491.36 stddev:4323.65 (173.5%) stddev-sans-first:4316.48 18:43:35 INFO - PROCESS | 1928 | Values: 1424.9 1615.3 1642.2 1652.7 1659.2 9406.1 9784.3 11145.0 11414.9 11612.3 11292.9 11334.3 11339.0 11352.3 11396.1 2188.7 2452.1 2491.0 2493.2 2495.2 2137.0 2211.6 2446.1 2515.7 2527.7 2472.8 2484.7 2503.8 2503.8 2504.0 2483.5 2489.0 2489.5 2490.4 2491.7 2397.5 2427.2 2435.2 2436.9 2484.0 11507.7 11602.2 11602.5 11624.3 11646.3 5800.3 5971.5 5973.9 5976.0 5983.0 3557.9 3643.5 3812.2 3861.9 3877.1 1250.7 1267.5 1291.5 1294.2 1311.0 15833.3 15905.1 15905.7 15922.5 15925.1 4790.0 4966.9 5012.0 5071.4 5152.9 12430.6 12463.3 12480.3 12489.3 12489.4 6019.3 6035.8 6038.8 6039.7 6055.0 4745.1 5218.2 5364.9 5472.2 5541.4 3813.9 3905.5 4195.7 4211.2 4241.7 1042.3 1156.1 1167.7 1176.2 1185.7 951.3 987.5 991.7 1003.8 1010.5 6381.7 6496.6 6580.2 6630.2 6675.6 998.9 1014.3 1036.8 1037.9 1040.6 3646.9 3849.7 3866.1 3901.0 4026.6 13772.2 14042.6 14172.7 14181.5 14208.2 13693.9 13700.8 13707.7 14070.6 14090.8 10273.4 10305.3 10503.4 10586.6 10639.0 5614.3 6378.2 6420.1 6540.8 6643.8 12038.9 12340.6 12484.4 12643.6 12661.6 12479.8 12628.9 12873.2 12907.4 12925.2 7809.7 8789.4 8856.2 8993.2 9008.3 10288.9 10325.6 10403.8 10417.9 10435.5 8715.9 8874.0 8961.3 8964.3 8975.9 7547.5 8420.2 8732.8 8801.7 8833.8 7873.6 8035.2 8041.0 8049.6 8058.3 5620.2 5771.2 5778.3 5986.1 6030.6 5893.4 6005.7 6036.0 6159.1 6278.3 18:43:35 INFO - PROCESS | 1928 | 18:43:35 INFO - PROCESS | 1928 | [#4] prototype.html Cycles:180 Average:4664.26 Median:5034.04 stddev:1296.99 (25.8%) stddev-sans-first:1260.35 18:43:35 INFO - PROCESS | 1928 | Values: 391.3 392.9 395.7 395.8 398.5 4524.3 5217.6 5234.2 5257.8 5281.8 4276.8 4826.6 4856.5 5116.1 5182.5 3915.7 4311.3 4360.4 4362.5 4379.9 4072.2 4315.4 4346.4 4400.2 4406.6 3938.7 3991.4 4049.5 4447.2 4462.8 3668.5 3708.5 4063.9 4308.5 4346.7 3654.3 3878.6 4001.5 4001.6 4007.6 4587.4 5033.3 5033.5 5044.2 5079.4 4822.4 4911.8 5008.8 5027.4 5034.6 5102.8 5195.0 5249.7 5454.0 5495.6 4202.6 4210.0 4338.5 4403.0 4429.8 5646.1 5811.4 5843.2 5868.6 5878.6 3802.7 4197.7 4239.3 4581.6 4618.4 4466.8 4671.0 4683.6 4731.5 4735.4 4405.4 4676.7 4952.5 4997.3 5031.0 4595.7 5333.6 5476.1 5515.1 5540.0 1390.6 1591.3 1596.4 1598.8 1611.8 1146.1 1191.1 1192.9 1198.4 1218.9 4797.4 5137.9 5139.5 5156.7 5161.1 5160.8 5630.4 5718.6 5730.3 5768.1 4807.1 4818.4 4865.3 5066.9 5264.8 2568.6 2689.2 2958.5 2967.4 2983.0 5387.4 5699.6 5927.3 5942.6 5956.0 5168.5 5173.8 5582.6 5754.3 5777.3 5062.8 5270.0 5811.0 5838.7 5970.8 4839.6 5302.2 5631.2 5749.7 5807.6 5445.2 5511.9 5703.5 5745.8 5758.9 5299.4 5409.6 5970.6 6051.5 6087.8 4577.6 4730.6 4848.8 4926.4 5058.6 5095.9 5810.2 5846.0 5853.8 5858.2 4718.8 5631.6 5654.2 5665.5 5682.8 5344.4 5601.7 5686.4 5693.4 5747.4 4717.3 5249.5 5523.6 5555.3 5559.6 4937.9 5031.4 5611.0 5625.8 5690.5 4665.6 5118.8 5299.6 5507.8 5566.3 18:43:35 INFO - PROCESS | 1928 | 18:43:35 INFO - PROCESS | 1928 | [#5] yui.html Cycles:180 Average:20809.29 Median:15859.48 stddev:29403.00 (185.4%) stddev-sans-first:29446.96 18:43:35 INFO - PROCESS | 1928 | Values: 762.9 776.8 779.9 813.1 894.8 12460.3 13938.5 14748.6 14878.6 14882.6 15859.2 15859.8 15879.8 16075.4 16104.6 974.4 995.0 998.4 1026.4 1042.1 1019.1 1033.8 1035.2 1037.2 1039.9 1073.5 1073.5 1074.8 1074.9 1075.4 1068.9 1069.3 1070.3 1071.2 1072.2 794.0 796.3 796.6 797.3 797.9 16535.0 16955.2 17463.2 17733.8 17734.2 14577.5 14840.5 14945.3 15011.8 15139.7 11949.6 12186.5 12387.5 12550.4 12895.0 3834.3 3900.2 3901.7 4048.3 4059.0 104934.5 105953.0 106590.0 106689.5 107344.9 11098.6 11140.8 11475.9 11675.8 12113.8 24281.8 24436.5 24464.8 24505.4 24528.8 8089.7 8160.5 8165.0 8245.7 8387.1 4281.9 4531.6 4587.6 4670.2 4707.2 3681.0 3702.9 3761.2 3765.4 3765.9 571.1 659.5 676.8 676.9 677.2 595.4 715.2 719.4 734.1 744.7 9768.4 10426.8 10715.7 10963.4 11064.9 682.4 689.6 734.2 756.7 758.7 4266.8 4937.9 5209.5 5231.2 5239.3 88840.7 101900.5 108577.9 110381.0 110393.0 72598.6 75317.2 94455.0 97397.5 101078.9 76060.5 77667.0 81331.2 82438.0 83002.0 10785.5 11099.9 11453.0 11461.6 11492.6 41762.6 47241.3 48327.0 49784.3 50197.7 63801.1 64670.0 65601.0 66030.7 66893.7 12532.5 12590.7 12942.3 13035.9 13057.2 14689.0 14823.3 15133.2 15328.8 16078.7 13285.7 13386.3 14230.3 14415.6 14876.4 13222.5 13506.5 13923.3 14100.9 14410.3 14001.4 14031.4 14117.2 14177.1 14211.9 10119.0 10338.0 10344.6 10639.1 10762.5 11270.4 11346.6 11455.3 11488.7 11535.2 18:43:35 INFO - PROCESS | 1928 | -------- Summary: end -------- 18:43:35 INFO - PROCESS | 1928 | 18:43:36 INFO - TEST-INFO | 1928: exit 0 18:43:36 INFO - TEST-OK | dromaeo_css | took 1119283ms 18:43:36 INFO - TEST-START | kraken 18:43:36 INFO - operating with platform_type : mac_ 18:43:36 INFO - Initialising browser for kraken test... 18:43:36 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmpaJWDmn/profile http://localhost:55813/getInfo.html) 18:43:38 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 18:43:38 INFO - PROCESS | 1932 | colorDepth:24 18:43:38 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 18:43:38 INFO - PROCESS | 1932 | __metrics 18:43:38 INFO - PROCESS | 1932 | 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" 18:43:38 INFO - PROCESS | 1932 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:43:39 INFO - TEST-INFO | 1932: exit 0 18:43:39 INFO - Browser initialized. 18:43:39 INFO - Running cycle 1/1 for kraken test... 18:43:39 INFO - TEST-INFO | started process 1934 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmpaJWDmn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:43:51 INFO - PROCESS | 1934 | RSS: Main: 165249024 18:43:51 INFO - PROCESS | 1934 | 18:43:54 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55813/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55813/tests/kraken/driver.html?testName=audio-beat-detection) 18:43:55 INFO - PROCESS | 1934 | RSS: Main: 215826432 18:43:55 INFO - PROCESS | 1934 | 18:43:58 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55813/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55813/tests/kraken/driver.html?testName=audio-dft) 18:43:58 INFO - PROCESS | 1934 | RSS: Main: 231469056 18:43:58 INFO - PROCESS | 1934 | 18:44:01 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55813/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55813/tests/kraken/driver.html?testName=audio-fft) 18:44:02 INFO - PROCESS | 1934 | RSS: Main: 328704000 18:44:02 INFO - PROCESS | 1934 | 18:44:04 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55813/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55813/tests/kraken/driver.html?testName=audio-oscillator) 18:44:05 INFO - PROCESS | 1934 | RSS: Main: 229330944 18:44:05 INFO - PROCESS | 1934 | 18:44:07 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55813/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55813/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:44:07 INFO - PROCESS | 1934 | RSS: Main: 230805504 18:44:07 INFO - PROCESS | 1934 | 18:44:10 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55813/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55813/tests/kraken/driver.html?testName=imaging-darkroom) 18:44:11 INFO - PROCESS | 1934 | RSS: Main: 271048704 18:44:11 INFO - PROCESS | 1934 | 18:44:14 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55813/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55813/tests/kraken/driver.html?testName=imaging-desaturate) 18:44:14 INFO - PROCESS | 1934 | RSS: Main: 272015360 18:44:14 INFO - PROCESS | 1934 | 18:44:17 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55813/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55813/tests/kraken/driver.html?testName=json-parse-financial) 18:44:18 INFO - PROCESS | 1934 | RSS: Main: 268902400 18:44:18 INFO - PROCESS | 1934 | 18:44:20 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55813/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55813/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:44:20 INFO - PROCESS | 1934 | RSS: Main: 247926784 18:44:20 INFO - PROCESS | 1934 | 18:44:22 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55813/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55813/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:44:23 INFO - PROCESS | 1934 | RSS: Main: 252002304 18:44:23 INFO - PROCESS | 1934 | 18:44:25 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55813/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55813/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:44:26 INFO - PROCESS | 1934 | RSS: Main: 256897024 18:44:26 INFO - PROCESS | 1934 | 18:44:28 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55813/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55813/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:44:29 INFO - PROCESS | 1934 | RSS: Main: 273633280 18:44:29 INFO - PROCESS | 1934 | 18:44:32 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55813/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55813/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:44:33 INFO - PROCESS | 1934 | RSS: Main: 267235328 18:44:33 INFO - PROCESS | 1934 | 18:44:34 INFO - PROCESS | 1934 | Cycle 1(1): loaded http://localhost:55813/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55813/tests/kraken/driver.html?testName=ai-astar) 18:44:35 INFO - PROCESS | 1934 | RSS: Main: 311820288 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | __start_tp_report 18:44:35 INFO - PROCESS | 1934 | _x_x_mozilla_page_load 18:44:35 INFO - PROCESS | 1934 | _x_x_mozilla_page_load_details 18:44:35 INFO - PROCESS | 1934 | |i|pagename|runs| 18:44:35 INFO - PROCESS | 1934 | |0;ai-astar;107;115;115;114;115;115;115;114;115;115 18:44:35 INFO - PROCESS | 1934 | |1;audio-beat-detection;160;126;130;145;110;113;130;128;136;108 18:44:35 INFO - PROCESS | 1934 | |2;audio-dft;185;187;185;182;183;185;186;183;184;185 18:44:35 INFO - PROCESS | 1934 | |3;audio-fft;83;60;76;108;82;61;58;58;72;59 18:44:35 INFO - PROCESS | 1934 | |4;audio-oscillator;90;69;88;69;69;69;82;70;70;83 18:44:35 INFO - PROCESS | 1934 | |5;imaging-gaussian-blur;87;87;87;87;87;87;86;91;129;105 18:44:35 INFO - PROCESS | 1934 | |6;imaging-darkroom;94;94;94;94;94;94;96;94;94;94 18:44:35 INFO - PROCESS | 1934 | |7;imaging-desaturate;87;86;87;84;87;84;101;117;127;123 18:44:35 INFO - PROCESS | 1934 | |8;json-parse-financial;62;60;60;61;63;63;64;64;63;67 18:44:35 INFO - PROCESS | 1934 | |9;json-stringify-tinderbox;56;66;71;63;62;59;60;61;59;60 18:44:35 INFO - PROCESS | 1934 | |10;stanford-crypto-aes;92;92;83;83;81;84;78;80;82;81 18:44:35 INFO - PROCESS | 1934 | |11;stanford-crypto-ccm;135;133;135;129;133;134;139;135;181;165 18:44:35 INFO - PROCESS | 1934 | |12;stanford-crypto-pbkdf2;164;162;163;165;160;198;161;159;164;162 18:44:35 INFO - PROCESS | 1934 | |13;stanford-crypto-sha256-iterative;64;65;65;62;63;64;64;63;63;62 18:44:35 INFO - PROCESS | 1934 | __end_tp_report 18:44:35 INFO - PROCESS | 1934 | __start_cc_report 18:44:35 INFO - PROCESS | 1934 | _x_x_mozilla_cycle_collect,2076 18:44:35 INFO - PROCESS | 1934 | __end_cc_report 18:44:35 INFO - PROCESS | 1934 | __startTimestamp1495503875148__endTimestamp 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | ------- Summary: start ------- 18:44:35 INFO - PROCESS | 1934 | Number of tests: 14 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | [#0] ai-astar Cycles:10 Average:114.00 Median:115.00 stddev:2.49 (2.2%) stddev-sans-first:0.44 18:44:35 INFO - PROCESS | 1934 | Values: 107.0 115.0 115.0 114.0 115.0 115.0 115.0 114.0 115.0 115.0 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | [#1] audio-beat-detection Cycles:10 Average:128.60 Median:130.00 stddev:16.11 (12.4%) stddev-sans-first:12.44 18:44:35 INFO - PROCESS | 1934 | Values: 160.0 126.0 130.0 145.0 110.0 113.0 130.0 128.0 136.0 108.0 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.00 stddev:1.51 (0.8%) stddev-sans-first:1.59 18:44:35 INFO - PROCESS | 1934 | Values: 185.0 187.0 185.0 182.0 183.0 185.0 186.0 183.0 184.0 185.0 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | [#3] audio-fft Cycles:10 Average:71.70 Median:66.50 stddev:16.19 (24.3%) stddev-sans-first:16.64 18:44:35 INFO - PROCESS | 1934 | Values: 83.0 60.0 76.0 108.0 82.0 61.0 58.0 58.0 72.0 59.0 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | [#4] audio-oscillator Cycles:10 Average:75.90 Median:76.00 stddev:8.77 (11.5%) stddev-sans-first:7.68 18:44:35 INFO - PROCESS | 1934 | Values: 90.0 69.0 88.0 69.0 69.0 69.0 82.0 70.0 70.0 83.0 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | [#5] imaging-gaussian-blur Cycles:10 Average:93.30 Median:87.00 stddev:13.78 (15.8%) stddev-sans-first:14.42 18:44:35 INFO - PROCESS | 1934 | Values: 87.0 87.0 87.0 87.0 87.0 87.0 86.0 91.0 129.0 105.0 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 18:44:35 INFO - PROCESS | 1934 | Values: 94.0 94.0 94.0 94.0 94.0 94.0 96.0 94.0 94.0 94.0 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | [#7] imaging-desaturate Cycles:10 Average:98.30 Median:85.00 stddev:17.43 (20.5%) stddev-sans-first:18.00 18:44:35 INFO - PROCESS | 1934 | Values: 87.0 86.0 87.0 84.0 87.0 84.0 101.0 117.0 127.0 123.0 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:63.00 stddev:2.11 (3.4%) stddev-sans-first:2.22 18:44:35 INFO - PROCESS | 1934 | Values: 62.0 60.0 60.0 61.0 63.0 63.0 64.0 64.0 63.0 67.0 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:61.50 stddev:4.22 (6.9%) stddev-sans-first:3.94 18:44:35 INFO - PROCESS | 1934 | Values: 56.0 66.0 71.0 63.0 62.0 59.0 60.0 61.0 59.0 60.0 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:83.00 stddev:4.74 (5.7%) stddev-sans-first:3.94 18:44:35 INFO - PROCESS | 1934 | Values: 92.0 92.0 83.0 83.0 81.0 84.0 78.0 80.0 82.0 81.0 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | [#11] stanford-crypto-ccm Cycles:10 Average:141.90 Median:135.00 stddev:17.00 (12.6%) stddev-sans-first:17.85 18:44:35 INFO - PROCESS | 1934 | Values: 135.0 133.0 135.0 129.0 133.0 134.0 139.0 135.0 181.0 165.0 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.80 Median:163.50 stddev:11.47 (7.0%) stddev-sans-first:12.14 18:44:35 INFO - PROCESS | 1934 | Values: 164.0 162.0 163.0 165.0 160.0 198.0 161.0 159.0 164.0 162.0 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - PROCESS | 1934 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.50 Median:64.00 stddev:1.08 (1.7%) stddev-sans-first:1.13 18:44:35 INFO - PROCESS | 1934 | Values: 64.0 65.0 65.0 62.0 63.0 64.0 64.0 63.0 63.0 62.0 18:44:35 INFO - PROCESS | 1934 | -------- Summary: end -------- 18:44:35 INFO - PROCESS | 1934 | 18:44:35 INFO - TEST-INFO | 1934: exit 0 18:44:36 INFO - TEST-OK | kraken | took 59549ms 18:44:36 INFO - SUITE-END | took 1178s 18:44:36 INFO - Completed test suite (00:19:39) 18:44:36 INFO - javascript benchmark 18:44:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7994.376367241565, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3190.4257233697926, 3539.94690079649, 3558.2705545363197, 3605.3870841956864, 3605.4051081571542, 5168.844934651962, 6174.7530098796105, 6175.629462232264, 6185.505159587234, 6211.844703882394, 6072.210612620364, 6159.8152055438295, 6203.410675985785, 6216.751329946808, 6221.377862213776, 4833.903321933559, 5617.269754931853, 5640.182173584828, 5648.983183027053, 5706.115552089433, 5516.0, 5568.0, 5577.860553486175, 5631.380548139701, 5768.8846223075725, 5647.858803529924, 5660.60375773696, 5662.122371032497, 5668.914966275509, 5735.08238681811, 5725.570582206351, 5734.311882574076, 5745.856353591152, 5788.797392091277, 5815.82552523425, 5552.250446189753, 5685.289338832646, 5687.971560142214, 5694.857628559278, 5696.857578560528, 6215.81352559424, 6223.875522489525, 6234.127222188898, 6241.594296370721, 6251.343608921089, 5236.502532259429, 6290.308066112724, 6299.307076221612, 6325.620462772203, 6533.052707357454, 6617.966910165466, 6713.127293451869, 6717.932820671828, 6718.462522998148, 6731.293214212486, 6243.906341404882, 6254.437100660963, 6258.718357673824, 6271.404216599416, 6275.466585340251, 6535.019747037983, 6599.70301336441, 6879.6560171991205, 6896.827579310558, 6897.827554311182, 6342.682865856688, 6347.873042539123, 6352.8411789704705, 6384.52116091295, 6388.393102655334, 6626.768063117768, 6661.467082633475, 6669.1330127083165, 6669.933300667029, 6671.366220209073, 6278.46633036197, 6486.416222539994, 6531.477481801445, 6563.671816409256, 6576.539642225094, 6381.1385462962835, 6485.092087107809, 6523.7064332105165, 6587.077809106729, 6605.768798092044, 5172.431032586413, 5184.37787465499, 5197.66215196011, 5199.012187684328, 5304.177852432879, 4739.312799644033, 4787.42550893895, 4787.54518320759, 4790.568848803624, 4803.615710743132, 6181.381861813783, 6278.99536074235, 6281.623102613858, 6290.811275661737, 6291.937080629226, 6335.524835637346, 6393.296737358887, 6663.700133494004, 6682.365175308338, 6684.799456016327, 6154.599951003171, 6155.569110162335, 6203.193584833943, 6209.813705588839, 6243.313235544087, 5379.085555455504, 5509.5675124467925, 5844.211031510692, 5849.327327357409, 5875.853103672443, 6853.314668533107, 6883.931160688529, 6932.272111428228, 6952.756653517305, 6957.582545047313, 6349.936500634934, 6398.776042838478, 6544.443722283469, 6608.570442921195, 6679.732810687518, 6914.170299564084, 6954.269801670752, 6954.652267386711, 6967.372936435745, 6968.128983877015, 6532.640704761284, 6559.114519539801, 6570.408663220333, 6597.80206593803, 6608.636524991171, 6352.9047064293145, 6391.265004524354, 6544.901826472512, 6688.866222675422, 6703.798886033427, 6351.587146835441, 6427.678616069272, 6429.839254018687, 6446.033095035782, 6448.709808058742, 5211.478852114758, 6409.647469389229, 6427.614343139427, 6456.644884531396, 6458.289588145206, 6225.719842607185, 6267.0, 6274.968625156845, 6275.780347687809, 6317.873642527032, 6338.746450142127, 6350.904736428864, 6428.164338636135, 6450.258220304538, 6451.741930322734, 6306.684665766785, 6309.1167236587835, 6309.895768240632, 6311.305756366704, 6322.241331040304], "value": 6070.390323793466, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.8553382090493, 786.9724559640414, 828.0146625515634, 891.6522556203082, 903.661127077346, 29647.999999999975, 40657.39013914794, 41308.38037429441, 41338.58661413385, 41451.963700907494, 41757.58242417575, 41902.0, 41939.532116375936, 41939.79030104853, 41979.790101049526, 8191.344692424622, 9707.660231891885, 9867.16129129025, 9869.259805514579, 9870.407775533455, 8203.876941845878, 11761.470733817, 11953.820692689573, 12144.696382590462, 12193.146479746421, 11974.880251197465, 11998.64004079879, 12009.819852702216, 12011.27932324059, 12021.398930053507, 11928.403579820973, 11956.461959211856, 11986.340751258676, 12005.898967680665, 12013.159078864483, 11557.768844623144, 11619.0, 11640.243384180001, 11641.70895727605, 11652.766944661143, 40105.598944010475, 40343.59656403428, 40363.394549081786, 40373.394399084034, 40654.593454065376, 3990.0, 4031.1333063391216, 4052.756834589934, 4052.7770972596495, 4061.695372847019, 27875.88496460139, 28488.14827036236, 30062.79748810045, 30612.08163755091, 30652.540211896838, 3281.146901805524, 3283.605967283941, 3312.569365982407, 3410.6759857813468, 3424.708899743525, 356985.0, 361407.0, 362043.0, 363621.1818940915, 364128.0, 54576.45423545714, 54772.90454190894, 54804.451955480734, 54929.72535137299, 54933.0, 56632.0, 56658.0, 56660.86678266412, 56699.0, 56771.0, 15825.050496970218, 15886.205689715467, 15897.761533577006, 15897.920510397375, 15910.284037218122, 11869.525218991317, 13639.863601363859, 13642.317884105954, 13647.590572282847, 13659.907207423383, 11232.438378081064, 11254.0, 12338.074644401706, 12570.0, 12585.685357865943, 4826.179549476527, 4908.251154662339, 5565.916511252334, 5614.270144881221, 5633.718314084279, 1085.5440714899762, 1090.4166271045112, 1093.4423444043596, 1096.6107032003683, 1154.1920655540987, 23571.646425303636, 25999.960001600102, 27795.305117371823, 28361.432771344458, 28417.431651366856, 1149.5804031528432, 1151.78691941991, 1156.2888823373564, 1203.3562044306254, 1206.849143857018, 14369.281535923372, 14415.711685766435, 14693.77959330611, 14700.55898323052, 14718.779218311734, 472261.0, 472802.0, 472847.0, 473384.0, 473760.0, 48641.75679121582, 49376.25935610969, 51639.74180129075, 58025.41974580284, 59023.11465328024, 1490.1580606957034, 1520.6046427928597, 1536.3931247157534, 1543.6989786991428, 1543.7838702581648, 29190.854045729637, 29264.0, 30634.995647307547, 30875.382492349578, 31095.844520778155, 43950.560494394646, 44831.32753008642, 45616.771916140206, 47813.760931195124, 47952.0, 471710.0, 473949.0, 478434.0, 483729.0, 491421.0, 34529.48205776865, 35101.122471938404, 35209.47185792266, 35347.29305413826, 35351.76268830579, 42921.71234862959, 43696.0, 44696.77651611851, 44771.0, 44880.551194487634, 29971.700282997765, 30201.546976794925, 30576.235594110327, 31198.22004449907, 31618.683813161573, 30044.69955300419, 30143.698563014088, 30385.69614303917, 30732.0, 30888.845555772077, 34972.65027349796, 35665.46501802527, 35925.28149436945, 36522.8173859129, 36648.81675591605, 30371.39257214799, 30461.695383046772, 30702.385952281275, 31411.84294078515, 31443.68556314499, 23536.6469502961, 24431.75568244295, 24687.753122468544, 24935.50128997446, 25043.998240070923], "value": 19551.03454184045, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [439.3563429575671, 439.7163829330081, 444.6398417282004, 446.57352228621664, 456.5320546439901, 21217.787822121772, 21945.231916882913, 22334.999999999978, 22739.97670104845, 22928.0, 18015.549611259692, 22283.108675652955, 22754.431139221528, 22857.542849143007, 22907.427314317152, 6818.9659051704675, 7121.145462544476, 7122.821929451753, 7668.961655191716, 7979.880301795478, 7740.419468539854, 8076.838463230732, 8126.471779334324, 8131.349492040652, 8140.918590814075, 8190.4266701330935, 8242.917570824306, 8257.25684688378, 8270.669173233064, 8276.875846862271, 7941.0, 8069.556174410405, 8170.223828736261, 8175.7138500152205, 8198.467099638505, 7637.0, 7848.332891704212, 7908.723194688216, 7946.364290856717, 7950.4832185907735, 26877.73122268772, 27040.864795676094, 27417.31456713598, 27583.724162758317, 27778.583321250197, 2968.584398184256, 2997.436599387766, 3045.162580290416, 3090.4128215638957, 3122.7970181938103, 15524.61188470286, 15769.526914192593, 16033.919830400773, 16081.517554473385, 16774.49676509707, 2109.799569040939, 2136.081484961466, 2138.957220855574, 2166.8374871884516, 2227.7438094619165, 162082.0, 165996.0, 167729.0, 172226.0, 173738.0, 20684.069216885277, 20783.0, 20939.953002349823, 21121.78878211229, 21156.990085941812, 34011.0, 34199.658003419645, 34373.48439773406, 34419.0, 34453.82773086119, 10704.19718521114, 10748.193885458622, 10833.945830270955, 10873.565057397775, 11058.612948546763, 9027.0, 9028.458292502472, 9050.954745226232, 9219.815603687888, 9229.35394522377, 9381.812363752686, 9796.9020309796, 9804.754881127887, 9820.508974551243, 10062.19502439803, 4049.959500405017, 4139.2135494256, 4161.170404125163, 4177.895552611149, 4179.749215047107, 857.9571021448903, 972.4116909269932, 1036.8133735927454, 1083.9891601084046, 1096.4627332606965, 20069.59860802805, 20487.8975605124, 21818.018189181526, 21834.454138646342, 21992.89003554972, 1024.6925922223304, 1025.6307729217476, 1040.8178568750438, 1059.502034043998, 1068.5885933915342, 10973.945130274456, 11305.94347028276, 11339.716507087389, 11366.829497557543, 11373.772524549628, 771189.0, 784431.0, 787423.0, 796385.0, 800087.0, 143629.0, 168119.0, 171013.7246568846, 174434.02263909302, 175460.12269938824, 1102.8125218712837, 1310.8951283897266, 1318.9076764626384, 1350.1898860683511, 1352.3238380809596, 19858.801411986275, 20016.699749503478, 21788.0, 21865.67201491947, 21907.795071271234, 68534.31465685279, 68716.96924546036, 69131.65434172798, 69600.0, 69671.0, 85428.57285713531, 85871.71192431994, 87360.6319684026, 88100.00992161971, 88583.55708221417, 10397.84403233937, 11576.768464630828, 11817.527298908291, 11823.113266504875, 12105.515779368727, 13501.932490337485, 13630.250336231602, 13709.314534273048, 13931.860681393056, 13968.371423285773, 11540.653780386267, 11639.6508104757, 11854.348010859485, 11878.584249551224, 12005.63983080509, 10766.138708903425, 10781.892181078088, 11170.888291116984, 11268.830967535658, 11320.490577924178, 11685.298882067103, 11702.88297117018, 11909.76180476403, 12068.336241506802, 12124.0, 9359.719208423758, 11293.943530282297, 11538.365389903636, 11875.287482751062, 11964.042876570027, 11540.94229528847, 11675.941620291844, 11771.764564708828, 12035.939820300842, 12089.335086570323], "value": 12284.952711136633, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1424.8860091192694, 1615.3296382001463, 1642.236360092556, 1652.7190377635798, 1659.2118743596789, 9406.05939406059, 9784.315097943147, 11145.0, 11414.942925285382, 11612.303261804298, 11292.887071129286, 11334.319940803558, 11339.036181924546, 11352.262102963301, 11396.14528910331, 2188.7482939462006, 2452.117237794397, 2491.0035985605814, 2493.2021753039035, 2495.1641200197955, 2136.9893150534303, 2211.5687440949023, 2446.070493212577, 2515.6981162260554, 2527.747225277476, 2472.752724727522, 2484.7266800651914, 2503.7746602805746, 2503.849769013865, 2504.023430861965, 2483.515714435686, 2488.9626655600273, 2489.5394352044927, 2490.4272017436, 2491.725910149882, 2397.520495900827, 2427.2475532584776, 2435.1842132885504, 2436.9268921932194, 2483.981567557311, 11507.65477035682, 11602.245854019462, 11602.535898564047, 11624.302541847432, 11646.301221926711, 5800.303963524362, 5971.462568368867, 5973.880522389528, 5975.9701201494145, 5982.97008514959, 3557.864688538682, 3643.508126402928, 3812.218495208476, 3861.8841434757, 3877.0695033192114, 1250.72484053509, 1267.5373488676566, 1291.4640424223935, 1294.1717300927414, 1311.0, 15833.337499562494, 15905.125218113115, 15905.681886362208, 15922.522324330288, 15925.124118173495, 4790.0252657388, 4966.875828104254, 5011.974940125276, 5071.366079240095, 5152.92270615941, 12430.627081187578, 12463.314517701432, 12480.313582752855, 12489.313087780078, 12489.437975290952, 6019.277686677627, 6035.78874739382, 6038.818835434944, 6039.7282122304605, 6055.031195008777, 4745.098431298043, 5218.191180367049, 5364.892702145935, 5472.2338872557875, 5541.362743284574, 3813.9427908581392, 3905.511811023622, 4195.727277726999, 4211.1998720243055, 4241.660667146621, 1042.280826229899, 1156.1329003247565, 1167.6788883057227, 1176.2472017908544, 1185.7213554814612, 951.2580187453797, 987.4865070163619, 991.6876184002016, 1003.7841863999275, 1010.4846528270635, 6381.744730210832, 6496.610203387718, 6580.243272023778, 6630.204375474974, 6675.632840193836, 998.9250806189565, 1014.3305418423805, 1036.8133735927606, 1037.906588407047, 1040.5920610002152, 3646.872359467406, 3849.672777813917, 3866.149447121685, 3900.9609903901164, 4026.617471340218, 13772.173669579857, 14042.648933776532, 14172.716545669235, 14181.503647372292, 14208.218547979961, 13693.863061369259, 13700.794488082687, 13707.697768711956, 14070.577882663536, 14090.788638170436, 10273.434961077213, 10305.278630495794, 10503.369797812156, 10586.629467968583, 10638.989296016865, 5614.29821272341, 6378.2346118466085, 6420.101185834081, 6540.83647908787, 6643.800685979429, 12038.879611203776, 12340.568080117153, 12484.375781211085, 12643.557475488315, 12661.620151395473, 12479.750404991668, 12628.873711263137, 12873.22760634365, 12907.419166137734, 12925.224486530838, 7809.726659566888, 8789.428687135316, 8856.158664926821, 8993.235574976201, 9008.279337653103, 10288.897111028793, 10325.586976520855, 10403.791924161626, 10417.895821041995, 10435.530401131815, 8715.869261961203, 8873.955630221808, 8961.327900407365, 8964.282857371265, 8975.910240897507, 7547.471676982558, 8420.15798420153, 8732.781680457785, 8801.735947921572, 8833.823323533365, 7873.645685944261, 8035.156308587514, 8041.0, 8049.557274349732, 8058.3150432213915, 5620.241267428844, 5771.163181338767, 5778.306603207642, 5986.102084687332, 6030.5778595497895, 5893.44012318829, 6005.699715014319, 6036.0, 6159.106929495288, 6278.340774218641], "value": 5248.082880137356, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.3112921258585, 392.9057026313684, 395.7348576453776, 395.8337498250734, 398.4501388084443, 4524.280773306147, 5217.608679349049, 5234.18870075138, 5257.842264732055, 5281.788728450857, 4276.828926842923, 4826.565609095181, 4856.5143485651415, 5116.053530096935, 5182.481751824816, 3915.7288772511874, 4311.3101903695415, 4360.389545463638, 4362.520122786491, 4379.868603941887, 4072.226277007374, 4315.41741864849, 4346.434963454746, 4400.227856450431, 4406.625436837872, 3938.684905207591, 3991.42124391963, 4049.4533238012787, 4447.155040542287, 4462.772737497182, 3668.52309199805, 3708.533825006381, 4063.862118606795, 4308.504521979966, 4346.739195648272, 3654.2691461707764, 3878.5733569307354, 4001.519817621904, 4001.639852413297, 4007.6393124618926, 4587.3807036050375, 5033.345665063518, 5033.489953014081, 5044.243363495469, 5079.415867175285, 4822.445418776815, 4911.754412279408, 5008.821162794196, 5027.421846487628, 5034.597232221414, 5102.795888164469, 5194.961007798455, 5249.661336359242, 5453.972730136364, 5495.587830912697, 4202.637889688237, 4210.0, 4338.544452832439, 4402.977985110054, 4429.785107446277, 5646.080332686906, 5811.401864487258, 5843.211166492554, 5868.647881127146, 5878.588498805086, 3802.7338086334244, 4197.664186865043, 4239.25812982733, 4581.633469322446, 4618.35343051973, 4466.7766611669285, 4671.0, 4683.555062269147, 4731.479537250969, 4735.384400028043, 4405.405270288534, 4676.69601475903, 4952.504749525091, 4997.326964680957, 5030.974845125751, 4595.748083439157, 5333.626646134733, 5476.12382018883, 5515.145152501369, 5540.033288182637, 1390.57587435831, 1591.3395940684718, 1596.3933705191953, 1598.8001499812524, 1611.8307577704406, 1146.1003112556602, 1191.076915390562, 1192.8548593350308, 1198.3528894396927, 1218.945612045574, 4797.376341075707, 5137.921036582359, 5139.511746384088, 5156.716380599103, 5161.071007218737, 5160.793568257228, 5630.4369563043365, 5718.599698021098, 5730.255066841284, 5768.077107662753, 4807.134715751198, 4818.445878723922, 4865.297145998872, 5066.859956509756, 5264.78940842362, 2568.6403903453534, 2689.2116742366497, 2958.45268625305, 2967.4065186962694, 2982.9850850745606, 5387.434319396485, 5699.5517711784605, 5927.288725352985, 5942.554308426886, 5956.0, 5168.483151684876, 5173.844784656466, 5582.581306401955, 5754.309482862083, 5777.335606405317, 5062.797488100435, 5269.973650131801, 5810.970945145247, 5838.650205596858, 5970.805618993817, 4839.588634966068, 5302.242833726364, 5631.155326701028, 5749.655020698771, 5807.564432667651, 5445.1832225166545, 5511.917321240265, 5703.486686198262, 5745.787871669354, 5758.913616295843, 5299.364076310867, 5409.648372855751, 5970.5522085844605, 6051.4553690168095, 6087.752010837679, 4577.626711986457, 4730.5978991784705, 4848.781804818721, 4926.359573255454, 5058.597166346103, 5095.872603184802, 5810.186573879576, 5846.041576072063, 5853.795117170878, 5858.209141765978, 4718.787654555622, 5631.577631677558, 5654.208410822406, 5665.490105890325, 5682.772689092555, 5344.385395679391, 5601.719914004202, 5686.43135686428, 5693.402192769865, 5747.425257474386, 4717.268823332389, 5249.527542521191, 5523.613347065667, 5555.2778138841695, 5559.610827242054, 4937.9012419752125, 5031.446540880427, 5610.971945140248, 5625.774969001194, 5690.459406356389, 4665.626749860071, 5118.769655365592, 5299.629025968299, 5507.834764957058, 5566.276384070207], "value": 4288.658999921376, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.870312046952, 776.7669699090268, 779.885293520915, 813.0771574263209, 894.7628878347238, 12460.314682692448, 13938.51215207468, 14748.557543273719, 14878.628034299149, 14882.553523394288, 15859.207039648029, 15859.762103568455, 15879.809014323913, 16075.356985720586, 16104.597385065379, 974.3763991045719, 995.0348162282611, 998.3810037776552, 1026.420072658949, 1042.1454407385947, 1019.0624625344701, 1033.844923261513, 1035.2390992620417, 1037.1702637889696, 1039.9220058495644, 1073.4579037586009, 1073.468633026653, 1074.7957888001295, 1074.8817630060687, 1075.42464781342, 1068.9417476698052, 1069.3476979042778, 1070.347088276151, 1071.2287153249647, 1072.1637123044038, 794.0352471746855, 796.3310818912141, 796.625585974591, 797.2904115337354, 797.8523973064944, 16535.0, 16955.237014334383, 17463.21415536291, 17733.822661773476, 17734.20196091179, 14577.48978785748, 14840.480583179537, 14945.25273736309, 15011.774823377658, 15139.697206055927, 11949.581764638282, 12186.451609677586, 12387.504499819996, 12550.372481375985, 12895.0, 3834.2906562286066, 3900.2004589059175, 3901.707371947087, 4048.332025215836, 4058.959410405888, 104934.47532762287, 105953.0, 106590.0, 106689.46655266751, 107344.92655073428, 11098.61154859576, 11140.832887506695, 11475.88524114765, 11675.83996960565, 12113.818292725617, 24281.785910704395, 24436.511269774506, 24464.75535244625, 24505.387365316008, 24528.754712452646, 8089.676412943534, 8160.5103693778565, 8165.0, 8245.670173193004, 8387.077421483631, 4281.850135245251, 4531.592156705912, 4587.610053145453, 4670.182718024332, 4707.246840505502, 3681.0, 3702.8889133326043, 3761.191343861083, 3765.4351847223134, 3765.9058523537133, 571.0919637775957, 659.5185514574291, 676.7597502886503, 676.8576312964688, 677.1603212017063, 595.4254144750337, 715.1561157833702, 719.4244604316526, 734.1263895963785, 744.7021191523326, 9768.36505627132, 10426.843597346045, 10715.678529643967, 10963.397013164193, 11064.944675276733, 682.4233522673333, 689.6172624193621, 734.1777209525366, 756.6821934787403, 758.74202771057, 4266.786660666954, 4937.851864444073, 5209.53114219722, 5231.241469986907, 5239.318888544465, 88840.6673899892, 101900.49049754853, 108577.91422085837, 110381.0, 110393.0, 72598.63700681462, 75317.24682753212, 94455.0, 97397.51301243449, 101078.91368690634, 76060.47879042388, 77667.0, 81331.18668813354, 82438.0, 83002.0, 10785.514651840844, 11099.944500277446, 11453.0, 11461.598844040418, 11492.597759078391, 41762.58237417587, 47241.34655287186, 48327.0, 49784.257550985545, 50197.74901125471, 63801.08596742105, 64670.0, 65601.0, 66030.66984665046, 66893.66553167203, 12532.49870005226, 12590.748185036065, 12942.288174150135, 13035.934820325838, 13057.21656700601, 14688.971771976287, 14823.332950017491, 15133.167675777497, 15328.770068449205, 16078.67842643164, 13285.66785830362, 13386.330683465983, 14230.288485575888, 14415.639609009439, 14876.404943802127, 13222.484966562171, 13506.459741610226, 13923.303834808017, 14100.858991409954, 14410.279486025867, 14001.369938353002, 14031.44585373609, 14117.152970821784, 14177.078489898124, 14211.863050956106, 10119.0, 10338.0, 10344.637937672145, 10639.095676867553, 10762.515686793957, 11270.436478176221, 11346.602868899548, 11455.255408398427, 11488.655340339803, 11535.192536522363], "value": 7953.965922321667, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1439.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 115.0, 115.0, 114.0, 115.0, 115.0, 115.0, 114.0, 115.0, 115.0], "value": 114.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 126.0, 130.0, 145.0, 110.0, 113.0, 130.0, 128.0, 136.0, 108.0], "value": 128.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 185.0, 182.0, 183.0, 185.0, 186.0, 183.0, 184.0, 185.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 76.0, 108.0, 82.0, 61.0, 58.0, 58.0, 72.0, 59.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 88.0, 69.0, 69.0, 69.0, 82.0, 70.0, 70.0, 83.0], "value": 75.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 87.0, 87.0, 87.0, 86.0, 91.0, 129.0, 105.0], "value": 93.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 96.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 84.0, 87.0, 84.0, 101.0, 117.0, 127.0, 123.0], "value": 98.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 60.0, 60.0, 61.0, 63.0, 63.0, 64.0, 64.0, 63.0, 67.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 66.0, 71.0, 63.0, 62.0, 59.0, 60.0, 61.0, 59.0, 60.0], "value": 61.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 83.0, 83.0, 81.0, 84.0, 78.0, 80.0, 82.0, 81.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 133.0, 135.0, 129.0, 133.0, 134.0, 139.0, 135.0, 181.0, 165.0], "value": 141.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 163.0, 165.0, 160.0, 198.0, 161.0, 159.0, 164.0, 162.0], "value": 165.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 65.0, 62.0, 63.0, 64.0, 64.0, 63.0, 63.0, 62.0], "value": 63.5, "unit": "score"}]}]} 18:44:36 INFO - Return code: 0 18:44:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:44:36 INFO - # TBPL SUCCESS # 18:44:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:44:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:44:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:44:36 INFO - 2017-05-22 18:44:36.475 screenresolution[1938:20545] starting screenresolution argv=screenresolution get 18:44:36 INFO - 2017-05-22 18:44:36.478 screenresolution[1938:20545] Display 0: 1600x1200x32@60 18:44:36 INFO - 2017-05-22 18:44:36.484 screenresolution[1939:20548] starting screenresolution argv=screenresolution list 18:44:36 INFO - Available Modes on Display 0 18:44:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:44:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:44:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:44:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:44:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:44:36 INFO - Intel Iris: 18:44:36 INFO - Chipset Model: Intel Iris 18:44:36 INFO - Type: GPU 18:44:36 INFO - Bus: Built-In 18:44:36 INFO - VRAM (Dynamic, Max): 1536 MB 18:44:36 INFO - Vendor: Intel (0x8086) 18:44:36 INFO - Device ID: 0x0a2e 18:44:36 INFO - Revision ID: 0x0009 18:44:36 INFO - Displays: 18:44:36 INFO - Display: 18:44:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:44:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:44:36 INFO - Display Serial Number: 1600x1200 60 18:44:36 INFO - Main Display: Yes 18:44:36 INFO - Mirror: Off 18:44:36 INFO - Online: Yes 18:44:36 INFO - Rotation: Supported 18:44:36 INFO - Return code: 0 18:44:36 INFO - Running post-action listener: _resource_record_post_action 18:44:36 INFO - [mozharness: 2017-05-23 01:44:36.722221Z] Finished run-tests step (success) 18:44:36 INFO - Running post-run listener: _resource_record_post_run 18:44:36 INFO - Total resource usage - Wall time: 1194s; CPU: 54.0%; Read bytes: 2564096; Write bytes: 420384768; Read time: 12; Write time: 1624 18:44:36 INFO - TinderboxPrint: CPU usage
54.1% 18:44:36 INFO - TinderboxPrint: I/O read bytes / time
2,564,096 / 12 18:44:36 INFO - TinderboxPrint: I/O write bytes / time
420,384,768 / 1,624 18:44:36 INFO - TinderboxPrint: CPU idle
2,191.7 (45.9%) 18:44:36 INFO - TinderboxPrint: CPU system
958.2 (20.1%) 18:44:36 INFO - TinderboxPrint: CPU user
1,627.7 (34.1%) 18:44:36 INFO - TinderboxPrint: Swap in / out
124,387,328 / 0 18:44:36 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121666048; Write bytes: 116500480; Read time: 11062; Write time: 335 18:44:36 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 1937408; Write bytes: 293681152; Read time: 9; Write time: 1224 18:44:37 INFO - Running post-run listener: _upload_blobber_files 18:44:37 INFO - Blob upload gear active. 18:44:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:44:37 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 18:44:37 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'] 18:44:37 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 18:44:37 INFO - (blobuploader) - INFO - Open directory for files ... 18:44:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:44:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:44:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:38 INFO - (blobuploader) - INFO - Done attempting. 18:44:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:44:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:44:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:39 INFO - (blobuploader) - INFO - Done attempting. 18:44:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:44:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:40 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:44:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:40 INFO - (blobuploader) - INFO - Done attempting. 18:44:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:44:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:44:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:44:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:44:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:44:42 INFO - (blobuploader) - INFO - Done attempting. 18:44:42 INFO - (blobuploader) - INFO - Iteration through files over. 18:44:42 INFO - Return code: 0 18:44:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:44:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:44:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4a0ef0f508928b171ebb03c4cef603da07731f5564d9c52e8480a2de6fdfb1cef000afe0540d156c78b94ff75a5ae85d5f8f83fa3545da8aa31afe8ed3be273d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ab72e689c673d6b8223ca285138f8ca9cbf44ed6cc84ee2fe2d36150e2eb6daacde6bb7d4dd91a4f75b0f22f4eb3b2ac3d51eed35dfb2e877c3688d27bde99d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/09542fbccff94d1071037b49132483c70c0aae8e884dbf1cbcf7926ceb2feb3fb48af3a4f1fad2b8fd96b246ef7f7e463485559c5e095ee6a00cf31d310a36a1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f3e61f7db99f04aba20f2d675da74da6c468d92b349d8a575baeaf908c4b7e6790097f5c2096f9ff3660dd78e959ef8935855cfd4cab5c2a4eb243bc165ae75"} 18:44:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:44:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:44:42 INFO - Contents: 18:44:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4a0ef0f508928b171ebb03c4cef603da07731f5564d9c52e8480a2de6fdfb1cef000afe0540d156c78b94ff75a5ae85d5f8f83fa3545da8aa31afe8ed3be273d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ab72e689c673d6b8223ca285138f8ca9cbf44ed6cc84ee2fe2d36150e2eb6daacde6bb7d4dd91a4f75b0f22f4eb3b2ac3d51eed35dfb2e877c3688d27bde99d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/09542fbccff94d1071037b49132483c70c0aae8e884dbf1cbcf7926ceb2feb3fb48af3a4f1fad2b8fd96b246ef7f7e463485559c5e095ee6a00cf31d310a36a1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f3e61f7db99f04aba20f2d675da74da6c468d92b349d8a575baeaf908c4b7e6790097f5c2096f9ff3660dd78e959ef8935855cfd4cab5c2a4eb243bc165ae75"} 18:44:42 INFO - Running post-run listener: copy_logs_to_upload_dir 18:44:42 INFO - Copying logs to upload dir... 18:44:42 INFO - mkdir: /builds/slave/test/build/upload/logs 18:44:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.751713 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-05-22 18:44:42.878533) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 18:44:42.881843) ========= 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.cUZXsDLcQv/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.bvLplIQ1gI/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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/4a0ef0f508928b171ebb03c4cef603da07731f5564d9c52e8480a2de6fdfb1cef000afe0540d156c78b94ff75a5ae85d5f8f83fa3545da8aa31afe8ed3be273d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ab72e689c673d6b8223ca285138f8ca9cbf44ed6cc84ee2fe2d36150e2eb6daacde6bb7d4dd91a4f75b0f22f4eb3b2ac3d51eed35dfb2e877c3688d27bde99d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/09542fbccff94d1071037b49132483c70c0aae8e884dbf1cbcf7926ceb2feb3fb48af3a4f1fad2b8fd96b246ef7f7e463485559c5e095ee6a00cf31d310a36a1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f3e61f7db99f04aba20f2d675da74da6c468d92b349d8a575baeaf908c4b7e6790097f5c2096f9ff3660dd78e959ef8935855cfd4cab5c2a4eb243bc165ae75"} build_url:https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011250 build_url: 'https://queue.taskcluster.net/v1/task/DRjsa7WFQLOKQgD_buUsdw/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/4a0ef0f508928b171ebb03c4cef603da07731f5564d9c52e8480a2de6fdfb1cef000afe0540d156c78b94ff75a5ae85d5f8f83fa3545da8aa31afe8ed3be273d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ab72e689c673d6b8223ca285138f8ca9cbf44ed6cc84ee2fe2d36150e2eb6daacde6bb7d4dd91a4f75b0f22f4eb3b2ac3d51eed35dfb2e877c3688d27bde99d2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/09542fbccff94d1071037b49132483c70c0aae8e884dbf1cbcf7926ceb2feb3fb48af3a4f1fad2b8fd96b246ef7f7e463485559c5e095ee6a00cf31d310a36a1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0f3e61f7db99f04aba20f2d675da74da6c468d92b349d8a575baeaf908c4b7e6790097f5c2096f9ff3660dd78e959ef8935855cfd4cab5c2a4eb243bc165ae75"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-22 18:44:42.914667) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:44:42.914997) ========= 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.cUZXsDLcQv/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.bvLplIQ1gI/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/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.005274 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-22 18:44:42.963732) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 18:44:42.964109) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-22 18:44:42.964535) ========= ========= Total master_lag: 0.17 =========