builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0034 starttime: 1496699951.09 results: success (0) buildid: 20170605125726 builduid: 26e479bb84b941c4bfb76685dc5bfcb8 revision: 97767088522e789dd04cf06b24478f052946d1c9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 14:59:11.094585) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 14:59:11.095065) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 14:59:11.095366) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 14:59:11.119423) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:59:11.119725) ========= 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.0pTqGxV5YK/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.K0zVTOo7qg/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-06-05 14:59:11-- 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% 70.4M=0s 2017-06-05 14:59:11 (70.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119880 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:59:11.262934) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:59:11.263270) ========= 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.0pTqGxV5YK/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.K0zVTOo7qg/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.022167 ========= master_lag: 0.18 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 14:59:11.462938) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-06-05 14:59:11.463310) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 97767088522e789dd04cf06b24478f052946d1c9 --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 97767088522e789dd04cf06b24478f052946d1c9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0pTqGxV5YK/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.K0zVTOo7qg/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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-06-05 14:59:11,817 truncating revision to first 12 chars 2017-06-05 14:59:11,817 Setting DEBUG logging. 2017-06-05 14:59:11,817 attempt 1/10 2017-06-05 14:59:11,817 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/97767088522e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 14:59:12,830 unpacking tar archive at: mozilla-beta-97767088522e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.290655 ========= master_lag: 1.79 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-06-05 14:59:14.539273) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 14:59:14.539642) ========= script_repo_revision: 97767088522e789dd04cf06b24478f052946d1c9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 14:59:14.540086) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 14:59:14.540415) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 14:59:14.575131) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-06-05 14:59:14.575549) ========= /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.0pTqGxV5YK/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.K0zVTOo7qg/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 14:59:14 INFO - MultiFileLogger online at 20170605 14:59:14 in /builds/slave/test 14:59:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 14:59:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:59:14 INFO - {'append_to_log': False, 14:59:14 INFO - 'base_work_dir': '/builds/slave/test', 14:59:14 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:59:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:59:14 INFO - 'branch': 'Mozilla-Beta', 14:59:14 INFO - 'buildbot_json_path': 'buildprops.json', 14:59:14 INFO - 'config_files': ('talos/mac_config.py',), 14:59:14 INFO - 'default_actions': ('clobber', 14:59:14 INFO - 'read-buildbot-config', 14:59:14 INFO - 'download-and-extract', 14:59:14 INFO - 'populate-webroot', 14:59:14 INFO - 'create-virtualenv', 14:59:14 INFO - 'install', 14:59:14 INFO - 'run-tests'), 14:59:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:59:14 INFO - 'download_minidump_stackwalk': True, 14:59:14 INFO - 'download_symbols': 'ondemand', 14:59:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:59:14 INFO - 'tooltool.py': '/tools/tooltool.py', 14:59:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:59:14 INFO - '/tools/misc-python/virtualenv.py')}, 14:59:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:59:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:59:14 INFO - 'gecko_profile': False, 14:59:14 INFO - 'gecko_profile_interval': 0, 14:59:14 INFO - 'installer_path': 'installer.exe', 14:59:14 INFO - 'log_level': 'info', 14:59:14 INFO - 'log_name': 'talos', 14:59:14 INFO - 'log_to_console': True, 14:59:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:59:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:59:14 INFO - 'opt_config_files': (), 14:59:14 INFO - 'pip_index': False, 14:59:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:59:14 INFO - 'cmd': ('bash', 14:59:14 INFO - '-c', 14:59:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:59:14 INFO - 'enabled': True, 14:59:14 INFO - 'halt_on_failure': False, 14:59:14 INFO - 'name': 'check_screen_resolution'},), 14:59:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:59:14 INFO - 'cmd': ('bash', 14:59:14 INFO - '-c', 14:59:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:59:14 INFO - 'enabled': True, 14:59:14 INFO - 'halt_on_failure': False, 14:59:14 INFO - 'name': 'check_screen_resolution'},), 14:59:14 INFO - 'run_cmd_checks_enabled': True, 14:59:14 INFO - 'suite': 'dromaeojs', 14:59:14 INFO - 'system_bits': '32', 14:59:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:59:14 INFO - 'title': 't-yosemite-r7-0034', 14:59:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:59:14 INFO - 'use_talos_json': True, 14:59:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:59:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:59:14 INFO - 'work_dir': 'build'} 14:59:14 INFO - [mozharness: 2017-06-05 21:59:14.791225Z] Running clobber step. 14:59:14 INFO - Running pre-action listener: _resource_record_pre_action 14:59:14 INFO - Running main action method: clobber 14:59:14 INFO - rmtree: /builds/slave/test/build 14:59:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:59:16 INFO - Running post-action listener: _resource_record_post_action 14:59:16 INFO - [mozharness: 2017-06-05 21:59:16.729764Z] Finished clobber step (success) 14:59:16 INFO - [mozharness: 2017-06-05 21:59:16.730028Z] Running read-buildbot-config step. 14:59:16 INFO - Running pre-action listener: _resource_record_pre_action 14:59:16 INFO - Running main action method: read_buildbot_config 14:59:16 INFO - Using buildbot properties: 14:59:16 INFO - { 14:59:16 INFO - "project": "", 14:59:16 INFO - "product": "firefox", 14:59:16 INFO - "script_repo_revision": "production", 14:59:16 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 14:59:16 INFO - "repository": "", 14:59:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 14:59:16 INFO - "buildid": "20170605125726", 14:59:16 INFO - "pgo_build": "False", 14:59:16 INFO - "basedir": "/builds/slave/test", 14:59:16 INFO - "buildnumber": 5, 14:59:16 INFO - "slavename": "t-yosemite-r7-0034", 14:59:16 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 14:59:16 INFO - "platform": "yosemite_r7", 14:59:16 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 14:59:16 INFO - "branch": "mozilla-beta", 14:59:16 INFO - "repo_path": "releases/mozilla-beta", 14:59:16 INFO - "revision": "97767088522e789dd04cf06b24478f052946d1c9", 14:59:16 INFO - "stage_platform": "macosx64", 14:59:16 INFO - "builduid": "26e479bb84b941c4bfb76685dc5bfcb8", 14:59:16 INFO - "slavebuilddir": "test" 14:59:16 INFO - } 14:59:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 14:59:16 INFO - Running post-action listener: _resource_record_post_action 14:59:16 INFO - [mozharness: 2017-06-05 21:59:16.736025Z] Finished read-buildbot-config step (success) 14:59:16 INFO - [mozharness: 2017-06-05 21:59:16.736240Z] Running download-and-extract step. 14:59:16 INFO - Running pre-action listener: _resource_record_pre_action 14:59:16 INFO - Running main action method: download_and_extract 14:59:16 INFO - mkdir: /builds/slave/test/build/tests 14:59:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:59:16 INFO - trying https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 14:59:16 INFO - Downloading https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/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 14:59:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/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 14:59:18 INFO - Downloaded 1287 bytes. 14:59:18 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 14:59:18 INFO - Using the following test package requirements: 14:59:18 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:18 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 14:59:18 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 14:59:18 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:18 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 14:59:18 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:18 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 14:59:18 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 14:59:18 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:18 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 14:59:18 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 14:59:18 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:18 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 14:59:18 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:18 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 14:59:18 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:18 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 14:59:18 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 14:59:18 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 14:59:18 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 14:59:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 14:59:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 14:59:18 INFO - Fetch https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 14:59:19 INFO - Expected file size: 51393777 14:59:19 INFO - Obtained file size: 51393777 14:59:36 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 14:59:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 14:59:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 14:59:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 14:59:38 INFO - Expected file size: 51393777 14:59:38 INFO - Obtained file size: 51393777 14:59:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 14:59:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 14:59:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 14:59:55 INFO - Expected file size: 14404461 14:59:55 INFO - Obtained file size: 14404461 14:59:55 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')]} 14:59:55 INFO - trying https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 14:59:55 INFO - Downloading https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 14:59:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/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 14:59:57 INFO - Downloaded 59679332 bytes. 14:59:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 14:59:57 INFO - mkdir: /builds/slave/test/properties 14:59:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:59:57 INFO - Writing to file /builds/slave/test/properties/build_url 14:59:57 INFO - Contents: 14:59:57 INFO - build_url:https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 14:59:58 INFO - Running post-action listener: _resource_record_post_action 14:59:58 INFO - Running post-action listener: set_extra_try_arguments 14:59:58 INFO - [mozharness: 2017-06-05 21:59:58.409277Z] Finished download-and-extract step (success) 14:59:58 INFO - [mozharness: 2017-06-05 21:59:58.409403Z] Running populate-webroot step. 14:59:58 INFO - Running pre-action listener: _resource_record_pre_action 14:59:58 INFO - Running main action method: populate_webroot 14:59:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:59:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:59:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:59:58 INFO - '--tppagecycles', 14:59:58 INFO - '7'], 14:59:58 INFO - 'tests': ['tsvgm']}}, 14:59:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:59:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:59:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:59:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:59:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:59:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:59:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:59:58 INFO - 'talos_options': ['--disable-e10s'], 14:59:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:59:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:59:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:59:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:59:58 INFO - 'talos_options': ['--disable-e10s'], 14:59:58 INFO - 'tests': ['damp', 'tps']}, 14:59:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:59:58 INFO - 'tests': ['damp', 'tps']}, 14:59:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:59:58 INFO - 'tests': ['dromaeo_dom']}, 14:59:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:59:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:59:58 INFO - 'tests': ['basic_compositor_video']}, 14:59:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:59:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:59:58 INFO - 'tests': ['a11yr', 14:59:58 INFO - 'ts_paint', 14:59:58 INFO - 'tpaint', 14:59:58 INFO - 'sessionrestore', 14:59:58 INFO - 'sessionrestore_no_auto_restore', 14:59:58 INFO - 'tabpaint']}, 14:59:58 INFO - 'other-e10s': {'tests': ['a11yr', 14:59:58 INFO - 'ts_paint', 14:59:58 INFO - 'tpaint', 14:59:58 INFO - 'sessionrestore', 14:59:58 INFO - 'sessionrestore_no_auto_restore', 14:59:58 INFO - 'tabpaint']}, 14:59:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:59:58 INFO - 'ts_paint', 14:59:58 INFO - 'tpaint', 14:59:58 INFO - 'sessionrestore', 14:59:58 INFO - 'sessionrestore_no_auto_restore', 14:59:58 INFO - 'tabpaint']}, 14:59:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:59:58 INFO - 'ts_paint', 14:59:58 INFO - 'tpaint', 14:59:58 INFO - 'sessionrestore', 14:59:58 INFO - 'sessionrestore_no_auto_restore', 14:59:58 INFO - 'tabpaint']}, 14:59:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:59:58 INFO - 'tests': ['a11yr', 14:59:58 INFO - 'ts_paint', 14:59:58 INFO - 'tpaint', 14:59:58 INFO - 'sessionrestore', 14:59:58 INFO - 'sessionrestore_no_auto_restore', 14:59:58 INFO - 'tabpaint']}, 14:59:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:59:58 INFO - 'tests': ['a11yr', 14:59:58 INFO - 'ts_paint', 14:59:58 INFO - 'tpaint', 14:59:58 INFO - 'sessionrestore', 14:59:58 INFO - 'sessionrestore_no_auto_restore', 14:59:58 INFO - 'tabpaint']}, 14:59:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:59:58 INFO - 'tests': ['tsvgx', 14:59:58 INFO - 'tsvgr_opacity', 14:59:58 INFO - 'tart', 14:59:58 INFO - 'tscrollx', 14:59:58 INFO - 'cart', 14:59:58 INFO - 'tsvg_static']}, 14:59:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:59:58 INFO - 'tsvgr_opacity', 14:59:58 INFO - 'tart', 14:59:58 INFO - 'tscrollx', 14:59:58 INFO - 'cart', 14:59:58 INFO - 'tsvg_static']}, 14:59:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:59:58 INFO - 'talos_options': ['--disable-e10s'], 14:59:58 INFO - 'tests': ['tp5o']}, 14:59:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:59:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:59:58 INFO - 'talos_options': ['--disable-e10s', 14:59:58 INFO - '--xperf_path', 14:59:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:59:58 INFO - 'tests': ['tp5n']}, 14:59:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:59:58 INFO - 'talos_options': ['--xperf_path', 14:59:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:59:58 INFO - 'tests': ['tp5n']}}, 14:59:58 INFO - 'talos.zip': {'path': '', 14:59:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:59:58 INFO - Running post-action listener: _resource_record_post_action 14:59:58 INFO - [mozharness: 2017-06-05 21:59:58.428763Z] Finished populate-webroot step (success) 14:59:58 INFO - [mozharness: 2017-06-05 21:59:58.428911Z] Running create-virtualenv step. 14:59:58 INFO - Running pre-action listener: _resource_record_pre_action 14:59:58 INFO - Running main action method: create_virtualenv 14:59:58 INFO - Creating virtualenv /builds/slave/test/build/venv 14:59:58 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 14:59:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:59:58 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:59:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:59:58 INFO - Using real prefix '/tools/python27' 14:59:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:59:59 INFO - Installing distribute.............................................................................................................................................................................................done. 15:00:02 INFO - Installing pip.................done. 15:00:02 INFO - Return code: 0 15:00:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:00:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:00:02 INFO - Reading from file tmpfile_stdout 15:00:02 INFO - Output received: 15:00:02 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) 15:00:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:00:02 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')]} 15:00:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:02 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')]} 15:00:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:02 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 0x106822b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106825768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f877b4686d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106600d30>, '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 0x106527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065f5be8>, '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.0pTqGxV5YK/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.K0zVTOo7qg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:00:02 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 15:00:02 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 15:00:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0pTqGxV5YK/Render', 15:00:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:02 INFO - 'HOME': '/Users/cltbld', 15:00:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:02 INFO - 'LOGNAME': 'cltbld', 15:00:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:02 INFO - 'MOZ_NO_REMOTE': '1', 15:00:02 INFO - 'NO_EM_RESTART': '1', 15:00:02 INFO - 'PAGER': '/bin/cat', 15:00:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:02 INFO - 'PWD': '/builds/slave/test', 15:00:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:02 INFO - 'SHELL': '/bin/bash', 15:00:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K0zVTOo7qg/Listeners', 15:00:02 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:00:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:02 INFO - 'USER': 'cltbld', 15:00:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:02 INFO - 'XPC_FLAGS': '0x0', 15:00:02 INFO - 'XPC_SERVICE_NAME': '0', 15:00:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:03 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 15:00:03 INFO - Cleaning up... 15:00:03 INFO - Return code: 0 15:00:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:00:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106822b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106825768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f877b4686d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106600d30>, '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 0x106527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065f5be8>, '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.0pTqGxV5YK/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.K0zVTOo7qg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:00:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:00:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:00:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0pTqGxV5YK/Render', 15:00:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:03 INFO - 'HOME': '/Users/cltbld', 15:00:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:03 INFO - 'LOGNAME': 'cltbld', 15:00:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:03 INFO - 'MOZ_NO_REMOTE': '1', 15:00:03 INFO - 'NO_EM_RESTART': '1', 15:00:03 INFO - 'PAGER': '/bin/cat', 15:00:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:03 INFO - 'PWD': '/builds/slave/test', 15:00:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:03 INFO - 'SHELL': '/bin/bash', 15:00:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K0zVTOo7qg/Listeners', 15:00:03 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:00:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:03 INFO - 'USER': 'cltbld', 15:00:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:03 INFO - 'XPC_FLAGS': '0x0', 15:00:03 INFO - 'XPC_SERVICE_NAME': '0', 15:00:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:03 INFO - Downloading/unpacking psutil>=3.1.1 15:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:00:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:00:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:00:05 INFO - Installing collected packages: psutil 15:00:05 INFO - Running setup.py install for psutil 15:00:05 INFO - building 'psutil._psutil_osx' extension 15:00:05 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 15:00:06 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 15:00:06 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 15:00:06 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 15:00:06 INFO - building 'psutil._psutil_posix' extension 15:00:06 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 15:00:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:00:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:00:06 INFO - ^ 15:00:06 INFO - 1 warning generated. 15:00:06 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 15:00:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:00:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:00:06 INFO - Successfully installed psutil 15:00:06 INFO - Cleaning up... 15:00:06 INFO - Return code: 0 15:00:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:00:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:00:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106822b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106825768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f877b4686d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106600d30>, '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 0x106527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065f5be8>, '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.0pTqGxV5YK/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.K0zVTOo7qg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:00:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:00:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:00:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0pTqGxV5YK/Render', 15:00:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:06 INFO - 'HOME': '/Users/cltbld', 15:00:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:06 INFO - 'LOGNAME': 'cltbld', 15:00:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:06 INFO - 'MOZ_NO_REMOTE': '1', 15:00:06 INFO - 'NO_EM_RESTART': '1', 15:00:06 INFO - 'PAGER': '/bin/cat', 15:00:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:06 INFO - 'PWD': '/builds/slave/test', 15:00:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:06 INFO - 'SHELL': '/bin/bash', 15:00:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K0zVTOo7qg/Listeners', 15:00:06 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:00:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:06 INFO - 'USER': 'cltbld', 15:00:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:06 INFO - 'XPC_FLAGS': '0x0', 15:00:06 INFO - 'XPC_SERVICE_NAME': '0', 15:00:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:00:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:00:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:00:08 INFO - Installing collected packages: mozsystemmonitor 15:00:08 INFO - Running setup.py install for mozsystemmonitor 15:00:08 INFO - Successfully installed mozsystemmonitor 15:00:08 INFO - Cleaning up... 15:00:08 INFO - Return code: 0 15:00:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x106822b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106825768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f877b4686d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106600d30>, '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 0x106527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065f5be8>, '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.0pTqGxV5YK/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.K0zVTOo7qg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:00:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:00:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:00:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0pTqGxV5YK/Render', 15:00:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:08 INFO - 'HOME': '/Users/cltbld', 15:00:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:08 INFO - 'LOGNAME': 'cltbld', 15:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:08 INFO - 'MOZ_NO_REMOTE': '1', 15:00:08 INFO - 'NO_EM_RESTART': '1', 15:00:08 INFO - 'PAGER': '/bin/cat', 15:00:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:08 INFO - 'PWD': '/builds/slave/test', 15:00:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:08 INFO - 'SHELL': '/bin/bash', 15:00:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K0zVTOo7qg/Listeners', 15:00:08 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:00:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:08 INFO - 'USER': 'cltbld', 15:00:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:08 INFO - 'XPC_FLAGS': '0x0', 15:00:08 INFO - 'XPC_SERVICE_NAME': '0', 15:00:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:09 INFO - Downloading/unpacking jsonschema==2.5.1 15:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:00:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:10 INFO - Downloading functools32-3.2.3-2.tar.gz 15:00:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:00:10 INFO - warning: no files found matching '*.txt' 15:00:10 INFO - no previously-included directories found matching 'build' 15:00:10 INFO - no previously-included directories found matching 'dist' 15:00:10 INFO - no previously-included directories found matching '.git*' 15:00:10 INFO - Installing collected packages: jsonschema, functools32 15:00:10 INFO - Running setup.py install for functools32 15:00:10 INFO - warning: no files found matching '*.txt' 15:00:10 INFO - no previously-included directories found matching 'build' 15:00:10 INFO - no previously-included directories found matching 'dist' 15:00:10 INFO - no previously-included directories found matching '.git*' 15:00:11 INFO - Successfully installed jsonschema functools32 15:00:11 INFO - Cleaning up... 15:00:11 INFO - Return code: 0 15:00:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:00: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')]} 15:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00: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')]} 15:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:11 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 0x106822b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106825768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f877b4686d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106600d30>, '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 0x106527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065f5be8>, '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.0pTqGxV5YK/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.K0zVTOo7qg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:00:11 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 15:00:11 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 15:00:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0pTqGxV5YK/Render', 15:00:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:11 INFO - 'HOME': '/Users/cltbld', 15:00:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:11 INFO - 'LOGNAME': 'cltbld', 15:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:11 INFO - 'MOZ_NO_REMOTE': '1', 15:00:11 INFO - 'NO_EM_RESTART': '1', 15:00:11 INFO - 'PAGER': '/bin/cat', 15:00:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:11 INFO - 'PWD': '/builds/slave/test', 15:00:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:11 INFO - 'SHELL': '/bin/bash', 15:00:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K0zVTOo7qg/Listeners', 15:00:11 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:00:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:11 INFO - 'USER': 'cltbld', 15:00:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:11 INFO - 'XPC_FLAGS': '0x0', 15:00:11 INFO - 'XPC_SERVICE_NAME': '0', 15:00:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:00:11 INFO - Cleaning up... 15:00:11 INFO - Return code: 0 15:00:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:00: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')]} 15:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00: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')]} 15:00:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:11 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 0x106822b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106825768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f877b4686d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106600d30>, '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 0x106527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065f5be8>, '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.0pTqGxV5YK/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.K0zVTOo7qg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:00:11 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 15:00:11 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 15:00:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0pTqGxV5YK/Render', 15:00:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:11 INFO - 'HOME': '/Users/cltbld', 15:00:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:11 INFO - 'LOGNAME': 'cltbld', 15:00:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:11 INFO - 'MOZ_NO_REMOTE': '1', 15:00:11 INFO - 'NO_EM_RESTART': '1', 15:00:11 INFO - 'PAGER': '/bin/cat', 15:00:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:11 INFO - 'PWD': '/builds/slave/test', 15:00:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:11 INFO - 'SHELL': '/bin/bash', 15:00:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K0zVTOo7qg/Listeners', 15:00:11 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:00:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:11 INFO - 'USER': 'cltbld', 15:00:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:11 INFO - 'XPC_FLAGS': '0x0', 15:00:11 INFO - 'XPC_SERVICE_NAME': '0', 15:00:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:11 INFO - Downloading/unpacking blobuploader==1.2.4 15:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:12 INFO - Downloading blobuploader-1.2.4.tar.gz 15:00:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:00:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:00:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:00:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:00:13 INFO - Downloading docopt-0.6.1.tar.gz 15:00:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:00:14 INFO - Installing collected packages: blobuploader, requests, docopt 15:00:14 INFO - Running setup.py install for blobuploader 15:00:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:00:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:00:14 INFO - Running setup.py install for requests 15:00:14 INFO - Running setup.py install for docopt 15:00:14 INFO - Successfully installed blobuploader requests docopt 15:00:14 INFO - Cleaning up... 15:00:14 INFO - Return code: 0 15:00:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106822b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106825768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f877b4686d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106600d30>, '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 0x106527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065f5be8>, '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.0pTqGxV5YK/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.K0zVTOo7qg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:00:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:00:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:00:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0pTqGxV5YK/Render', 15:00:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:14 INFO - 'HOME': '/Users/cltbld', 15:00:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:14 INFO - 'LOGNAME': 'cltbld', 15:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:14 INFO - 'MOZ_NO_REMOTE': '1', 15:00:14 INFO - 'NO_EM_RESTART': '1', 15:00:14 INFO - 'PAGER': '/bin/cat', 15:00:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:14 INFO - 'PWD': '/builds/slave/test', 15:00:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:14 INFO - 'SHELL': '/bin/bash', 15:00:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K0zVTOo7qg/Listeners', 15:00:14 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:00:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:14 INFO - 'USER': 'cltbld', 15:00:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:14 INFO - 'XPC_FLAGS': '0x0', 15:00:14 INFO - 'XPC_SERVICE_NAME': '0', 15:00:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:00:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ASg9yV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:00:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-lU4dRN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:00:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-V8qyxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:00:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-hAqDYI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:00:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-uILMgx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:00:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Sa6exH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:00:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-uwbKhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:00:15 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-lE21bo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:00:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-wNcJwW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:00:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-diwOxh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:00:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-Ud5Y_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:00:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-hB8F07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:00:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-sgbqnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:00:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-texO6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:00:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-nkA0qd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:00:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-TuFfJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:00:16 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-aJ3ot4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:00:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:00:17 INFO - Running setup.py install for manifestparser 15:00:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:00:17 INFO - Running setup.py install for mozcrash 15:00:17 INFO - Running setup.py install for mozdebug 15:00:17 INFO - Running setup.py install for mozdevice 15:00:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:00:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:00:17 INFO - Running setup.py install for mozfile 15:00:17 INFO - Running setup.py install for mozhttpd 15:00:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:00:18 INFO - Running setup.py install for mozinfo 15:00:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:00:18 INFO - Running setup.py install for mozInstall 15:00:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:00:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:00:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:00:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:00:18 INFO - Running setup.py install for mozleak 15:00:18 INFO - Running setup.py install for mozlog 15:00:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:00:18 INFO - Running setup.py install for moznetwork 15:00:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:00:18 INFO - Running setup.py install for mozprocess 15:00:18 INFO - Running setup.py install for mozprofile 15:00:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:00:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:00:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:00:19 INFO - Running setup.py install for mozrunner 15:00:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:00:19 INFO - Running setup.py install for mozscreenshot 15:00:19 INFO - Running setup.py install for moztest 15:00:19 INFO - Running setup.py install for mozversion 15:00:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:00:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:00:19 INFO - Cleaning up... 15:00:19 INFO - Return code: 0 15:00:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:00: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')]} 15:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00: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')]} 15:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:19 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 0x106822b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106825768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f877b4686d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106600d30>, '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 0x106527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065f5be8>, '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.0pTqGxV5YK/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.K0zVTOo7qg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:00:19 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 15:00:19 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 15:00:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0pTqGxV5YK/Render', 15:00:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:19 INFO - 'HOME': '/Users/cltbld', 15:00:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:19 INFO - 'LOGNAME': 'cltbld', 15:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:19 INFO - 'MOZ_NO_REMOTE': '1', 15:00:19 INFO - 'NO_EM_RESTART': '1', 15:00:19 INFO - 'PAGER': '/bin/cat', 15:00:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:19 INFO - 'PWD': '/builds/slave/test', 15:00:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:19 INFO - 'SHELL': '/bin/bash', 15:00:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K0zVTOo7qg/Listeners', 15:00:19 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:00:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:19 INFO - 'USER': 'cltbld', 15:00:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:19 INFO - 'XPC_FLAGS': '0x0', 15:00:19 INFO - 'XPC_SERVICE_NAME': '0', 15:00:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:00:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-P99Ccw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:00:20 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)) 15:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:00:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-f6eNtz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:00:20 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)) 15:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:00:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-vIYHQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:00:20 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)) 15:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:00:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-rWE8Pk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:00:20 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)) 15:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:00:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-q00lxv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:00:20 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)) 15:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:00:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ZrVZlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:00:20 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)) 15:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:00:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-5LbDfu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:00:20 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)) 15:00:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:00:20 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-S2ivcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:00:21 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)) 15:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:00:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-JUT01E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:00:21 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)) 15:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:00:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-eMohrL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:00:21 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)) 15:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:00:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-wsz7ri-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:00:21 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)) 15:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:00:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-t_05fI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:00:21 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)) 15:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:00:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-ahKSXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:00:21 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)) 15:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:00:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-DpX9Mh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:00:21 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)) 15:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:00:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-5x7gdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:00:21 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)) 15:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:00:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-EZFKJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:00:21 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)) 15:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:00:21 INFO - Running setup.py (path:/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/pip-mMO5zl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:00:22 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)) 15:00:22 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)) 15:00:22 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)) 15:00:22 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)) 15:00:22 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)) 15:00:22 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)) 15:00:22 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:00: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 15:00: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 15:00: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 15:00: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 15:00:23 INFO - Downloading blessings-1.6.tar.gz 15:00:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:00:23 INFO - Installing collected packages: blessings 15:00:23 INFO - Running setup.py install for blessings 15:00:23 INFO - Successfully installed blessings 15:00:23 INFO - Cleaning up... 15:00:23 INFO - Return code: 0 15:00:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:00:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:00:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:00:24 INFO - Reading from file tmpfile_stdout 15:00:24 INFO - Current package versions: 15:00:24 INFO - blessings == 1.6 15:00:24 INFO - blobuploader == 1.2.4 15:00:24 INFO - docopt == 0.6.1 15:00:24 INFO - functools32 == 3.2.3-2 15:00:24 INFO - jsonschema == 2.5.1 15:00:24 INFO - manifestparser == 1.1 15:00:24 INFO - mozInstall == 1.12 15:00:24 INFO - mozcrash == 1.0 15:00:24 INFO - mozdebug == 0.1 15:00:24 INFO - mozdevice == 0.49 15:00:24 INFO - mozfile == 1.2 15:00:24 INFO - mozhttpd == 0.7 15:00:24 INFO - mozinfo == 0.9 15:00:24 INFO - mozleak == 0.1 15:00:24 INFO - mozlog == 3.4 15:00:24 INFO - moznetwork == 0.27 15:00:24 INFO - mozprocess == 0.25 15:00:24 INFO - mozprofile == 0.28 15:00:24 INFO - mozrunner == 6.13 15:00:24 INFO - mozscreenshot == 0.1 15:00:24 INFO - mozsystemmonitor == 0.3 15:00:24 INFO - moztest == 0.8 15:00:24 INFO - mozversion == 1.4 15:00:24 INFO - psutil == 3.1.1 15:00:24 INFO - requests == 1.2.3 15:00:24 INFO - wsgiref == 0.1.2 15:00:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:00: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')]} 15:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00: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')]} 15:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00:24 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 0x106822b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106825768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f877b4686d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106600d30>, '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 0x106527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065f5be8>, '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.0pTqGxV5YK/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.K0zVTOo7qg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:00:24 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 15:00:24 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 15:00:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0pTqGxV5YK/Render', 15:00:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:24 INFO - 'HOME': '/Users/cltbld', 15:00:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:24 INFO - 'LOGNAME': 'cltbld', 15:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:24 INFO - 'MOZ_NO_REMOTE': '1', 15:00:24 INFO - 'NO_EM_RESTART': '1', 15:00:24 INFO - 'PAGER': '/bin/cat', 15:00:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:24 INFO - 'PWD': '/builds/slave/test', 15:00:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:24 INFO - 'SHELL': '/bin/bash', 15:00:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K0zVTOo7qg/Listeners', 15:00:24 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:00:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:24 INFO - 'USER': 'cltbld', 15:00:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:24 INFO - 'XPC_FLAGS': '0x0', 15:00:24 INFO - 'XPC_SERVICE_NAME': '0', 15:00:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:24 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)) 15:00:24 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)) 15:00:24 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)) 15:00:24 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)) 15:00:24 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)) 15:00:24 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)) 15:00:24 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)) 15:00:24 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)) 15:00:24 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)) 15:00:24 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)) 15:00:24 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)) 15:00:24 INFO - Cleaning up... 15:00:24 INFO - Return code: 0 15:00:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:00: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')]} 15:00:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:00: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')]} 15:00:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:00: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']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106822b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106825768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f877b4686d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106600d30>, '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 0x106527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065f5be8>, '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.0pTqGxV5YK/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.K0zVTOo7qg/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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 15:00: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'] in /builds/slave/test/build 15:00: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 15:00:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0pTqGxV5YK/Render', 15:00:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:24 INFO - 'HOME': '/Users/cltbld', 15:00:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:24 INFO - 'LOGNAME': 'cltbld', 15:00:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:24 INFO - 'MOZ_NO_REMOTE': '1', 15:00:24 INFO - 'NO_EM_RESTART': '1', 15:00:24 INFO - 'PAGER': '/bin/cat', 15:00:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:24 INFO - 'PWD': '/builds/slave/test', 15:00:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:24 INFO - 'SHELL': '/bin/bash', 15:00:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K0zVTOo7qg/Listeners', 15:00:24 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:00:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:24 INFO - 'USER': 'cltbld', 15:00:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:24 INFO - 'XPC_FLAGS': '0x0', 15:00:24 INFO - 'XPC_SERVICE_NAME': '0', 15:00:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:00:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:00:24 INFO - Cleaning up... 15:00:24 INFO - Return code: 0 15:00:24 INFO - Running post-action listener: _resource_record_post_action 15:00:24 INFO - Running post-action listener: _start_resource_monitoring 15:00:24 INFO - Starting resource monitoring. 15:00:24 INFO - [mozharness: 2017-06-05 22:00:24.742079Z] Finished create-virtualenv step (success) 15:00:24 INFO - [mozharness: 2017-06-05 22:00:24.743073Z] Running install step. 15:00:24 INFO - Running pre-action listener: _resource_record_pre_action 15:00:24 INFO - Running main action method: install 15:00:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:00:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:00:25 INFO - Reading from file tmpfile_stdout 15:00:25 INFO - Detecting whether we're running mozinstall >=1.0... 15:00:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:00:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:00:25 INFO - Reading from file tmpfile_stdout 15:00:25 INFO - Output received: 15:00:25 INFO - Usage: mozinstall [options] installer 15:00:25 INFO - Options: 15:00:25 INFO - -h, --help show this help message and exit 15:00:25 INFO - -d DEST, --destination=DEST 15:00:25 INFO - Directory to install application into. [default: 15:00:25 INFO - "/builds/slave/test"] 15:00:25 INFO - --app=APP Application being installed. [default: firefox] 15:00:25 INFO - mkdir: /builds/slave/test/build/application 15:00:25 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'] 15:00:25 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 15:00:40 INFO - Reading from file tmpfile_stdout 15:00:40 INFO - Output received: 15:00:40 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:00:40 INFO - Running post-action listener: _resource_record_post_action 15:00:40 INFO - [mozharness: 2017-06-05 22:00:40.474919Z] Finished install step (success) 15:00:40 INFO - [mozharness: 2017-06-05 22:00:40.475054Z] Running run-tests step. 15:00:40 INFO - Running pre-action listener: _resource_record_pre_action 15:00:40 INFO - Running main action method: run_tests 15:00:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:00:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:00:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:00:40 INFO - 2017-06-05 15:00:40.491 screenresolution[2325:25485] starting screenresolution argv=screenresolution get 15:00:40 INFO - 2017-06-05 15:00:40.494 screenresolution[2325:25485] Display 0: 1600x1200x32@60 15:00:40 INFO - 2017-06-05 15:00:40.500 screenresolution[2326:25493] starting screenresolution argv=screenresolution list 15:00:40 INFO - Available Modes on Display 0 15:00:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:00:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:00:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:00:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:00:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:00:40 INFO - Intel Iris: 15:00:40 INFO - Chipset Model: Intel Iris 15:00:40 INFO - Type: GPU 15:00:40 INFO - Bus: Built-In 15:00:40 INFO - VRAM (Dynamic, Max): 1536 MB 15:00:40 INFO - Vendor: Intel (0x8086) 15:00:40 INFO - Device ID: 0x0a2e 15:00:40 INFO - Revision ID: 0x0009 15:00:40 INFO - Displays: 15:00:40 INFO - Display: 15:00:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:00:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:00:40 INFO - Display Serial Number: 1600x1200 60 15:00:40 INFO - Main Display: Yes 15:00:40 INFO - Mirror: Off 15:00:40 INFO - Online: Yes 15:00:40 INFO - Rotation: Supported 15:00:40 INFO - Return code: 0 15:00:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:00:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:00:40 INFO - Python 2.7.3 15:00:40 INFO - Return code: 0 15:00:40 INFO - grabbing minidump binary from tooltool 15:00: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')]} 15:00:40 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106600d30>, '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 0x106527db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1065f5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:00:40 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:00:40 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 15:00:40 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:00:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:00:40 INFO - Return code: 0 15:00:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:00:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:00:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:00:40 INFO - ENV: RUST_BACKTRACE is now 1 15:00:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:00:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:00:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 15:00:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0034 --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 15:00:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0pTqGxV5YK/Render', 15:00:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:00:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:00:40 INFO - 'HOME': '/Users/cltbld', 15:00:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:00:40 INFO - 'LOGNAME': 'cltbld', 15:00:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:00:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:00:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:00:40 INFO - 'MOZ_NO_REMOTE': '1', 15:00:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:00:40 INFO - 'NO_EM_RESTART': '1', 15:00:40 INFO - 'PAGER': '/bin/cat', 15:00:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:00:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:00:40 INFO - 'PWD': '/builds/slave/test', 15:00:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:00:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:00:40 INFO - 'RUST_BACKTRACE': '1', 15:00:40 INFO - 'SHELL': '/bin/bash', 15:00:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.K0zVTOo7qg/Listeners', 15:00:40 INFO - 'TMPDIR': '/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/', 15:00:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:00:40 INFO - 'USER': 'cltbld', 15:00:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:00:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:00:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:00:40 INFO - 'XPC_FLAGS': '0x0', 15:00:40 INFO - 'XPC_SERVICE_NAME': '0', 15:00:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:00:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0034', '--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 15:00:41 INFO - mozversion application_buildid: 20170605125726 15:00:41 INFO - mozversion application_changeset: 97767088522e789dd04cf06b24478f052946d1c9 15:00:41 INFO - mozversion application_display_name: Firefox 15:00:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:00:41 INFO - mozversion application_name: Firefox 15:00:41 INFO - mozversion application_remotingname: firefox 15:00:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:00:41 INFO - mozversion application_vendor: Mozilla 15:00:41 INFO - mozversion application_version: 54.0 15:00:41 INFO - mozversion platform_buildid: 20170605125726 15:00:41 INFO - mozversion platform_changeset: 97767088522e789dd04cf06b24478f052946d1c9 15:00:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:00:41 INFO - mozversion platform_version: 54.0 15:00:41 INFO - using testdate: 1496700041 15:00:41 INFO - actual date: 1496700041 15:00:41 INFO - starting webserver on 'localhost:55315' 15:00:41 INFO - SUITE-START | Running 2 tests 15:00:41 INFO - TEST-START | dromaeo_css 15:00:41 INFO - operating with platform_type : mac_ 15:00:41 INFO - Initialising browser for dromaeo_css test... 15:00:41 INFO - TEST-INFO | started process 2336 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpBPWZew/profile http://localhost:55315/getInfo.html) 15:00:43 INFO - PROCESS | 2336 | __metrics Screen width/height:1600/1200 15:00:43 INFO - PROCESS | 2336 | colorDepth:24 15:00:43 INFO - PROCESS | 2336 | Browser inner width/height: 1024/689 15:00:43 INFO - PROCESS | 2336 | __metrics 15:00:43 INFO - PROCESS | 2336 | 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" 15:00:43 INFO - PROCESS | 2336 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:00:44 INFO - TEST-INFO | 2336: exit 0 15:00:44 INFO - Browser initialized. 15:00:44 INFO - Running cycle 1/1 for dromaeo_css test... 15:00:44 INFO - TEST-INFO | started process 2339 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpBPWZew/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:00:56 INFO - PROCESS | 2339 | RSS: Main: 165400576 15:00:56 INFO - PROCESS | 2339 | 15:03:59 INFO - PROCESS | 2339 | Cycle 1(1): loaded http://localhost:55315/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55315/tests/dromaeo/cssquery-ext.html) 15:04:01 INFO - PROCESS | 2339 | RSS: Main: 345251840 15:04:01 INFO - PROCESS | 2339 | 15:07:04 INFO - PROCESS | 2339 | Cycle 1(1): loaded http://localhost:55315/tests/dromaeo/cssquery-ext.html (next: http://localhost:55315/tests/dromaeo/cssquery-jquery.html) 15:07:05 INFO - PROCESS | 2339 | RSS: Main: 305934336 15:07:05 INFO - PROCESS | 2339 | 15:10:08 INFO - PROCESS | 2339 | Cycle 1(1): loaded http://localhost:55315/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55315/tests/dromaeo/cssquery-mootools.html) 15:10:08 INFO - PROCESS | 2339 | RSS: Main: 295682048 15:10:08 INFO - PROCESS | 2339 | 15:13:11 INFO - PROCESS | 2339 | Cycle 1(1): loaded http://localhost:55315/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55315/tests/dromaeo/cssquery-prototype.html) 15:13:11 INFO - PROCESS | 2339 | RSS: Main: 272830464 15:13:11 INFO - PROCESS | 2339 | 15:16:15 INFO - PROCESS | 2339 | Cycle 1(1): loaded http://localhost:55315/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55315/tests/dromaeo/cssquery-yui.html) 15:16:16 INFO - PROCESS | 2339 | RSS: Main: 267558912 15:16:16 INFO - PROCESS | 2339 | 15:19:19 INFO - PROCESS | 2339 | Cycle 1(1): loaded http://localhost:55315/tests/dromaeo/cssquery-yui.html (next: http://localhost:55315/tests/dromaeo/cssquery-dojo.html) 15:19:19 INFO - PROCESS | 2339 | RSS: Main: 369459200 15:19:19 INFO - PROCESS | 2339 | 15:19:19 INFO - PROCESS | 2339 | __start_tp_report 15:19:19 INFO - PROCESS | 2339 | _x_x_mozilla_page_load 15:19:19 INFO - PROCESS | 2339 | _x_x_mozilla_page_load_details 15:19:19 INFO - PROCESS | 2339 | |i|pagename|runs| 15:19:19 INFO - PROCESS | 2339 | |0;dojo.html;3044.1932887784733;3108.2695566541865;3136.2473006478453;3142.921426964327;3163.209197700575;5217.321748172737;5291.708956007418;5311.707856067915;5341.278927344807;5365.7585408656605;5305.65513241639;5306.575473962083;5341.759620817063;5376.569874410047;5385.192221166827;4819.373481447425;4826.87932801681;4850.636202284825;4886.902261954759;4930.309756634074;4675.4155730533685;4768.61851051915;4797.2324428091515;4829.927551086719;4974.328465657144;4887.0225954809;4887.462379138292;4914.459409464956;4928.827491037814;4943.8516844494725;4886.731229782361;4899.828506002308;4911.653186929558;4921;4948.826791062295;4635.768211589407;4680.976595117037;4822.517748225185;4861.173600487926;4877.3903262092235;5070.974645126751;5155.9484405155845;5227.26818245446;5234.816781412632;5244.108501554745;5938.406159384027;5940.465358117747;5950.34546199922;5970.104484327342;5976.880462390772;5886.23478947734;5916.142159386908;5918.348981612019;5962.07587823888;5986.2217911671205;5265.631405801634;5459.099248624012;5500.779968801283;5541.806036788734;5544.889102217973;5997.520198384119;6008.939910600925;6016.849578760479;6019.277686677539;6040.063790112539;5687.459691329317;5708.1723150143;5710.172025056344;5715.48560629537;5735.397783232784;5798.768049277982;5832.445917637818;5851.970740146272;5877.617954832922;5883.735231914574;5733.741981610838;5749.108888122348;5784.2769653793275;5801.477866991991;5813.128030795415;5796.014677504834;5815.941840581625;5832.941670583325;5908.379620139909;6029.547783916224;4782.665213435026;4797.18447863864;4815.879603009953;4834.589059929875;4864.318995340655;4241.172971270573;4290.957090429119;4295.355696645465;4311.8490852820005;4365.432493775852;5131.332926719502;5168.457311982263;5270.235815806764;5274.525292723597;5355.86610334737;5846.210761547221;5861.296644402699;5863.120531920161;5898.793542226002;5945.821625351246;4018.3369743991916;4993.151164302078;5123.333966584395;5213.7393130343335;5365.302510673588;5086.364204474441;5098.337216161875;5106.055379754757;5128.69227846323;5147.2279158126585;5275.287836142148;5839.416058394127;6017.699115044318;6018.638881667114;6030.6080104793045;5637.605367624227;5701.543876489788;5829.912551311649;5852.736626851717;5967.537953201493;6007.969960150172;6012.729427175875;6019.668918209541;6100.145979562776;6110.419510146529;5875.676837773964;5936.673482958478;5940.227770389822;5949.494292985145;5962.52299816005;5741.712914354349;5745.97127014379;5938.287405511193;5972.790952316648;5974.253218347707;5657;5706.6005379624685;5782.363939966516;5880.50015748649;5923.289205295392;5782.710864456844;5795.507381872588;5805.071188609802;5815.76736930518;5822.970885145547;4821.445533763662;5579.2746942897165;5657.953278643464;5697.7151142443545;5711.771529138788;4605.884852878705;5517.61376703643;5568.9443105568425;5731.598788084794;5885.705714714161;5453.209284653783;5657;5666.2350582670815;5680.034394153086;5721.828345149652 15:19:19 INFO - PROCESS | 2339 | |1;ext.html;692.799088264403;746.2910235276487;765.219476134343;830.2444775254515;847.2925107535216;28421.573676394844;40131.39802902955;40363;40428.393574096415;40474;41479;41634.37548436776;41657;41662.16675666485;41759.58240417595;7741.690332386726;9413.388129771578;9613;9615.278854085936;9626.663066792664;7664.38684905209;11209.327440353605;11422.543098276059;11672.474738636782;11710.121740869427;11459.140564457657;11493.425328733572;11506.884931150707;11528.365939873303;11574.363410012487;10428.530716117793;10845.752738435063;11449.255798373079;11461.312321260668;11483.311001339946;11111.388873611948;11149.832752508719;11230.606928757454;11241.83137252942;11243.156763242709;38461.84614461571;38672.80663596664;38770.80614596937;38772.642957496355;38775.22449550993;3793.8103094845146;3855.51806024247;3862.9227415451533;3888.844446222148;3926.293267211901;27282.5907611386;28687.282817929718;28692.282692932637;29200;29240.268993275127;3009.4432529982023;3117.0492999635103;3215.4051500472488;3218.7585931055264;3311.850966706504;311428.4428577843;311583;311604.8839511621;312322;312369;49995.250071248964;50180.74909625429;50229;50344.74827625838;50362.49637503578;51856.48143518592;51963.22055169175;51998.96002079937;52133;52227.21659175115;14751.557453276419;14873.70252594942;14875.776863347059;14905.9565830393;14929.029613075116;11404.97355238016;12801.61595152147;12808.551700690432;12815.359232038361;12819;10156.949215254024;10225.977402259863;11386.316820990767;11611.535538578531;11866.940665296619;4419.602235798793;4469.50235296791;5067.214581739836;5140.665856719302;5150;1014.1481155829139;1021.1677482851452;1039.6828967165088;1041.4116024446164;1050.3435352904435;26267;26701.599476007876;26929;27050.5942410864;27153.321166970985;1158.623447379605;1182.3260741377333;1195.7847587434346;1208.8911997920054;1230.4155526125198;12828.80756788649;13739.313034348243;13797.448102076005;13816.861831381557;13904;417299;417469;418388;419297;434798;44775.77612111918;45841.54158458373;46161.30758038632;53934.7303263489;58014.54963625943;1370.58882335301;1404.4382247101241;1408.338081101901;1423.9430422782973;1425.3158483927566;24585.754142458347;28847.56728649114;29146.27134321574;29633.703662963093;30083.69916300809;43796;43842.123157536036;43902;44013.779931100136;45072;444055.7797210993;455847;558161;570798;571072;33412;33646.49530257097;33702.662973370934;33747.156321091185;33844.83077584596;42376.78811605922;42858.14283714371;42952.78523607362;43162.2521522626;43977;29283.121506354844;29478.557821633123;29610.851945740134;30116;30166;28995.13014609565;29175.854120729262;29537;30025.399492010474;30231.395372091996;34581.82709086438;35357.823210883784;35837;35848;35985.460218096225;29496.705032949394;29527.866098745762;29806.850965745034;30164.698353017066;30391.696083038885;21812.89093554522;21864.890675547158;23681.64477532873;23884.761152388255;24111.517769644157 15:19:19 INFO - PROCESS | 2339 | |2;jquery.html;360.92781443711254;388.5415210052137;393.91727737175194;405.1855769902497;437.07776591392155;20601.690974635392;21693.566128677416;21765.2382166624;22119.115235390564;22506.32481025572;22684.65973010406;22717.54564908701;22783.886080569617;23202;23451.530969380645;6568.3760042795975;6932.445404367639;6951.131108611424;6959.30406959305;6966.79099627012;5819.7672093116225;6409.4551963083195;6480.157579514656;6537.93462065378;6616.0406741022525;6539.476841852664;6597.076409302702;6610.239822420435;6619.933800662004;6631.336866313378;6525.967370163143;6549.312322206171;6794.320567943216;6800.387965083166;6804.387605115514;6533.542652014361;6575.210974683021;6635.6682165892;6665.233498147727;6667.333266673294;26970.865145674343;27105.728942710713;27672.03147889814;27701;28129.015484458152;2773.736495032989;2900.2894290898653;2930.3260250142375;2983.3287510310006;3040.148758347644;15824.28790704421;15826.44607438734;16012.199390030451;16848.989060656277;17012.319507219698;1891.6945271012116;2019.1418647074934;2027.3107143571256;2037.8165965063067;2118.6610142377144;130261.34869325688;131845;134217;135558.6444135556;135682;19362.419127425917;19687;19765.60468790616;19931.60136797256;19969.101390437467;32429.35141297161;32506.187345316084;32554.674453255637;32569.18577035593;32576.837115814742;8737.956310218407;9258.67594634198;9707.369021013612;10096;10140;8660.35047371437;8683.52240626759;8866.290696744374;8884.37809353352;8891.4665120093;7434.104303402598;9056.864147037799;9171.037041110721;9284.814303713887;9311.813763724687;2824.56219286011;3647.325244829715;3653.3606618841595;3663.6885864701267;3670.3576874047467;703.3739971425435;737.5537799631146;749.2806905370905;771.9228077192236;777.9766607001799;20993.475163121046;22379.104835806713;22400.88799555992;22402.327930162122;22450.88774556117;829.2702421868717;836.8828364029041;837.8868852704928;843.0946061900028;845.9166338695421;9277.165055144935;11074.889251107546;11599.478023488964;11691.415429228504;11796.29222246668;358133.20933395164;494371;640325;680899;681298;114788;125275;141154;146032;153120;1141.680329507739;1174.8955981377649;1186.0867132308074;1191.0828661930439;1196.3061424373843;18713.90643046776;19643;19644.80355196487;19734.40796776099;19744.703829442857;61632.07551886815;62634.555099469966;62660.37339626728;63068;63710.04434933572;85779.5711021441;86650.56674716587;87004.56497717471;88350.11649883677;88915.55542222247;7768.61156942162;11185.552577896793;11555.537778488766;11591.478383472595;11668.94165529167;10727.624533141303;12427;12551.748965020832;12764.425600847799;12929.482820687068;7712.344450721752;10675.839862402225;11131.554737810397;11183;11573.305601663928;10193.847092293474;10228.948855255676;10232.795344093225;10530.631427899987;10536;11066.944665276622;11235.831462528233;11329.433528323716;11513.53945842157;11537.134714896249;11179.496922638338;11181.552737890393;11297.096232301561;11366.033887119356;11574.18980671345;10168.33905796121;10476.185761456578;11012.806166853165;11087.833682494607;11253.324800511995 15:19:19 INFO - PROCESS | 2339 | |3;mootools.html;1513.6367271854758;1536.907785532868;1547.4583895636529;1571.9528414147576;1574.6142195162183;10414.531346089425;11213.551457941692;11218.943905280472;11224.943875280622;11260.493277802498;8526.01950775661;9993.250506212027;10999.175061870352;11015.724606884833;11053.834192487118;2362.917297894574;2412.1557454890735;2412.577798885197;2412.7104747430335;2413.613821788514;2319.3158018384615;2374.1453076892303;2424.7090349158125;2426.3691440225493;2431.027588964411;2334.1947028275217;2383.6067048936907;2400.1719406804623;2421.1646981791255;2423.624338227569;2291.5302363015544;2310.260716570698;2353.1175809071597;2364.125273648752;2369.526094781042;2024.9696254556193;2291.564602725477;2312.6184179610514;2315.953680926372;2318.953620927589;10427.739306517322;11067.391293478855;11080.224384293102;11086.002259796576;11098.112151027899;5675;5877.147813567009;5882.264716910387;5884.970575147139;5901.557383196277;3425;3560.3235385276716;3595.7482976191677;3649.124210189562;3649.835757390924;1188.7800756860008;1190.2325047304746;1218.1838168427087;1243.347242697582;1245.4333278358324;13520.32398380077;13867.098638588459;14173.29133543329;15265.694686106326;15551.766723499157;4466.441694788152;4645.744484053342;4646.697964632355;4679.22792739201;4685.437747470325;10112.443815590066;11541.019013383788;11562.653120406401;11577.07383409342;11603.6518904433;4859.975700121548;4900.387451568554;4913.410390753133;4913.508649135129;4917.262410638361;4433.866983990485;5059.06407314648;5232.895342093137;5251.501107394792;5279.604029697711;3794.5446546414605;3821.4458903458844;3896.883093507199;3902.746321489094;3924.9803750981064;1019.4189312092185;1028.5320179318392;1038.0709265207747;1038.1072277841051;1045.4929359260752;740.7222291640635;829.929455996235;834.1741675740973;863;876.7063033883593;6300.653464059521;6306.810795676045;6414.775482858171;6415.358464153641;6629.977856753736;879.3976126353341;882.52343734384;887.9300442966211;892.1925657280171;922.6493932305815;3924.3524818404644;3966.662833659171;3967.484227050523;4101.118259574206;4102.794860256975;11044.447777611247;13795.379207935666;13907.652308692364;13934.790978135336;13938.442462301395;13745.793813092812;13757.38091785872;13785.448582056806;13800.654983625489;13801.654958626115;9798.75503112428;9808.607655693691;9834.164096051916;9994.500274986221;10062.74843128913;6253.812385628438;6373.6175829450385;6435.292117867125;6437.935620643921;6439.645819479786;11823.940880295544;12040.879591204326;12166.756664866476;12265.386730663253;12347.38263086824;10017.54921028541;10788.730281743019;12503;12554.623361299176;12569.057320700802;8178.550179740171;8245.546494942835;8245.95877020611;8336.249737523653;8382.119877413028;9979.60081596728;10147.898521014991;10167.491625418552;10173.28786984904;10185.592576296865;8501.19238672325;8658.610362533575;8702.1297870215;8713.52075635846;8727.738167854974;8417.284530814704;8428.747137585882;8509.276711479593;8515.701950431705;8569.657213711382;7667.386609071373;7816.452848300564;7820.726274580363;7821.530708157528;7844.294013538809;4378.715383500063;5001.974990125026;5583.022970980143;5611.382747897646;5847.356790753099;4888.242322440027;5697.6296540724725;5748.195252664714;5873.2658417697785;5913.368550994766 15:19:19 INFO - PROCESS | 2339 | |4;prototype.html;331.0713448776182;341.1267156080434;343.8710483568663;345.80807651753264;355.3585777671304;3745.9324092633606;3867.69058475322;3878.489966580246;3919.1965647042357;3955.22873039757;3447.913802154941;3517.9472307915403;3648.978286079895;3806.371948628473;3862.420636904466;3047.7351898961906;3174.365126974603;3372.7133193678565;3383.2387712764676;3440.9849094517053;3182.408795602199;3390.5761779777526;3405.3359594879007;3434.1414646338417;3449.3273811606746;2793.748562629363;3143.3380112169293;3150.967625881305;3207.807531548103;3312.5031245313276;3357.261402491448;3358.194033431959;3364.596248450177;3372.898813035625;3373.359061778279;3230.854611542486;3242.2977936583247;3247.6427592964756;3262.807292628984;3277.8197199154038;3691.335559599271;3713.9814300928592;3736.3373298882007;3769.3968964965748;3778.131029863147;3252.3983063133396;3700.6669399753882;3908.218356328717;3908.4137379393032;3917.5298964124486;3701.8519259229597;3857.2092720992127;3975.926499845026;4136.442659312988;4174.165166966619;3332.133645252228;3457.6542345765474;3606.134527713356;3633.3823250047553;3648.3615367310613;3793.6775374093236;4333.15503476819;4338.501072376685;4341.56872151593;4357.455318085271;3080.8305543194892;3363.6972672459597;3728.588341667344;3758.6805121564425;3767.3218820612005;3771.9434208486896;3781.300459415017;3814.809259537012;3836.2928496819204;3837.002379381381;3893.7079719021185;3950.2889479893897;3951.076203609366;3981.900452488653;4000.4999375078114;3855.151866589346;4144.854930077433;4190.392393102983;4192.874213773592;4234.809433575497;1151.8214676725122;1166.976660466786;1220.9755804883853;1292.7802273613509;1300.5708116321591;886.6630680341558;990.7869807991317;1004.3979852135832;1076.1337123615408;1084.9186311026704;3440.896773096761;3471.444568868969;3527.7530572859655;3550.2721942001826;3748.9315545069526;3534.91162720934;3866.6326698963553;4197.601227883346;4222.218889505451;4263.189993901211;3307.6361600223954;3727.5713292971654;3762.811859407019;3762.8118594070734;3825.101101241206;1994.8404127670042;2102.2221777941954;2151.967720484194;2166.458385403649;2166.620841352757;3829.7319187657154;3970.682345412418;4102.528209255914;4107.014316564003;4235.576442355801;3752.81235938202;3914.9608503915165;4203.726757760735;4334.739915605074;4347;3603.4234522475754;3947.9802600987387;4357.934630980538;4385.978070109629;4414.8808571885675;4255.87232383029;4265.42416773744;4267.359896015622;4267.957320426756;4296.76367799774;3690.084832981914;3951.4665520154413;3961.3661814110756;4229.830806767818;4245.299525578245;3861.8841434755877;4328.93506597395;4415.328726120651;4447.3329000650165;4466.5086840446875;3123.281645221566;3159.968400316059;3479.9560131960816;3487.494313324503;3528.5412896323314;4189.0574620710095;4265.398107154695;4290.699651024398;4293.270144075577;4308.396824444643;3622.293652737691;4217.852375166977;4223.260929337415;4239.321708526621;4246.44796176495;3930.253251882133;4130.05887233;4174.872784348148;4178.603032711888;4206.516250631216;3605.5673319201865;3799.924001519899;4055.1484188320783;4090.3455447128317;4095.5904409558807;3559.555055618048;3880.3791393376923;3894.468595413004;3974.3243648580387;4164.68764842644;3284.9835750821094;3763.7553559018575;3953.2291203215095;3990.18201268745;4056.634902858757 15:19:19 INFO - PROCESS | 2339 | |5;yui.html;718.2279050021228;743.0117943135626;773.8568364852503;824.1387749801457;841.9873701894472;15013;16678.749818752727;16678.83321166788;16717.414890478834;16720.33118675253;15180.468683596077;15233.77149342758;15355.385784568603;15358.539243822675;15444.22778861058;956.4070276428624;959.8752162218901;973.445136272325;978.1685567267801;987.8024395120968;987.7185002274354;987.7678745494803;988.0514705882362;989.376692683612;989.7228775942712;1031.437866362834;1033.085719138564;1036.6786296248188;1037.9065884070433;1041.234692501011;1028.598846449885;1030.1707125763794;1032;1032.334144476812;1033.5297439664935;766.0309708219107;768.0207735137689;768.50431471701;768.673313841615;768.9038870141233;16210.75683864743;16462.835371646368;16495.092769897652;16796.580085497837;17079.914600427044;12756;14115.223662698547;14159.292035398292;14221;14239.857601423957;11294;11660.242084264495;11848.229865058744;11897.226680265756;12033.458494367775;3690.6862916652117;3713.4801127842125;3737.9065523362133;3780.0549862534367;3878.204967981557;91010;99747;101416;109751;111024.4448777759;9735.26985476078;10065.748356291006;10312.484375781181;10456.385903524118;10653.9467302663;22953;22998.080076797076;23004.65493017606;23045.078196872273;23106.65340019901;7591.962040189874;7748.418868584766;7756.534845900581;7843.411744119214;7872.72445464406;4372;4495.213337665896;4514.616257618073;4521.276595744731;4711.199096153662;3394.032700680294;3509.2455122148863;3509.64903509647;3510.192655689155;3511.754177207571;618.6628287583278;624.3974564545235;650.1612919334007;653.3172834388076;655.0469067506766;529.4969778710241;624.978125765596;628.6605233174126;641.1888960465016;653.5555822040963;10100.797984040279;10212.642557510451;10331.586736530606;10517.42154181512;10714.839277410845;440.3504830375235;527.9815206467755;559.4377650461341;567.7076305702567;569.7834822767322;4680.204365257914;4837.37114175155;4865.391826021747;4877.829275975324;4891.241857512091;81315;94899.52550237205;96523;97220.51389743148;97369.5131524352;49777.50222497801;52307;62750;79314.20685793068;84038.57980710057;68568;68584;69030.96453553201;69542.30457695358;70655.29344706693;10440.68677939663;10719.088877445505;10968.341899486057;11066.557337706723;11129.332240065622;44971.029490429035;45450.54549454463;46561.767191163824;46912;47248;43836.780816095714;56823.71588142198;57118;57300;58103.12845307239;12459.688507787378;12524.311162886128;12677;13071.411786469822;13251.80122298147;12061.75876482483;14456.85543144555;14484.203368814817;14748.336324865193;15373.3082011312;12616.369181540702;12808.871911280768;13821.861781382058;14035.719285614436;14280.471717762664;12947.417366218731;13309.341730394284;13386.19682819034;13516.662083447993;13883.167009979434;12769.872301276868;13529;13568.253746044062;13708.3831227593;14007.276764912875;9408.717738467856;9752.744535654181;9773;9774.706758797247;9904.25718071162;10396.532156053147;11068.35898460917;11173.385463799244;11193.27243729155;11211.943940280573 15:19:19 INFO - PROCESS | 2339 | __end_tp_report 15:19:19 INFO - PROCESS | 2339 | __start_cc_report 15:19:19 INFO - PROCESS | 2339 | _x_x_mozilla_cycle_collect,1845 15:19:19 INFO - PROCESS | 2339 | __end_cc_report 15:19:19 INFO - PROCESS | 2339 | __startTimestamp1496701159941__endTimestamp 15:19:19 INFO - PROCESS | 2339 | 15:19:19 INFO - PROCESS | 2339 | ------- Summary: start ------- 15:19:19 INFO - PROCESS | 2339 | Number of tests: 6 15:19:19 INFO - PROCESS | 2339 | 15:19:19 INFO - PROCESS | 2339 | [#0] dojo.html Cycles:165 Average:5387.44 Median:5637.61 stddev:627.14 (11.1%) stddev-sans-first:601.52 15:19:19 INFO - PROCESS | 2339 | Values: 3044.2 3108.3 3136.2 3142.9 3163.2 5217.3 5291.7 5311.7 5341.3 5365.8 5305.7 5306.6 5341.8 5376.6 5385.2 4819.4 4826.9 4850.6 4886.9 4930.3 4675.4 4768.6 4797.2 4829.9 4974.3 4887.0 4887.5 4914.5 4928.8 4943.9 4886.7 4899.8 4911.7 4921.0 4948.8 4635.8 4681.0 4822.5 4861.2 4877.4 5071.0 5155.9 5227.3 5234.8 5244.1 5938.4 5940.5 5950.3 5970.1 5976.9 5886.2 5916.1 5918.3 5962.1 5986.2 5265.6 5459.1 5500.8 5541.8 5544.9 5997.5 6008.9 6016.8 6019.3 6040.1 5687.5 5708.2 5710.2 5715.5 5735.4 5798.8 5832.4 5852.0 5877.6 5883.7 5733.7 5749.1 5784.3 5801.5 5813.1 5796.0 5815.9 5832.9 5908.4 6029.5 4782.7 4797.2 4815.9 4834.6 4864.3 4241.2 4291.0 4295.4 4311.8 4365.4 5131.3 5168.5 5270.2 5274.5 5355.9 5846.2 5861.3 5863.1 5898.8 5945.8 4018.3 4993.2 5123.3 5213.7 5365.3 5086.4 5098.3 5106.1 5128.7 5147.2 5275.3 5839.4 6017.7 6018.6 6030.6 5637.6 5701.5 5829.9 5852.7 5967.5 6008.0 6012.7 6019.7 6100.1 6110.4 5875.7 5936.7 5940.2 5949.5 5962.5 5741.7 5746.0 5938.3 5972.8 5974.3 5657.0 5706.6 5782.4 5880.5 5923.3 5782.7 5795.5 5805.1 5815.8 5823.0 4821.4 5579.3 5658.0 5697.7 5711.8 4605.9 5517.6 5568.9 5731.6 5885.7 5453.2 5657.0 5666.2 5680.0 5721.8 15:19:19 INFO - PROCESS | 2339 | 15:19:19 INFO - PROCESS | 2339 | [#1] ext.html Cycles:180 Average:55508.19 Median:170910.07 stddev:113745.59 (66.6%) stddev-sans-first:113990.22 15:19:19 INFO - PROCESS | 2339 | Values: 692.8 746.3 765.2 830.2 847.3 28421.6 40131.4 40363.0 40428.4 40474.0 41479.0 41634.4 41657.0 41662.2 41759.6 7741.7 9413.4 9613.0 9615.3 9626.7 7664.4 11209.3 11422.5 11672.5 11710.1 11459.1 11493.4 11506.9 11528.4 11574.4 10428.5 10845.8 11449.3 11461.3 11483.3 11111.4 11149.8 11230.6 11241.8 11243.2 38461.8 38672.8 38770.8 38772.6 38775.2 3793.8 3855.5 3862.9 3888.8 3926.3 27282.6 28687.3 28692.3 29200.0 29240.3 3009.4 3117.0 3215.4 3218.8 3311.9 311428.4 311583.0 311604.9 312322.0 312369.0 49995.3 50180.7 50229.0 50344.7 50362.5 51856.5 51963.2 51999.0 52133.0 52227.2 14751.6 14873.7 14875.8 14906.0 14929.0 11405.0 12801.6 12808.6 12815.4 12819.0 10156.9 10226.0 11386.3 11611.5 11866.9 4419.6 4469.5 5067.2 5140.7 5150.0 1014.1 1021.2 1039.7 1041.4 1050.3 26267.0 26701.6 26929.0 27050.6 27153.3 1158.6 1182.3 1195.8 1208.9 1230.4 12828.8 13739.3 13797.4 13816.9 13904.0 417299.0 417469.0 418388.0 419297.0 434798.0 44775.8 45841.5 46161.3 53934.7 58014.5 1370.6 1404.4 1408.3 1423.9 1425.3 24585.8 28847.6 29146.3 29633.7 30083.7 43796.0 43842.1 43902.0 44013.8 45072.0 444055.8 455847.0 558161.0 570798.0 571072.0 33412.0 33646.5 33702.7 33747.2 33844.8 42376.8 42858.1 42952.8 43162.3 43977.0 29283.1 29478.6 29610.9 30116.0 30166.0 28995.1 29175.9 29537.0 30025.4 30231.4 34581.8 35357.8 35837.0 35848.0 35985.5 29496.7 29527.9 29806.9 30164.7 30391.7 21812.9 21864.9 23681.6 23884.8 24111.5 15:19:19 INFO - PROCESS | 2339 | 15:19:19 INFO - PROCESS | 2339 | [#2] jquery.html Cycles:180 Average:37040.59 Median:27388.88 stddev:98399.86 (359.3%) stddev-sans-first:98637.35 15:19:19 INFO - PROCESS | 2339 | Values: 360.9 388.5 393.9 405.2 437.1 20601.7 21693.6 21765.2 22119.1 22506.3 22684.7 22717.5 22783.9 23202.0 23451.5 6568.4 6932.4 6951.1 6959.3 6966.8 5819.8 6409.5 6480.2 6537.9 6616.0 6539.5 6597.1 6610.2 6619.9 6631.3 6526.0 6549.3 6794.3 6800.4 6804.4 6533.5 6575.2 6635.7 6665.2 6667.3 26970.9 27105.7 27672.0 27701.0 28129.0 2773.7 2900.3 2930.3 2983.3 3040.1 15824.3 15826.4 16012.2 16849.0 17012.3 1891.7 2019.1 2027.3 2037.8 2118.7 130261.3 131845.0 134217.0 135558.6 135682.0 19362.4 19687.0 19765.6 19931.6 19969.1 32429.4 32506.2 32554.7 32569.2 32576.8 8738.0 9258.7 9707.4 10096.0 10140.0 8660.4 8683.5 8866.3 8884.4 8891.5 7434.1 9056.9 9171.0 9284.8 9311.8 2824.6 3647.3 3653.4 3663.7 3670.4 703.4 737.6 749.3 771.9 778.0 20993.5 22379.1 22400.9 22402.3 22450.9 829.3 836.9 837.9 843.1 845.9 9277.2 11074.9 11599.5 11691.4 11796.3 358133.2 494371.0 640325.0 680899.0 681298.0 114788.0 125275.0 141154.0 146032.0 153120.0 1141.7 1174.9 1186.1 1191.1 1196.3 18713.9 19643.0 19644.8 19734.4 19744.7 61632.1 62634.6 62660.4 63068.0 63710.0 85779.6 86650.6 87004.6 88350.1 88915.6 7768.6 11185.6 11555.5 11591.5 11668.9 10727.6 12427.0 12551.7 12764.4 12929.5 7712.3 10675.8 11131.6 11183.0 11573.3 10193.8 10228.9 10232.8 10530.6 10536.0 11066.9 11235.8 11329.4 11513.5 11537.1 11179.5 11181.6 11297.1 11366.0 11574.2 10168.3 10476.2 11012.8 11087.8 11253.3 15:19:19 INFO - PROCESS | 2339 | 15:19:19 INFO - PROCESS | 2339 | [#3] mootools.html Cycles:180 Average:6531.96 Median:3622.44 stddev:4119.46 (113.7%) stddev-sans-first:4113.76 15:19:19 INFO - PROCESS | 2339 | Values: 1513.6 1536.9 1547.5 1572.0 1574.6 10414.5 11213.6 11218.9 11224.9 11260.5 8526.0 9993.3 10999.2 11015.7 11053.8 2362.9 2412.2 2412.6 2412.7 2413.6 2319.3 2374.1 2424.7 2426.4 2431.0 2334.2 2383.6 2400.2 2421.2 2423.6 2291.5 2310.3 2353.1 2364.1 2369.5 2025.0 2291.6 2312.6 2316.0 2319.0 10427.7 11067.4 11080.2 11086.0 11098.1 5675.0 5877.1 5882.3 5885.0 5901.6 3425.0 3560.3 3595.7 3649.1 3649.8 1188.8 1190.2 1218.2 1243.3 1245.4 13520.3 13867.1 14173.3 15265.7 15551.8 4466.4 4645.7 4646.7 4679.2 4685.4 10112.4 11541.0 11562.7 11577.1 11603.7 4860.0 4900.4 4913.4 4913.5 4917.3 4433.9 5059.1 5232.9 5251.5 5279.6 3794.5 3821.4 3896.9 3902.7 3925.0 1019.4 1028.5 1038.1 1038.1 1045.5 740.7 829.9 834.2 863.0 876.7 6300.7 6306.8 6414.8 6415.4 6630.0 879.4 882.5 887.9 892.2 922.6 3924.4 3966.7 3967.5 4101.1 4102.8 11044.4 13795.4 13907.7 13934.8 13938.4 13745.8 13757.4 13785.4 13800.7 13801.7 9798.8 9808.6 9834.2 9994.5 10062.7 6253.8 6373.6 6435.3 6437.9 6439.6 11823.9 12040.9 12166.8 12265.4 12347.4 10017.5 10788.7 12503.0 12554.6 12569.1 8178.6 8245.5 8246.0 8336.2 8382.1 9979.6 10147.9 10167.5 10173.3 10185.6 8501.2 8658.6 8702.1 8713.5 8727.7 8417.3 8428.7 8509.3 8515.7 8569.7 7667.4 7816.5 7820.7 7821.5 7844.3 4378.7 5002.0 5583.0 5611.4 5847.4 4888.2 5697.6 5748.2 5873.3 5913.4 15:19:19 INFO - PROCESS | 2339 | 15:19:19 INFO - PROCESS | 2339 | [#4] prototype.html Cycles:180 Average:3524.65 Median:3796.80 stddev:930.02 (24.5%) stddev-sans-first:901.21 15:19:19 INFO - PROCESS | 2339 | Values: 331.1 341.1 343.9 345.8 355.4 3745.9 3867.7 3878.5 3919.2 3955.2 3447.9 3517.9 3649.0 3806.4 3862.4 3047.7 3174.4 3372.7 3383.2 3441.0 3182.4 3390.6 3405.3 3434.1 3449.3 2793.7 3143.3 3151.0 3207.8 3312.5 3357.3 3358.2 3364.6 3372.9 3373.4 3230.9 3242.3 3247.6 3262.8 3277.8 3691.3 3714.0 3736.3 3769.4 3778.1 3252.4 3700.7 3908.2 3908.4 3917.5 3701.9 3857.2 3975.9 4136.4 4174.2 3332.1 3457.7 3606.1 3633.4 3648.4 3793.7 4333.2 4338.5 4341.6 4357.5 3080.8 3363.7 3728.6 3758.7 3767.3 3771.9 3781.3 3814.8 3836.3 3837.0 3893.7 3950.3 3951.1 3981.9 4000.5 3855.2 4144.9 4190.4 4192.9 4234.8 1151.8 1167.0 1221.0 1292.8 1300.6 886.7 990.8 1004.4 1076.1 1084.9 3440.9 3471.4 3527.8 3550.3 3748.9 3534.9 3866.6 4197.6 4222.2 4263.2 3307.6 3727.6 3762.8 3762.8 3825.1 1994.8 2102.2 2152.0 2166.5 2166.6 3829.7 3970.7 4102.5 4107.0 4235.6 3752.8 3915.0 4203.7 4334.7 4347.0 3603.4 3948.0 4357.9 4386.0 4414.9 4255.9 4265.4 4267.4 4268.0 4296.8 3690.1 3951.5 3961.4 4229.8 4245.3 3861.9 4328.9 4415.3 4447.3 4466.5 3123.3 3160.0 3480.0 3487.5 3528.5 4189.1 4265.4 4290.7 4293.3 4308.4 3622.3 4217.9 4223.3 4239.3 4246.4 3930.3 4130.1 4174.9 4178.6 4206.5 3605.6 3799.9 4055.1 4090.3 4095.6 3559.6 3880.4 3894.5 3974.3 4164.7 3285.0 3763.8 3953.2 3990.2 4056.6 15:19:19 INFO - PROCESS | 2339 | 15:19:19 INFO - PROCESS | 2339 | [#5] yui.html Cycles:180 Average:18894.12 Median:3510.97 stddev:25994.01 (740.4%) stddev-sans-first:26031.10 15:19:19 INFO - PROCESS | 2339 | Values: 718.2 743.0 773.9 824.1 842.0 15013.0 16678.7 16678.8 16717.4 16720.3 15180.5 15233.8 15355.4 15358.5 15444.2 956.4 959.9 973.4 978.2 987.8 987.7 987.8 988.1 989.4 989.7 1031.4 1033.1 1036.7 1037.9 1041.2 1028.6 1030.2 1032.0 1032.3 1033.5 766.0 768.0 768.5 768.7 768.9 16210.8 16462.8 16495.1 16796.6 17079.9 12756.0 14115.2 14159.3 14221.0 14239.9 11294.0 11660.2 11848.2 11897.2 12033.5 3690.7 3713.5 3737.9 3780.1 3878.2 91010.0 99747.0 101416.0 109751.0 111024.4 9735.3 10065.7 10312.5 10456.4 10653.9 22953.0 22998.1 23004.7 23045.1 23106.7 7592.0 7748.4 7756.5 7843.4 7872.7 4372.0 4495.2 4514.6 4521.3 4711.2 3394.0 3509.2 3509.6 3510.2 3511.8 618.7 624.4 650.2 653.3 655.0 529.5 625.0 628.7 641.2 653.6 10100.8 10212.6 10331.6 10517.4 10714.8 440.4 528.0 559.4 567.7 569.8 4680.2 4837.4 4865.4 4877.8 4891.2 81315.0 94899.5 96523.0 97220.5 97369.5 49777.5 52307.0 62750.0 79314.2 84038.6 68568.0 68584.0 69031.0 69542.3 70655.3 10440.7 10719.1 10968.3 11066.6 11129.3 44971.0 45450.5 46561.8 46912.0 47248.0 43836.8 56823.7 57118.0 57300.0 58103.1 12459.7 12524.3 12677.0 13071.4 13251.8 12061.8 14456.9 14484.2 14748.3 15373.3 12616.4 12808.9 13821.9 14035.7 14280.5 12947.4 13309.3 13386.2 13516.7 13883.2 12769.9 13529.0 13568.3 13708.4 14007.3 9408.7 9752.7 9773.0 9774.7 9904.3 10396.5 11068.4 11173.4 11193.3 11211.9 15:19:19 INFO - PROCESS | 2339 | -------- Summary: end -------- 15:19:19 INFO - PROCESS | 2339 | 15:19:20 INFO - TEST-INFO | 2339: exit 0 15:19:20 INFO - TEST-OK | dromaeo_css | took 1119440ms 15:19:20 INFO - TEST-START | kraken 15:19:20 INFO - operating with platform_type : mac_ 15:19:20 INFO - Initialising browser for kraken test... 15:19:20 INFO - TEST-INFO | started process 2343 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpcyKfw6/profile http://localhost:55315/getInfo.html) 15:19:22 INFO - PROCESS | 2343 | __metrics Screen width/height:1600/1200 15:19:22 INFO - PROCESS | 2343 | colorDepth:24 15:19:22 INFO - PROCESS | 2343 | Browser inner width/height: 1024/689 15:19:22 INFO - PROCESS | 2343 | __metrics 15:19:23 INFO - PROCESS | 2343 | 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" 15:19:23 INFO - PROCESS | 2343 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:19:23 INFO - TEST-INFO | 2343: exit 0 15:19:23 INFO - Browser initialized. 15:19:23 INFO - Running cycle 1/1 for kraken test... 15:19:23 INFO - TEST-INFO | started process 2345 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/T/tmpcyKfw6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:19:36 INFO - PROCESS | 2345 | RSS: Main: 165117952 15:19:36 INFO - PROCESS | 2345 | 15:19:38 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:55315/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55315/tests/kraken/driver.html?testName=audio-beat-detection) 15:19:39 INFO - PROCESS | 2345 | RSS: Main: 215900160 15:19:39 INFO - PROCESS | 2345 | 15:19:42 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:55315/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55315/tests/kraken/driver.html?testName=audio-dft) 15:19:42 INFO - PROCESS | 2345 | RSS: Main: 231452672 15:19:42 INFO - PROCESS | 2345 | 15:19:46 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:55315/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55315/tests/kraken/driver.html?testName=audio-fft) 15:19:47 INFO - PROCESS | 2345 | RSS: Main: 322301952 15:19:47 INFO - PROCESS | 2345 | 15:19:49 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:55315/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55315/tests/kraken/driver.html?testName=audio-oscillator) 15:19:49 INFO - PROCESS | 2345 | RSS: Main: 226177024 15:19:49 INFO - PROCESS | 2345 | 15:19:51 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:55315/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55315/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:19:52 INFO - PROCESS | 2345 | RSS: Main: 229744640 15:19:52 INFO - PROCESS | 2345 | 15:19:55 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:55315/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55315/tests/kraken/driver.html?testName=imaging-darkroom) 15:19:56 INFO - PROCESS | 2345 | RSS: Main: 270241792 15:19:56 INFO - PROCESS | 2345 | 15:19:59 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:55315/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55315/tests/kraken/driver.html?testName=imaging-desaturate) 15:19:59 INFO - PROCESS | 2345 | RSS: Main: 272113664 15:19:59 INFO - PROCESS | 2345 | 15:20:02 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:55315/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55315/tests/kraken/driver.html?testName=json-parse-financial) 15:20:03 INFO - PROCESS | 2345 | RSS: Main: 267681792 15:20:03 INFO - PROCESS | 2345 | 15:20:05 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:55315/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55315/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:20:05 INFO - PROCESS | 2345 | RSS: Main: 246185984 15:20:05 INFO - PROCESS | 2345 | 15:20:07 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:55315/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55315/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:20:08 INFO - PROCESS | 2345 | RSS: Main: 250114048 15:20:08 INFO - PROCESS | 2345 | 15:20:10 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:55315/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55315/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:20:11 INFO - PROCESS | 2345 | RSS: Main: 252289024 15:20:11 INFO - PROCESS | 2345 | 15:20:14 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:55315/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55315/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:20:15 INFO - PROCESS | 2345 | RSS: Main: 270024704 15:20:15 INFO - PROCESS | 2345 | 15:20:18 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:55315/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55315/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:20:18 INFO - PROCESS | 2345 | RSS: Main: 264257536 15:20:18 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | Cycle 1(1): loaded http://localhost:55315/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55315/tests/kraken/driver.html?testName=ai-astar) 15:20:20 INFO - PROCESS | 2345 | RSS: Main: 308551680 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | __start_tp_report 15:20:20 INFO - PROCESS | 2345 | _x_x_mozilla_page_load 15:20:20 INFO - PROCESS | 2345 | _x_x_mozilla_page_load_details 15:20:20 INFO - PROCESS | 2345 | |i|pagename|runs| 15:20:20 INFO - PROCESS | 2345 | |0;ai-astar;110;120;122;118;119;122;120;120;120;120 15:20:20 INFO - PROCESS | 2345 | |1;audio-beat-detection;151;114;114;153;114;114;111;112;133;115 15:20:20 INFO - PROCESS | 2345 | |2;audio-dft;214;212;215;213;215;216;214;220;294;211 15:20:20 INFO - PROCESS | 2345 | |3;audio-fft;84;64;65;94;64;61;60;61;78;62 15:20:20 INFO - PROCESS | 2345 | |4;audio-oscillator;94;73;87;71;72;69;84;69;70;85 15:20:20 INFO - PROCESS | 2345 | |5;imaging-gaussian-blur;90;90;90;90;90;90;94;89;88;90 15:20:20 INFO - PROCESS | 2345 | |6;imaging-darkroom;104;97;97;97;97;97;98;101;96;96 15:20:20 INFO - PROCESS | 2345 | |7;imaging-desaturate;100;100;101;100;101;103;99;100;101;100 15:20:20 INFO - PROCESS | 2345 | |8;json-parse-financial;59;61;59;63;64;64;65;63;65;65 15:20:20 INFO - PROCESS | 2345 | |9;json-stringify-tinderbox;57;68;67;65;65;62;63;64;67;65 15:20:20 INFO - PROCESS | 2345 | |10;stanford-crypto-aes;89;85;86;85;74;85;76;91;75;87 15:20:20 INFO - PROCESS | 2345 | |11;stanford-crypto-ccm;163;164;163;148;142;144;176;152;187;173 15:20:20 INFO - PROCESS | 2345 | |12;stanford-crypto-pbkdf2;169;167;169;167;169;178;171;169;169;170 15:20:20 INFO - PROCESS | 2345 | |13;stanford-crypto-sha256-iterative;66;67;68;68;67;67;65;66;64;66 15:20:20 INFO - PROCESS | 2345 | __end_tp_report 15:20:20 INFO - PROCESS | 2345 | __start_cc_report 15:20:20 INFO - PROCESS | 2345 | _x_x_mozilla_cycle_collect,2205 15:20:20 INFO - PROCESS | 2345 | __end_cc_report 15:20:20 INFO - PROCESS | 2345 | __startTimestamp1496701220789__endTimestamp 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | ------- Summary: start ------- 15:20:20 INFO - PROCESS | 2345 | Number of tests: 14 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | [#0] ai-astar Cycles:10 Average:119.10 Median:120.00 stddev:3.41 (2.8%) stddev-sans-first:1.27 15:20:20 INFO - PROCESS | 2345 | Values: 110.0 120.0 122.0 118.0 119.0 122.0 120.0 120.0 120.0 120.0 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | [#1] audio-beat-detection Cycles:10 Average:123.10 Median:114.50 stddev:16.45 (14.4%) stddev-sans-first:14.02 15:20:20 INFO - PROCESS | 2345 | Values: 151.0 114.0 114.0 153.0 114.0 114.0 111.0 112.0 133.0 115.0 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | [#2] audio-dft Cycles:10 Average:222.40 Median:215.00 stddev:25.28 (11.8%) stddev-sans-first:26.63 15:20:20 INFO - PROCESS | 2345 | Values: 214.0 212.0 215.0 213.0 215.0 216.0 214.0 220.0 294.0 211.0 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | [#3] audio-fft Cycles:10 Average:69.30 Median:64.50 stddev:11.80 (18.3%) stddev-sans-first:11.26 15:20:20 INFO - PROCESS | 2345 | Values: 84.0 64.0 65.0 94.0 64.0 61.0 60.0 61.0 78.0 62.0 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:78.50 stddev:9.16 (11.7%) stddev-sans-first:7.49 15:20:20 INFO - PROCESS | 2345 | Values: 94.0 73.0 87.0 71.0 72.0 69.0 84.0 69.0 70.0 85.0 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | [#5] imaging-gaussian-blur Cycles:10 Average:90.10 Median:90.00 stddev:1.52 (1.7%) stddev-sans-first:1.62 15:20:20 INFO - PROCESS | 2345 | Values: 90.0 90.0 90.0 90.0 90.0 90.0 94.0 89.0 88.0 90.0 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | [#6] imaging-darkroom Cycles:10 Average:98.00 Median:97.00 stddev:2.54 (2.6%) stddev-sans-first:1.50 15:20:20 INFO - PROCESS | 2345 | Values: 104.0 97.0 97.0 97.0 97.0 97.0 98.0 101.0 96.0 96.0 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | [#7] imaging-desaturate Cycles:10 Average:100.50 Median:101.00 stddev:1.08 (1.1%) stddev-sans-first:1.13 15:20:20 INFO - PROCESS | 2345 | Values: 100.0 100.0 101.0 100.0 101.0 103.0 99.0 100.0 101.0 100.0 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | [#8] json-parse-financial Cycles:10 Average:62.80 Median:64.00 stddev:2.35 (3.7%) stddev-sans-first:2.05 15:20:20 INFO - PROCESS | 2345 | Values: 59.0 61.0 59.0 63.0 64.0 64.0 65.0 63.0 65.0 65.0 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | [#9] json-stringify-tinderbox Cycles:10 Average:64.30 Median:65.00 stddev:3.16 (4.9%) stddev-sans-first:1.96 15:20:20 INFO - PROCESS | 2345 | Values: 57.0 68.0 67.0 65.0 65.0 62.0 63.0 64.0 67.0 65.0 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:85.50 stddev:6.06 (7.1%) stddev-sans-first:6.06 15:20:20 INFO - PROCESS | 2345 | Values: 89.0 85.0 86.0 85.0 74.0 85.0 76.0 91.0 75.0 87.0 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | [#11] stanford-crypto-ccm Cycles:10 Average:161.20 Median:163.50 stddev:14.76 (9.0%) stddev-sans-first:15.64 15:20:20 INFO - PROCESS | 2345 | Values: 163.0 164.0 163.0 148.0 142.0 144.0 176.0 152.0 187.0 173.0 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.80 Median:169.00 stddev:3.12 (1.8%) stddev-sans-first:3.30 15:20:20 INFO - PROCESS | 2345 | Values: 169.0 167.0 169.0 167.0 169.0 178.0 171.0 169.0 169.0 170.0 15:20:20 INFO - PROCESS | 2345 | 15:20:20 INFO - PROCESS | 2345 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:1.26 (1.9%) stddev-sans-first:1.33 15:20:20 INFO - PROCESS | 2345 | Values: 66.0 67.0 68.0 68.0 67.0 67.0 65.0 66.0 64.0 66.0 15:20:20 INFO - PROCESS | 2345 | -------- Summary: end -------- 15:20:20 INFO - PROCESS | 2345 | 15:20:21 INFO - TEST-INFO | 2345: exit 0 15:20:21 INFO - TEST-OK | kraken | took 61181ms 15:20:21 INFO - SUITE-END | took 1180s 15:20:21 INFO - Completed test suite (00:19:40) 15:20:21 INFO - javascript benchmark 15:20:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7157.479342538238, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3044.1932887784733, 3108.2695566541865, 3136.2473006478453, 3142.921426964327, 3163.209197700575, 5217.321748172737, 5291.708956007418, 5311.707856067915, 5341.278927344807, 5365.7585408656605, 5305.65513241639, 5306.575473962083, 5341.759620817063, 5376.569874410047, 5385.192221166827, 4819.373481447425, 4826.87932801681, 4850.636202284825, 4886.902261954759, 4930.309756634074, 4675.4155730533685, 4768.61851051915, 4797.2324428091515, 4829.927551086719, 4974.328465657144, 4887.0225954809, 4887.462379138292, 4914.459409464956, 4928.827491037814, 4943.8516844494725, 4886.731229782361, 4899.828506002308, 4911.653186929558, 4921.0, 4948.826791062295, 4635.768211589407, 4680.976595117037, 4822.517748225185, 4861.173600487926, 4877.3903262092235, 5070.974645126751, 5155.9484405155845, 5227.26818245446, 5234.816781412632, 5244.108501554745, 5938.406159384027, 5940.465358117747, 5950.34546199922, 5970.104484327342, 5976.880462390772, 5886.23478947734, 5916.142159386908, 5918.348981612019, 5962.07587823888, 5986.2217911671205, 5265.631405801634, 5459.099248624012, 5500.779968801283, 5541.806036788734, 5544.889102217973, 5997.520198384119, 6008.939910600925, 6016.849578760479, 6019.277686677539, 6040.063790112539, 5687.459691329317, 5708.1723150143, 5710.172025056344, 5715.48560629537, 5735.397783232784, 5798.768049277982, 5832.445917637818, 5851.970740146272, 5877.617954832922, 5883.735231914574, 5733.741981610838, 5749.108888122348, 5784.2769653793275, 5801.477866991991, 5813.128030795415, 5796.014677504834, 5815.941840581625, 5832.941670583325, 5908.379620139909, 6029.547783916224, 4782.665213435026, 4797.18447863864, 4815.879603009953, 4834.589059929875, 4864.318995340655, 4241.172971270573, 4290.957090429119, 4295.355696645465, 4311.8490852820005, 4365.432493775852, 5131.332926719502, 5168.457311982263, 5270.235815806764, 5274.525292723597, 5355.86610334737, 5846.210761547221, 5861.296644402699, 5863.120531920161, 5898.793542226002, 5945.821625351246, 4018.3369743991916, 4993.151164302078, 5123.333966584395, 5213.7393130343335, 5365.302510673588, 5086.364204474441, 5098.337216161875, 5106.055379754757, 5128.69227846323, 5147.2279158126585, 5275.287836142148, 5839.416058394127, 6017.699115044318, 6018.638881667114, 6030.6080104793045, 5637.605367624227, 5701.543876489788, 5829.912551311649, 5852.736626851717, 5967.537953201493, 6007.969960150172, 6012.729427175875, 6019.668918209541, 6100.145979562776, 6110.419510146529, 5875.676837773964, 5936.673482958478, 5940.227770389822, 5949.494292985145, 5962.52299816005, 5741.712914354349, 5745.97127014379, 5938.287405511193, 5972.790952316648, 5974.253218347707, 5657.0, 5706.6005379624685, 5782.363939966516, 5880.50015748649, 5923.289205295392, 5782.710864456844, 5795.507381872588, 5805.071188609802, 5815.76736930518, 5822.970885145547, 4821.445533763662, 5579.2746942897165, 5657.953278643464, 5697.7151142443545, 5711.771529138788, 4605.884852878705, 5517.61376703643, 5568.9443105568425, 5731.598788084794, 5885.705714714161, 5453.209284653783, 5657.0, 5666.2350582670815, 5680.034394153086, 5721.828345149652], "value": 5347.063928916563, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.799088264403, 746.2910235276487, 765.219476134343, 830.2444775254515, 847.2925107535216, 28421.573676394844, 40131.39802902955, 40363.0, 40428.393574096415, 40474.0, 41479.0, 41634.37548436776, 41657.0, 41662.16675666485, 41759.58240417595, 7741.690332386726, 9413.388129771578, 9613.0, 9615.278854085936, 9626.663066792664, 7664.38684905209, 11209.327440353605, 11422.543098276059, 11672.474738636782, 11710.121740869427, 11459.140564457657, 11493.425328733572, 11506.884931150707, 11528.365939873303, 11574.363410012487, 10428.530716117793, 10845.752738435063, 11449.255798373079, 11461.312321260668, 11483.311001339946, 11111.388873611948, 11149.832752508719, 11230.606928757454, 11241.83137252942, 11243.156763242709, 38461.84614461571, 38672.80663596664, 38770.80614596937, 38772.642957496355, 38775.22449550993, 3793.8103094845146, 3855.51806024247, 3862.9227415451533, 3888.844446222148, 3926.293267211901, 27282.5907611386, 28687.282817929718, 28692.282692932637, 29200.0, 29240.268993275127, 3009.4432529982023, 3117.0492999635103, 3215.4051500472488, 3218.7585931055264, 3311.850966706504, 311428.4428577843, 311583.0, 311604.8839511621, 312322.0, 312369.0, 49995.250071248964, 50180.74909625429, 50229.0, 50344.74827625838, 50362.49637503578, 51856.48143518592, 51963.22055169175, 51998.96002079937, 52133.0, 52227.21659175115, 14751.557453276419, 14873.70252594942, 14875.776863347059, 14905.9565830393, 14929.029613075116, 11404.97355238016, 12801.61595152147, 12808.551700690432, 12815.359232038361, 12819.0, 10156.949215254024, 10225.977402259863, 11386.316820990767, 11611.535538578531, 11866.940665296619, 4419.602235798793, 4469.50235296791, 5067.214581739836, 5140.665856719302, 5150.0, 1014.1481155829139, 1021.1677482851452, 1039.6828967165088, 1041.4116024446164, 1050.3435352904435, 26267.0, 26701.599476007876, 26929.0, 27050.5942410864, 27153.321166970985, 1158.623447379605, 1182.3260741377333, 1195.7847587434346, 1208.8911997920054, 1230.4155526125198, 12828.80756788649, 13739.313034348243, 13797.448102076005, 13816.861831381557, 13904.0, 417299.0, 417469.0, 418388.0, 419297.0, 434798.0, 44775.77612111918, 45841.54158458373, 46161.30758038632, 53934.7303263489, 58014.54963625943, 1370.58882335301, 1404.4382247101241, 1408.338081101901, 1423.9430422782973, 1425.3158483927566, 24585.754142458347, 28847.56728649114, 29146.27134321574, 29633.703662963093, 30083.69916300809, 43796.0, 43842.123157536036, 43902.0, 44013.779931100136, 45072.0, 444055.7797210993, 455847.0, 558161.0, 570798.0, 571072.0, 33412.0, 33646.49530257097, 33702.662973370934, 33747.156321091185, 33844.83077584596, 42376.78811605922, 42858.14283714371, 42952.78523607362, 43162.2521522626, 43977.0, 29283.121506354844, 29478.557821633123, 29610.851945740134, 30116.0, 30166.0, 28995.13014609565, 29175.854120729262, 29537.0, 30025.399492010474, 30231.395372091996, 34581.82709086438, 35357.823210883784, 35837.0, 35848.0, 35985.460218096225, 29496.705032949394, 29527.866098745762, 29806.850965745034, 30164.698353017066, 30391.696083038885, 21812.89093554522, 21864.890675547158, 23681.64477532873, 23884.761152388255, 24111.517769644157], "value": 18619.933280010948, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.92781443711254, 388.5415210052137, 393.91727737175194, 405.1855769902497, 437.07776591392155, 20601.690974635392, 21693.566128677416, 21765.2382166624, 22119.115235390564, 22506.32481025572, 22684.65973010406, 22717.54564908701, 22783.886080569617, 23202.0, 23451.530969380645, 6568.3760042795975, 6932.445404367639, 6951.131108611424, 6959.30406959305, 6966.79099627012, 5819.7672093116225, 6409.4551963083195, 6480.157579514656, 6537.93462065378, 6616.0406741022525, 6539.476841852664, 6597.076409302702, 6610.239822420435, 6619.933800662004, 6631.336866313378, 6525.967370163143, 6549.312322206171, 6794.320567943216, 6800.387965083166, 6804.387605115514, 6533.542652014361, 6575.210974683021, 6635.6682165892, 6665.233498147727, 6667.333266673294, 26970.865145674343, 27105.728942710713, 27672.03147889814, 27701.0, 28129.015484458152, 2773.736495032989, 2900.2894290898653, 2930.3260250142375, 2983.3287510310006, 3040.148758347644, 15824.28790704421, 15826.44607438734, 16012.199390030451, 16848.989060656277, 17012.319507219698, 1891.6945271012116, 2019.1418647074934, 2027.3107143571256, 2037.8165965063067, 2118.6610142377144, 130261.34869325688, 131845.0, 134217.0, 135558.6444135556, 135682.0, 19362.419127425917, 19687.0, 19765.60468790616, 19931.60136797256, 19969.101390437467, 32429.35141297161, 32506.187345316084, 32554.674453255637, 32569.18577035593, 32576.837115814742, 8737.956310218407, 9258.67594634198, 9707.369021013612, 10096.0, 10140.0, 8660.35047371437, 8683.52240626759, 8866.290696744374, 8884.37809353352, 8891.4665120093, 7434.104303402598, 9056.864147037799, 9171.037041110721, 9284.814303713887, 9311.813763724687, 2824.56219286011, 3647.325244829715, 3653.3606618841595, 3663.6885864701267, 3670.3576874047467, 703.3739971425435, 737.5537799631146, 749.2806905370905, 771.9228077192236, 777.9766607001799, 20993.475163121046, 22379.104835806713, 22400.88799555992, 22402.327930162122, 22450.88774556117, 829.2702421868717, 836.8828364029041, 837.8868852704928, 843.0946061900028, 845.9166338695421, 9277.165055144935, 11074.889251107546, 11599.478023488964, 11691.415429228504, 11796.29222246668, 358133.20933395164, 494371.0, 640325.0, 680899.0, 681298.0, 114788.0, 125275.0, 141154.0, 146032.0, 153120.0, 1141.680329507739, 1174.8955981377649, 1186.0867132308074, 1191.0828661930439, 1196.3061424373843, 18713.90643046776, 19643.0, 19644.80355196487, 19734.40796776099, 19744.703829442857, 61632.07551886815, 62634.555099469966, 62660.37339626728, 63068.0, 63710.04434933572, 85779.5711021441, 86650.56674716587, 87004.56497717471, 88350.11649883677, 88915.55542222247, 7768.61156942162, 11185.552577896793, 11555.537778488766, 11591.478383472595, 11668.94165529167, 10727.624533141303, 12427.0, 12551.748965020832, 12764.425600847799, 12929.482820687068, 7712.344450721752, 10675.839862402225, 11131.554737810397, 11183.0, 11573.305601663928, 10193.847092293474, 10228.948855255676, 10232.795344093225, 10530.631427899987, 10536.0, 11066.944665276622, 11235.831462528233, 11329.433528323716, 11513.53945842157, 11537.134714896249, 11179.496922638338, 11181.552737890393, 11297.096232301561, 11366.033887119356, 11574.18980671345, 10168.33905796121, 10476.185761456578, 11012.806166853165, 11087.833682494607, 11253.324800511995], "value": 11115.832176553222, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1513.6367271854758, 1536.907785532868, 1547.4583895636529, 1571.9528414147576, 1574.6142195162183, 10414.531346089425, 11213.551457941692, 11218.943905280472, 11224.943875280622, 11260.493277802498, 8526.01950775661, 9993.250506212027, 10999.175061870352, 11015.724606884833, 11053.834192487118, 2362.917297894574, 2412.1557454890735, 2412.577798885197, 2412.7104747430335, 2413.613821788514, 2319.3158018384615, 2374.1453076892303, 2424.7090349158125, 2426.3691440225493, 2431.027588964411, 2334.1947028275217, 2383.6067048936907, 2400.1719406804623, 2421.1646981791255, 2423.624338227569, 2291.5302363015544, 2310.260716570698, 2353.1175809071597, 2364.125273648752, 2369.526094781042, 2024.9696254556193, 2291.564602725477, 2312.6184179610514, 2315.953680926372, 2318.953620927589, 10427.739306517322, 11067.391293478855, 11080.224384293102, 11086.002259796576, 11098.112151027899, 5675.0, 5877.147813567009, 5882.264716910387, 5884.970575147139, 5901.557383196277, 3425.0, 3560.3235385276716, 3595.7482976191677, 3649.124210189562, 3649.835757390924, 1188.7800756860008, 1190.2325047304746, 1218.1838168427087, 1243.347242697582, 1245.4333278358324, 13520.32398380077, 13867.098638588459, 14173.29133543329, 15265.694686106326, 15551.766723499157, 4466.441694788152, 4645.744484053342, 4646.697964632355, 4679.22792739201, 4685.437747470325, 10112.443815590066, 11541.019013383788, 11562.653120406401, 11577.07383409342, 11603.6518904433, 4859.975700121548, 4900.387451568554, 4913.410390753133, 4913.508649135129, 4917.262410638361, 4433.866983990485, 5059.06407314648, 5232.895342093137, 5251.501107394792, 5279.604029697711, 3794.5446546414605, 3821.4458903458844, 3896.883093507199, 3902.746321489094, 3924.9803750981064, 1019.4189312092185, 1028.5320179318392, 1038.0709265207747, 1038.1072277841051, 1045.4929359260752, 740.7222291640635, 829.929455996235, 834.1741675740973, 863.0, 876.7063033883593, 6300.653464059521, 6306.810795676045, 6414.775482858171, 6415.358464153641, 6629.977856753736, 879.3976126353341, 882.52343734384, 887.9300442966211, 892.1925657280171, 922.6493932305815, 3924.3524818404644, 3966.662833659171, 3967.484227050523, 4101.118259574206, 4102.794860256975, 11044.447777611247, 13795.379207935666, 13907.652308692364, 13934.790978135336, 13938.442462301395, 13745.793813092812, 13757.38091785872, 13785.448582056806, 13800.654983625489, 13801.654958626115, 9798.75503112428, 9808.607655693691, 9834.164096051916, 9994.500274986221, 10062.74843128913, 6253.812385628438, 6373.6175829450385, 6435.292117867125, 6437.935620643921, 6439.645819479786, 11823.940880295544, 12040.879591204326, 12166.756664866476, 12265.386730663253, 12347.38263086824, 10017.54921028541, 10788.730281743019, 12503.0, 12554.623361299176, 12569.057320700802, 8178.550179740171, 8245.546494942835, 8245.95877020611, 8336.249737523653, 8382.119877413028, 9979.60081596728, 10147.898521014991, 10167.491625418552, 10173.28786984904, 10185.592576296865, 8501.19238672325, 8658.610362533575, 8702.1297870215, 8713.52075635846, 8727.738167854974, 8417.284530814704, 8428.747137585882, 8509.276711479593, 8515.701950431705, 8569.657213711382, 7667.386609071373, 7816.452848300564, 7820.726274580363, 7821.530708157528, 7844.294013538809, 4378.715383500063, 5001.974990125026, 5583.022970980143, 5611.382747897646, 5847.356790753099, 4888.242322440027, 5697.6296540724725, 5748.195252664714, 5873.2658417697785, 5913.368550994766], "value": 4973.248498340263, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.0713448776182, 341.1267156080434, 343.8710483568663, 345.80807651753264, 355.3585777671304, 3745.9324092633606, 3867.69058475322, 3878.489966580246, 3919.1965647042357, 3955.22873039757, 3447.913802154941, 3517.9472307915403, 3648.978286079895, 3806.371948628473, 3862.420636904466, 3047.7351898961906, 3174.365126974603, 3372.7133193678565, 3383.2387712764676, 3440.9849094517053, 3182.408795602199, 3390.5761779777526, 3405.3359594879007, 3434.1414646338417, 3449.3273811606746, 2793.748562629363, 3143.3380112169293, 3150.967625881305, 3207.807531548103, 3312.5031245313276, 3357.261402491448, 3358.194033431959, 3364.596248450177, 3372.898813035625, 3373.359061778279, 3230.854611542486, 3242.2977936583247, 3247.6427592964756, 3262.807292628984, 3277.8197199154038, 3691.335559599271, 3713.9814300928592, 3736.3373298882007, 3769.3968964965748, 3778.131029863147, 3252.3983063133396, 3700.6669399753882, 3908.218356328717, 3908.4137379393032, 3917.5298964124486, 3701.8519259229597, 3857.2092720992127, 3975.926499845026, 4136.442659312988, 4174.165166966619, 3332.133645252228, 3457.6542345765474, 3606.134527713356, 3633.3823250047553, 3648.3615367310613, 3793.6775374093236, 4333.15503476819, 4338.501072376685, 4341.56872151593, 4357.455318085271, 3080.8305543194892, 3363.6972672459597, 3728.588341667344, 3758.6805121564425, 3767.3218820612005, 3771.9434208486896, 3781.300459415017, 3814.809259537012, 3836.2928496819204, 3837.002379381381, 3893.7079719021185, 3950.2889479893897, 3951.076203609366, 3981.900452488653, 4000.4999375078114, 3855.151866589346, 4144.854930077433, 4190.392393102983, 4192.874213773592, 4234.809433575497, 1151.8214676725122, 1166.976660466786, 1220.9755804883853, 1292.7802273613509, 1300.5708116321591, 886.6630680341558, 990.7869807991317, 1004.3979852135832, 1076.1337123615408, 1084.9186311026704, 3440.896773096761, 3471.444568868969, 3527.7530572859655, 3550.2721942001826, 3748.9315545069526, 3534.91162720934, 3866.6326698963553, 4197.601227883346, 4222.218889505451, 4263.189993901211, 3307.6361600223954, 3727.5713292971654, 3762.811859407019, 3762.8118594070734, 3825.101101241206, 1994.8404127670042, 2102.2221777941954, 2151.967720484194, 2166.458385403649, 2166.620841352757, 3829.7319187657154, 3970.682345412418, 4102.528209255914, 4107.014316564003, 4235.576442355801, 3752.81235938202, 3914.9608503915165, 4203.726757760735, 4334.739915605074, 4347.0, 3603.4234522475754, 3947.9802600987387, 4357.934630980538, 4385.978070109629, 4414.8808571885675, 4255.87232383029, 4265.42416773744, 4267.359896015622, 4267.957320426756, 4296.76367799774, 3690.084832981914, 3951.4665520154413, 3961.3661814110756, 4229.830806767818, 4245.299525578245, 3861.8841434755877, 4328.93506597395, 4415.328726120651, 4447.3329000650165, 4466.5086840446875, 3123.281645221566, 3159.968400316059, 3479.9560131960816, 3487.494313324503, 3528.5412896323314, 4189.0574620710095, 4265.398107154695, 4290.699651024398, 4293.270144075577, 4308.396824444643, 3622.293652737691, 4217.852375166977, 4223.260929337415, 4239.321708526621, 4246.44796176495, 3930.253251882133, 4130.05887233, 4174.872784348148, 4178.603032711888, 4206.516250631216, 3605.5673319201865, 3799.924001519899, 4055.1484188320783, 4090.3455447128317, 4095.5904409558807, 3559.555055618048, 3880.3791393376923, 3894.468595413004, 3974.3243648580387, 4164.68764842644, 3284.9835750821094, 3763.7553559018575, 3953.2291203215095, 3990.18201268745, 4056.634902858757], "value": 3269.6929138227174, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.2279050021228, 743.0117943135626, 773.8568364852503, 824.1387749801457, 841.9873701894472, 15013.0, 16678.749818752727, 16678.83321166788, 16717.414890478834, 16720.33118675253, 15180.468683596077, 15233.77149342758, 15355.385784568603, 15358.539243822675, 15444.22778861058, 956.4070276428624, 959.8752162218901, 973.445136272325, 978.1685567267801, 987.8024395120968, 987.7185002274354, 987.7678745494803, 988.0514705882362, 989.376692683612, 989.7228775942712, 1031.437866362834, 1033.085719138564, 1036.6786296248188, 1037.9065884070433, 1041.234692501011, 1028.598846449885, 1030.1707125763794, 1032.0, 1032.334144476812, 1033.5297439664935, 766.0309708219107, 768.0207735137689, 768.50431471701, 768.673313841615, 768.9038870141233, 16210.75683864743, 16462.835371646368, 16495.092769897652, 16796.580085497837, 17079.914600427044, 12756.0, 14115.223662698547, 14159.292035398292, 14221.0, 14239.857601423957, 11294.0, 11660.242084264495, 11848.229865058744, 11897.226680265756, 12033.458494367775, 3690.6862916652117, 3713.4801127842125, 3737.9065523362133, 3780.0549862534367, 3878.204967981557, 91010.0, 99747.0, 101416.0, 109751.0, 111024.4448777759, 9735.26985476078, 10065.748356291006, 10312.484375781181, 10456.385903524118, 10653.9467302663, 22953.0, 22998.080076797076, 23004.65493017606, 23045.078196872273, 23106.65340019901, 7591.962040189874, 7748.418868584766, 7756.534845900581, 7843.411744119214, 7872.72445464406, 4372.0, 4495.213337665896, 4514.616257618073, 4521.276595744731, 4711.199096153662, 3394.032700680294, 3509.2455122148863, 3509.64903509647, 3510.192655689155, 3511.754177207571, 618.6628287583278, 624.3974564545235, 650.1612919334007, 653.3172834388076, 655.0469067506766, 529.4969778710241, 624.978125765596, 628.6605233174126, 641.1888960465016, 653.5555822040963, 10100.797984040279, 10212.642557510451, 10331.586736530606, 10517.42154181512, 10714.839277410845, 440.3504830375235, 527.9815206467755, 559.4377650461341, 567.7076305702567, 569.7834822767322, 4680.204365257914, 4837.37114175155, 4865.391826021747, 4877.829275975324, 4891.241857512091, 81315.0, 94899.52550237205, 96523.0, 97220.51389743148, 97369.5131524352, 49777.50222497801, 52307.0, 62750.0, 79314.20685793068, 84038.57980710057, 68568.0, 68584.0, 69030.96453553201, 69542.30457695358, 70655.29344706693, 10440.68677939663, 10719.088877445505, 10968.341899486057, 11066.557337706723, 11129.332240065622, 44971.029490429035, 45450.54549454463, 46561.767191163824, 46912.0, 47248.0, 43836.780816095714, 56823.71588142198, 57118.0, 57300.0, 58103.12845307239, 12459.688507787378, 12524.311162886128, 12677.0, 13071.411786469822, 13251.80122298147, 12061.75876482483, 14456.85543144555, 14484.203368814817, 14748.336324865193, 15373.3082011312, 12616.369181540702, 12808.871911280768, 13821.861781382058, 14035.719285614436, 14280.471717762664, 12947.417366218731, 13309.341730394284, 13386.19682819034, 13516.662083447993, 13883.167009979434, 12769.872301276868, 13529.0, 13568.253746044062, 13708.3831227593, 14007.276764912875, 9408.717738467856, 9752.744535654181, 9773.0, 9774.706758797247, 9904.25718071162, 10396.532156053147, 11068.35898460917, 11173.385463799244, 11193.27243729155, 11211.943940280573], "value": 7469.992926460313, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1507.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 120.0, 122.0, 118.0, 119.0, 122.0, 120.0, 120.0, 120.0, 120.0], "value": 119.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 114.0, 114.0, 153.0, 114.0, 114.0, 111.0, 112.0, 133.0, 115.0], "value": 123.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [214.0, 212.0, 215.0, 213.0, 215.0, 216.0, 214.0, 220.0, 294.0, 211.0], "value": 222.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 64.0, 65.0, 94.0, 64.0, 61.0, 60.0, 61.0, 78.0, 62.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 73.0, 87.0, 71.0, 72.0, 69.0, 84.0, 69.0, 70.0, 85.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 90.0, 90.0, 90.0, 90.0, 94.0, 89.0, 88.0, 90.0], "value": 90.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 97.0, 97.0, 97.0, 97.0, 97.0, 98.0, 101.0, 96.0, 96.0], "value": 98.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 100.0, 101.0, 100.0, 101.0, 103.0, 99.0, 100.0, 101.0, 100.0], "value": 100.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 59.0, 63.0, 64.0, 64.0, 65.0, 63.0, 65.0, 65.0], "value": 62.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 68.0, 67.0, 65.0, 65.0, 62.0, 63.0, 64.0, 67.0, 65.0], "value": 64.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 86.0, 85.0, 74.0, 85.0, 76.0, 91.0, 75.0, 87.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 163.0, 148.0, 142.0, 144.0, 176.0, 152.0, 187.0, 173.0], "value": 161.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 169.0, 167.0, 169.0, 178.0, 171.0, 169.0, 169.0, 170.0], "value": 169.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 68.0, 67.0, 67.0, 65.0, 66.0, 64.0, 66.0], "value": 66.4, "unit": "score"}]}]} 15:20:22 INFO - Return code: 0 15:20:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:20:22 INFO - # TBPL SUCCESS # 15:20:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:20:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:20:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:20:22 INFO - 2017-06-05 15:20:22.151 screenresolution[2349:27577] starting screenresolution argv=screenresolution get 15:20:22 INFO - 2017-06-05 15:20:22.154 screenresolution[2349:27577] Display 0: 1600x1200x32@60 15:20:22 INFO - 2017-06-05 15:20:22.160 screenresolution[2350:27580] starting screenresolution argv=screenresolution list 15:20:22 INFO - Available Modes on Display 0 15:20:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:20:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:20:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:20:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:20:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:20:22 INFO - Intel Iris: 15:20:22 INFO - Chipset Model: Intel Iris 15:20:22 INFO - Type: GPU 15:20:22 INFO - Bus: Built-In 15:20:22 INFO - VRAM (Dynamic, Max): 1536 MB 15:20:22 INFO - Vendor: Intel (0x8086) 15:20:22 INFO - Device ID: 0x0a2e 15:20:22 INFO - Revision ID: 0x0009 15:20:22 INFO - Displays: 15:20:22 INFO - Display: 15:20:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:20:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:20:22 INFO - Display Serial Number: 1600x1200 60 15:20:22 INFO - Main Display: Yes 15:20:22 INFO - Mirror: Off 15:20:22 INFO - Online: Yes 15:20:22 INFO - Rotation: Supported 15:20:22 INFO - Return code: 0 15:20:22 INFO - Running post-action listener: _resource_record_post_action 15:20:22 INFO - [mozharness: 2017-06-05 22:20:22.404848Z] Finished run-tests step (success) 15:20:22 INFO - Running post-run listener: _resource_record_post_run 15:20:22 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 1638400; Write bytes: 421008384; Read time: 16; Write time: 1425 15:20:22 INFO - TinderboxPrint: CPU usage
54.1% 15:20:22 INFO - TinderboxPrint: I/O read bytes / time
1,638,400 / 16 15:20:22 INFO - TinderboxPrint: I/O write bytes / time
421,008,384 / 1,425 15:20:22 INFO - TinderboxPrint: CPU idle
2,199.3 (45.9%) 15:20:22 INFO - TinderboxPrint: CPU system
964.4 (20.1%) 15:20:22 INFO - TinderboxPrint: CPU user
1,626.0 (33.9%) 15:20:22 INFO - TinderboxPrint: Swap in / out
124,301,312 / 0 15:20:22 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 115356160; Write bytes: 113205248; Read time: 10860; Write time: 237 15:20:22 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 1638400; Write bytes: 297874432; Read time: 16; Write time: 1155 15:20:22 INFO - Running post-run listener: _upload_blobber_files 15:20:22 INFO - Blob upload gear active. 15:20:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:20:22 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 15:20:22 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'] 15:20:22 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 15:20:23 INFO - (blobuploader) - INFO - Open directory for files ... 15:20:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:20:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:20:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:20:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:20:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:20:23 INFO - (blobuploader) - INFO - Done attempting. 15:20:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:20:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:20:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:20:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:20:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:20:25 INFO - (blobuploader) - INFO - Done attempting. 15:20:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:20:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:20:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:20:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:20:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:20:26 INFO - (blobuploader) - INFO - Done attempting. 15:20:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:20:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:20:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:20:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:20:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:20:28 INFO - (blobuploader) - INFO - Done attempting. 15:20:28 INFO - (blobuploader) - INFO - Iteration through files over. 15:20:28 INFO - Return code: 0 15:20:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:20:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:20:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b24ce404b899b845be61fded2f6975265132c8bf9afc0d01d8a5b62101c50518422c92a164455c91309d00ceb7d0fcb984e0ca5711614f42b538cd5ea96cc5e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b282a72fcf7ab6f8369b9eb8f4d447ef979316c58cc5736482b0d86b9cd5afa763ed8ec50e84c036b5df1e63f5819f1d549361b1a1ac00a0587f28bcc81f01ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0c5529f5135cf29c3b4806aa6c25259e2acf92f44d35d69318e1e580f9c4a8f2009708bcb1c549d650852bbd141c2e64d774b34094bca3a5cedb58a3f37716e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/edf6c73f7724e18f19e997f6536da7cd2025165b11dff700e8a49e40ef66207c5a1dc758ffd1744f0bba9d725f23d625e9e32c999890145b65c87915a3d5210e"} 15:20:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:20:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:20:28 INFO - Contents: 15:20:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b24ce404b899b845be61fded2f6975265132c8bf9afc0d01d8a5b62101c50518422c92a164455c91309d00ceb7d0fcb984e0ca5711614f42b538cd5ea96cc5e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b282a72fcf7ab6f8369b9eb8f4d447ef979316c58cc5736482b0d86b9cd5afa763ed8ec50e84c036b5df1e63f5819f1d549361b1a1ac00a0587f28bcc81f01ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0c5529f5135cf29c3b4806aa6c25259e2acf92f44d35d69318e1e580f9c4a8f2009708bcb1c549d650852bbd141c2e64d774b34094bca3a5cedb58a3f37716e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/edf6c73f7724e18f19e997f6536da7cd2025165b11dff700e8a49e40ef66207c5a1dc758ffd1744f0bba9d725f23d625e9e32c999890145b65c87915a3d5210e"} 15:20:28 INFO - Running post-run listener: copy_logs_to_upload_dir 15:20:28 INFO - Copying logs to upload dir... 15:20:28 INFO - mkdir: /builds/slave/test/build/upload/logs 15:20:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.067534 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-06-05 15:20:28.681664) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 15:20:28.685628) ========= 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.0pTqGxV5YK/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.K0zVTOo7qg/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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/b24ce404b899b845be61fded2f6975265132c8bf9afc0d01d8a5b62101c50518422c92a164455c91309d00ceb7d0fcb984e0ca5711614f42b538cd5ea96cc5e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b282a72fcf7ab6f8369b9eb8f4d447ef979316c58cc5736482b0d86b9cd5afa763ed8ec50e84c036b5df1e63f5819f1d549361b1a1ac00a0587f28bcc81f01ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0c5529f5135cf29c3b4806aa6c25259e2acf92f44d35d69318e1e580f9c4a8f2009708bcb1c549d650852bbd141c2e64d774b34094bca3a5cedb58a3f37716e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/edf6c73f7724e18f19e997f6536da7cd2025165b11dff700e8a49e40ef66207c5a1dc758ffd1744f0bba9d725f23d625e9e32c999890145b65c87915a3d5210e"} build_url:https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011937 build_url: 'https://queue.taskcluster.net/v1/task/Gquv7v35SymJzdv4R6EnvA/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/b24ce404b899b845be61fded2f6975265132c8bf9afc0d01d8a5b62101c50518422c92a164455c91309d00ceb7d0fcb984e0ca5711614f42b538cd5ea96cc5e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b282a72fcf7ab6f8369b9eb8f4d447ef979316c58cc5736482b0d86b9cd5afa763ed8ec50e84c036b5df1e63f5819f1d549361b1a1ac00a0587f28bcc81f01ae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f0c5529f5135cf29c3b4806aa6c25259e2acf92f44d35d69318e1e580f9c4a8f2009708bcb1c549d650852bbd141c2e64d774b34094bca3a5cedb58a3f37716e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/edf6c73f7724e18f19e997f6536da7cd2025165b11dff700e8a49e40ef66207c5a1dc758ffd1744f0bba9d725f23d625e9e32c999890145b65c87915a3d5210e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 15:20:28.719743) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 15:20:28.720077) ========= 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.0pTqGxV5YK/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.K0zVTOo7qg/Listeners TMPDIR=/var/folders/nm/9455zyq90cx5b0vslr6bbx7h00000w/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.005492 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 15:20:28.769497) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 15:20:28.769832) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 15:20:28.770175) ========= ========= Total master_lag: 2.09 =========