builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0190 starttime: 1494961339.42 results: success (0) buildid: 20170516105120 builduid: 6aff3651840d4188995237cf5bba6665 revision: 925160e8379600a79d869cf18df4f158e7eb1868 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 12:02:19.421690) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 12:02:19.422107) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 12:02:19.422375) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 12:02:19.598904) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 12:02:19.599206) ========= 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.WAEGzV9wHy/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.hIctt6F1Et/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-16 12:02:19-- 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% 66.4M=0s 2017-05-16 12:02:20 (66.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.511280 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 12:02:20.135925) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 12:02:20.136230) ========= 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.WAEGzV9wHy/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.hIctt6F1Et/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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.027663 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 12:02:20.184187) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-16 12:02:20.184571) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 925160e8379600a79d869cf18df4f158e7eb1868 --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 925160e8379600a79d869cf18df4f158e7eb1868 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WAEGzV9wHy/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.hIctt6F1Et/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-16 12:02:20,289 truncating revision to first 12 chars 2017-05-16 12:02:20,289 Setting DEBUG logging. 2017-05-16 12:02:20,289 attempt 1/10 2017-05-16 12:02:20,289 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/925160e83796?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-16 12:02:21,089 unpacking tar archive at: mozilla-beta-925160e83796/testing/mozharness/ program finished with exit code 0 elapsedTime=1.065661 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-16 12:02:21.272680) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 12:02:21.273017) ========= script_repo_revision: 925160e8379600a79d869cf18df4f158e7eb1868 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 12:02:21.273536) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 12:02:21.273904) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 12:02:21.292781) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-05-16 12:02:21.293152) ========= /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.WAEGzV9wHy/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.hIctt6F1Et/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:02:21 INFO - MultiFileLogger online at 20170516 12:02:21 in /builds/slave/test 12:02:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:02:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:02:21 INFO - {'append_to_log': False, 12:02:21 INFO - 'base_work_dir': '/builds/slave/test', 12:02:21 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:02:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:02:21 INFO - 'branch': 'Mozilla-Beta', 12:02:21 INFO - 'buildbot_json_path': 'buildprops.json', 12:02:21 INFO - 'config_files': ('talos/mac_config.py',), 12:02:21 INFO - 'default_actions': ('clobber', 12:02:21 INFO - 'read-buildbot-config', 12:02:21 INFO - 'download-and-extract', 12:02:21 INFO - 'populate-webroot', 12:02:21 INFO - 'create-virtualenv', 12:02:21 INFO - 'install', 12:02:21 INFO - 'run-tests'), 12:02:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:02:21 INFO - 'download_minidump_stackwalk': True, 12:02:21 INFO - 'download_symbols': 'ondemand', 12:02:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:02:21 INFO - 'tooltool.py': '/tools/tooltool.py', 12:02:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:02:21 INFO - '/tools/misc-python/virtualenv.py')}, 12:02:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:02:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:02:21 INFO - 'gecko_profile': False, 12:02:21 INFO - 'gecko_profile_interval': 0, 12:02:21 INFO - 'installer_path': 'installer.exe', 12:02:21 INFO - 'log_level': 'info', 12:02:21 INFO - 'log_name': 'talos', 12:02:21 INFO - 'log_to_console': True, 12:02:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:02:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:02:21 INFO - 'opt_config_files': (), 12:02:21 INFO - 'pip_index': False, 12:02:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:02:21 INFO - 'cmd': ('bash', 12:02:21 INFO - '-c', 12:02:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:02:21 INFO - 'enabled': True, 12:02:21 INFO - 'halt_on_failure': False, 12:02:21 INFO - 'name': 'check_screen_resolution'},), 12:02:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:02:21 INFO - 'cmd': ('bash', 12:02:21 INFO - '-c', 12:02:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:02:21 INFO - 'enabled': True, 12:02:21 INFO - 'halt_on_failure': False, 12:02:21 INFO - 'name': 'check_screen_resolution'},), 12:02:21 INFO - 'run_cmd_checks_enabled': True, 12:02:21 INFO - 'suite': 'dromaeojs', 12:02:21 INFO - 'system_bits': '32', 12:02:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:02:21 INFO - 'title': 't-yosemite-r7-0190', 12:02:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:02:21 INFO - 'use_talos_json': True, 12:02:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:02:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:02:21 INFO - 'work_dir': 'build'} 12:02:21 INFO - [mozharness: 2017-05-16 19:02:21.511607Z] Running clobber step. 12:02:21 INFO - Running pre-action listener: _resource_record_pre_action 12:02:21 INFO - Running main action method: clobber 12:02:21 INFO - rmtree: /builds/slave/test/build 12:02:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:02:23 INFO - Running post-action listener: _resource_record_post_action 12:02:23 INFO - [mozharness: 2017-05-16 19:02:23.981688Z] Finished clobber step (success) 12:02:23 INFO - [mozharness: 2017-05-16 19:02:23.981813Z] Running read-buildbot-config step. 12:02:23 INFO - Running pre-action listener: _resource_record_pre_action 12:02:23 INFO - Running main action method: read_buildbot_config 12:02:23 INFO - Using buildbot properties: 12:02:23 INFO - { 12:02:23 INFO - "project": "", 12:02:23 INFO - "product": "firefox", 12:02:23 INFO - "script_repo_revision": "production", 12:02:23 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 12:02:23 INFO - "repository": "", 12:02:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 12:02:23 INFO - "buildid": "20170516105120", 12:02:23 INFO - "pgo_build": "False", 12:02:23 INFO - "basedir": "/builds/slave/test", 12:02:23 INFO - "buildnumber": 13, 12:02:23 INFO - "slavename": "t-yosemite-r7-0190", 12:02:23 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 12:02:23 INFO - "platform": "yosemite_r7", 12:02:23 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:02:23 INFO - "branch": "mozilla-beta", 12:02:23 INFO - "repo_path": "releases/mozilla-beta", 12:02:23 INFO - "revision": "925160e8379600a79d869cf18df4f158e7eb1868", 12:02:23 INFO - "stage_platform": "macosx64", 12:02:23 INFO - "builduid": "6aff3651840d4188995237cf5bba6665", 12:02:23 INFO - "slavebuilddir": "test" 12:02:23 INFO - } 12:02:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 12:02:23 INFO - Running post-action listener: _resource_record_post_action 12:02:23 INFO - [mozharness: 2017-05-16 19:02:23.985020Z] Finished read-buildbot-config step (success) 12:02:23 INFO - [mozharness: 2017-05-16 19:02:23.985151Z] Running download-and-extract step. 12:02:23 INFO - Running pre-action listener: _resource_record_pre_action 12:02:23 INFO - Running main action method: download_and_extract 12:02:23 INFO - mkdir: /builds/slave/test/build/tests 12:02:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:02:23 INFO - trying https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 12:02:23 INFO - Downloading https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:02:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 12:02:25 INFO - Downloaded 1287 bytes. 12:02:25 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:02:25 INFO - Using the following test package requirements: 12:02:25 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:02:25 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 12:02:25 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:02:25 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:02:25 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 12:02:25 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:02:25 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 12:02:25 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:02:25 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:02:25 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 12:02:25 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:02:25 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:02:25 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 12:02:25 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:02:25 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 12:02:25 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:02:25 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 12:02:25 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:02:25 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 12:02:25 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 12:02:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:02:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:02:25 INFO - Fetch https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:02:27 INFO - Expected file size: 51224847 12:02:27 INFO - Obtained file size: 51224847 12:02:42 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:02:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:02:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:02:42 INFO - Fetch https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:02:44 INFO - Expected file size: 51224847 12:02:44 INFO - Obtained file size: 51224847 12:02:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 12:02:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 12:02:59 INFO - Fetch https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 12:03:00 INFO - Expected file size: 14404449 12:03:00 INFO - Obtained file size: 14404449 12:03:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:00 INFO - trying https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:03:00 INFO - Downloading https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 12:03:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 12:03:02 INFO - Downloaded 59540991 bytes. 12:03:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:03:02 INFO - mkdir: /builds/slave/test/properties 12:03:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:03:02 INFO - Writing to file /builds/slave/test/properties/build_url 12:03:02 INFO - Contents: 12:03:02 INFO - build_url:https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:03:03 INFO - Running post-action listener: _resource_record_post_action 12:03:03 INFO - Running post-action listener: set_extra_try_arguments 12:03:03 INFO - [mozharness: 2017-05-16 19:03:03.273954Z] Finished download-and-extract step (success) 12:03:03 INFO - [mozharness: 2017-05-16 19:03:03.274091Z] Running populate-webroot step. 12:03:03 INFO - Running pre-action listener: _resource_record_pre_action 12:03:03 INFO - Running main action method: populate_webroot 12:03:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:03:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:03:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:03:03 INFO - '--tppagecycles', 12:03:03 INFO - '7'], 12:03:03 INFO - 'tests': ['tsvgm']}}, 12:03:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:03:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:03:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:03:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:03:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:03:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:03:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:03:03 INFO - 'talos_options': ['--disable-e10s'], 12:03:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:03:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:03:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:03:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:03:03 INFO - 'talos_options': ['--disable-e10s'], 12:03:03 INFO - 'tests': ['damp', 'tps']}, 12:03:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:03:03 INFO - 'tests': ['damp', 'tps']}, 12:03:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:03:03 INFO - 'tests': ['dromaeo_dom']}, 12:03:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:03:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:03:03 INFO - 'tests': ['basic_compositor_video']}, 12:03:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:03:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:03:03 INFO - 'tests': ['a11yr', 12:03:03 INFO - 'ts_paint', 12:03:03 INFO - 'tpaint', 12:03:03 INFO - 'sessionrestore', 12:03:03 INFO - 'sessionrestore_no_auto_restore', 12:03:03 INFO - 'tabpaint']}, 12:03:03 INFO - 'other-e10s': {'tests': ['a11yr', 12:03:03 INFO - 'ts_paint', 12:03:03 INFO - 'tpaint', 12:03:03 INFO - 'sessionrestore', 12:03:03 INFO - 'sessionrestore_no_auto_restore', 12:03:03 INFO - 'tabpaint']}, 12:03:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:03:03 INFO - 'ts_paint', 12:03:03 INFO - 'tpaint', 12:03:03 INFO - 'sessionrestore', 12:03:03 INFO - 'sessionrestore_no_auto_restore', 12:03:03 INFO - 'tabpaint']}, 12:03:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:03:03 INFO - 'ts_paint', 12:03:03 INFO - 'tpaint', 12:03:03 INFO - 'sessionrestore', 12:03:03 INFO - 'sessionrestore_no_auto_restore', 12:03:03 INFO - 'tabpaint']}, 12:03:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:03:03 INFO - 'tests': ['a11yr', 12:03:03 INFO - 'ts_paint', 12:03:03 INFO - 'tpaint', 12:03:03 INFO - 'sessionrestore', 12:03:03 INFO - 'sessionrestore_no_auto_restore', 12:03:03 INFO - 'tabpaint']}, 12:03:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:03:03 INFO - 'tests': ['a11yr', 12:03:03 INFO - 'ts_paint', 12:03:03 INFO - 'tpaint', 12:03:03 INFO - 'sessionrestore', 12:03:03 INFO - 'sessionrestore_no_auto_restore', 12:03:03 INFO - 'tabpaint']}, 12:03:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:03:03 INFO - 'tests': ['tsvgx', 12:03:03 INFO - 'tsvgr_opacity', 12:03:03 INFO - 'tart', 12:03:03 INFO - 'tscrollx', 12:03:03 INFO - 'cart', 12:03:03 INFO - 'tsvg_static']}, 12:03:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:03:03 INFO - 'tsvgr_opacity', 12:03:03 INFO - 'tart', 12:03:03 INFO - 'tscrollx', 12:03:03 INFO - 'cart', 12:03:03 INFO - 'tsvg_static']}, 12:03:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:03:03 INFO - 'talos_options': ['--disable-e10s'], 12:03:03 INFO - 'tests': ['tp5o']}, 12:03:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:03:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:03:03 INFO - 'talos_options': ['--disable-e10s', 12:03:03 INFO - '--xperf_path', 12:03:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:03:03 INFO - 'tests': ['tp5n']}, 12:03:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:03:03 INFO - 'talos_options': ['--xperf_path', 12:03:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:03:03 INFO - 'tests': ['tp5n']}}, 12:03:03 INFO - 'talos.zip': {'path': '', 12:03:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:03:03 INFO - Running post-action listener: _resource_record_post_action 12:03:03 INFO - [mozharness: 2017-05-16 19:03:03.292376Z] Finished populate-webroot step (success) 12:03:03 INFO - [mozharness: 2017-05-16 19:03:03.292509Z] Running create-virtualenv step. 12:03:03 INFO - Running pre-action listener: _resource_record_pre_action 12:03:03 INFO - Running main action method: create_virtualenv 12:03:03 INFO - Creating virtualenv /builds/slave/test/build/venv 12:03:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:03:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:03:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:03:03 INFO - Using real prefix '/tools/python27' 12:03:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:03:04 INFO - Installing distribute.............................................................................................................................................................................................done. 12:03:06 INFO - Installing pip.................done. 12:03:06 INFO - Return code: 0 12:03:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:03:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:03:07 INFO - Reading from file tmpfile_stdout 12:03:07 INFO - Output received: 12:03:07 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:03:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:03:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:07 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 0x108d23b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d26768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94dbdcc260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b01d30>, '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 0x108a28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108af6be8>, '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.WAEGzV9wHy/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.hIctt6F1Et/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:03:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:03:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WAEGzV9wHy/Render', 12:03:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:07 INFO - 'HOME': '/Users/cltbld', 12:03:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:07 INFO - 'LOGNAME': 'cltbld', 12:03:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:07 INFO - 'MOZ_NO_REMOTE': '1', 12:03:07 INFO - 'NO_EM_RESTART': '1', 12:03:07 INFO - 'PAGER': '/bin/cat', 12:03:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:07 INFO - 'PWD': '/builds/slave/test', 12:03:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:07 INFO - 'SHELL': '/bin/bash', 12:03:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIctt6F1Et/Listeners', 12:03:07 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 12:03:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:07 INFO - 'USER': 'cltbld', 12:03:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:07 INFO - 'XPC_FLAGS': '0x0', 12:03:07 INFO - 'XPC_SERVICE_NAME': '0', 12:03:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:07 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:03:07 INFO - Cleaning up... 12:03:07 INFO - Return code: 0 12:03:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:03:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:07 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 0x108d23b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d26768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94dbdcc260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b01d30>, '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 0x108a28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108af6be8>, '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.WAEGzV9wHy/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.hIctt6F1Et/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:03:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:03:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WAEGzV9wHy/Render', 12:03:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:07 INFO - 'HOME': '/Users/cltbld', 12:03:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:07 INFO - 'LOGNAME': 'cltbld', 12:03:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:07 INFO - 'MOZ_NO_REMOTE': '1', 12:03:07 INFO - 'NO_EM_RESTART': '1', 12:03:07 INFO - 'PAGER': '/bin/cat', 12:03:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:07 INFO - 'PWD': '/builds/slave/test', 12:03:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:07 INFO - 'SHELL': '/bin/bash', 12:03:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIctt6F1Et/Listeners', 12:03:07 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 12:03:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:07 INFO - 'USER': 'cltbld', 12:03:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:07 INFO - 'XPC_FLAGS': '0x0', 12:03:07 INFO - 'XPC_SERVICE_NAME': '0', 12:03:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:07 INFO - Downloading/unpacking psutil>=3.1.1 12:03: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 12:03: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 12:03: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 12:03: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 12:03:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:03:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:03:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:03:10 INFO - Installing collected packages: psutil 12:03:10 INFO - Running setup.py install for psutil 12:03:10 INFO - building 'psutil._psutil_osx' extension 12:03:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:03:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:03:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:03:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:03:11 INFO - building 'psutil._psutil_posix' extension 12:03:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:03:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:03:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:03:11 INFO - ^ 12:03:11 INFO - 1 warning generated. 12:03:11 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:03:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:03:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:03:11 INFO - Successfully installed psutil 12:03:11 INFO - Cleaning up... 12:03:11 INFO - Return code: 0 12:03:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:03: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')]} 12:03:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03: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')]} 12:03:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108d23b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d26768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94dbdcc260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b01d30>, '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 0x108a28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108af6be8>, '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.WAEGzV9wHy/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.hIctt6F1Et/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:03: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 mozsystemmonitor==0.3 12:03:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WAEGzV9wHy/Render', 12:03:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:11 INFO - 'HOME': '/Users/cltbld', 12:03:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:11 INFO - 'LOGNAME': 'cltbld', 12:03:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:11 INFO - 'MOZ_NO_REMOTE': '1', 12:03:11 INFO - 'NO_EM_RESTART': '1', 12:03:11 INFO - 'PAGER': '/bin/cat', 12:03:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:11 INFO - 'PWD': '/builds/slave/test', 12:03:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:11 INFO - 'SHELL': '/bin/bash', 12:03:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIctt6F1Et/Listeners', 12:03:11 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 12:03:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:11 INFO - 'USER': 'cltbld', 12:03:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:11 INFO - 'XPC_FLAGS': '0x0', 12:03:11 INFO - 'XPC_SERVICE_NAME': '0', 12:03:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:03: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 12:03: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 12:03: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 12:03: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 12:03:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:03:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:03:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:03:13 INFO - Installing collected packages: mozsystemmonitor 12:03:13 INFO - Running setup.py install for mozsystemmonitor 12:03:13 INFO - Successfully installed mozsystemmonitor 12:03:13 INFO - Cleaning up... 12:03:13 INFO - Return code: 0 12:03:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:03:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:13 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 0x108d23b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d26768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94dbdcc260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b01d30>, '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 0x108a28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108af6be8>, '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.WAEGzV9wHy/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.hIctt6F1Et/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:03:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:03:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WAEGzV9wHy/Render', 12:03:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:13 INFO - 'HOME': '/Users/cltbld', 12:03:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:13 INFO - 'LOGNAME': 'cltbld', 12:03:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:13 INFO - 'MOZ_NO_REMOTE': '1', 12:03:13 INFO - 'NO_EM_RESTART': '1', 12:03:13 INFO - 'PAGER': '/bin/cat', 12:03:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:13 INFO - 'PWD': '/builds/slave/test', 12:03:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:13 INFO - 'SHELL': '/bin/bash', 12:03:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIctt6F1Et/Listeners', 12:03:13 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 12:03:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:13 INFO - 'USER': 'cltbld', 12:03:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:13 INFO - 'XPC_FLAGS': '0x0', 12:03:13 INFO - 'XPC_SERVICE_NAME': '0', 12:03:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:13 INFO - Downloading/unpacking jsonschema==2.5.1 12:03: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 12:03: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 12:03: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 12:03: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 12:03:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:03:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:15 INFO - Downloading functools32-3.2.3-2.tar.gz 12:03:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:03:15 INFO - warning: no files found matching '*.txt' 12:03:15 INFO - no previously-included directories found matching 'build' 12:03:15 INFO - no previously-included directories found matching 'dist' 12:03:15 INFO - no previously-included directories found matching '.git*' 12:03:15 INFO - Installing collected packages: jsonschema, functools32 12:03:15 INFO - Running setup.py install for functools32 12:03:15 INFO - warning: no files found matching '*.txt' 12:03:15 INFO - no previously-included directories found matching 'build' 12:03:15 INFO - no previously-included directories found matching 'dist' 12:03:15 INFO - no previously-included directories found matching '.git*' 12:03:15 INFO - Successfully installed jsonschema functools32 12:03:15 INFO - Cleaning up... 12:03:15 INFO - Return code: 0 12:03:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:15 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 0x108d23b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d26768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94dbdcc260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b01d30>, '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 0x108a28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108af6be8>, '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.WAEGzV9wHy/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.hIctt6F1Et/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:03:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:03:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WAEGzV9wHy/Render', 12:03:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:15 INFO - 'HOME': '/Users/cltbld', 12:03:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:15 INFO - 'LOGNAME': 'cltbld', 12:03:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:15 INFO - 'MOZ_NO_REMOTE': '1', 12:03:15 INFO - 'NO_EM_RESTART': '1', 12:03:15 INFO - 'PAGER': '/bin/cat', 12:03:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:15 INFO - 'PWD': '/builds/slave/test', 12:03:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:15 INFO - 'SHELL': '/bin/bash', 12:03:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIctt6F1Et/Listeners', 12:03:15 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 12:03:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:15 INFO - 'USER': 'cltbld', 12:03:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:15 INFO - 'XPC_FLAGS': '0x0', 12:03:15 INFO - 'XPC_SERVICE_NAME': '0', 12:03:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:03:15 INFO - Cleaning up... 12:03:15 INFO - Return code: 0 12:03:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:15 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 0x108d23b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d26768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94dbdcc260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b01d30>, '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 0x108a28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108af6be8>, '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.WAEGzV9wHy/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.hIctt6F1Et/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:03:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:03:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WAEGzV9wHy/Render', 12:03:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:15 INFO - 'HOME': '/Users/cltbld', 12:03:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:15 INFO - 'LOGNAME': 'cltbld', 12:03:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:15 INFO - 'MOZ_NO_REMOTE': '1', 12:03:15 INFO - 'NO_EM_RESTART': '1', 12:03:15 INFO - 'PAGER': '/bin/cat', 12:03:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:15 INFO - 'PWD': '/builds/slave/test', 12:03:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:15 INFO - 'SHELL': '/bin/bash', 12:03:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIctt6F1Et/Listeners', 12:03:15 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 12:03:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:15 INFO - 'USER': 'cltbld', 12:03:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:15 INFO - 'XPC_FLAGS': '0x0', 12:03:15 INFO - 'XPC_SERVICE_NAME': '0', 12:03:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:16 INFO - Downloading/unpacking blobuploader==1.2.4 12:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:17 INFO - Downloading blobuploader-1.2.4.tar.gz 12:03:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:03:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:03:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:18 INFO - Downloading docopt-0.6.1.tar.gz 12:03:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:03:18 INFO - Installing collected packages: blobuploader, requests, docopt 12:03:18 INFO - Running setup.py install for blobuploader 12:03:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:03:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:03:18 INFO - Running setup.py install for requests 12:03:19 INFO - Running setup.py install for docopt 12:03:19 INFO - Successfully installed blobuploader requests docopt 12:03:19 INFO - Cleaning up... 12:03:19 INFO - Return code: 0 12:03:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:03: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')]} 12:03:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03: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')]} 12:03:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:19 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 0x108d23b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d26768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94dbdcc260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b01d30>, '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 0x108a28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108af6be8>, '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.WAEGzV9wHy/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.hIctt6F1Et/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:03:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:03:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WAEGzV9wHy/Render', 12:03:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:19 INFO - 'HOME': '/Users/cltbld', 12:03:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:19 INFO - 'LOGNAME': 'cltbld', 12:03:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:19 INFO - 'MOZ_NO_REMOTE': '1', 12:03:19 INFO - 'NO_EM_RESTART': '1', 12:03:19 INFO - 'PAGER': '/bin/cat', 12:03:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:19 INFO - 'PWD': '/builds/slave/test', 12:03:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:19 INFO - 'SHELL': '/bin/bash', 12:03:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIctt6F1Et/Listeners', 12:03:19 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 12:03:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:19 INFO - 'USER': 'cltbld', 12:03:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:19 INFO - 'XPC_FLAGS': '0x0', 12:03:19 INFO - 'XPC_SERVICE_NAME': '0', 12:03:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:03:19 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-wz_zmv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:03:19 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-6PqrFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:03:19 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-WV1Umb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:03:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:03:19 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-dnqbPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:03:20 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-4SavFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:03:20 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-gXNtRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:03:20 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-oe4nJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:03:20 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-eok4tt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:03:20 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-IAqM52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:03:20 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-bpcWsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:03:20 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-Vdw2fm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:03:20 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-1wheeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:03:20 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-GVXevV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:03:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:03:20 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-ZjG3Fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:21 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-JAuLAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:03:21 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-A3gG_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:03:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:03:21 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-08T1fG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:03:21 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:03:21 INFO - Running setup.py install for manifestparser 12:03:21 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:03:21 INFO - Running setup.py install for mozcrash 12:03:21 INFO - Running setup.py install for mozdebug 12:03:21 INFO - Running setup.py install for mozdevice 12:03:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:03:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:03:21 INFO - Running setup.py install for mozfile 12:03:22 INFO - Running setup.py install for mozhttpd 12:03:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:03:22 INFO - Running setup.py install for mozinfo 12:03:22 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:03:22 INFO - Running setup.py install for mozInstall 12:03:22 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:03:22 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:03:22 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:03:22 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:03:22 INFO - Running setup.py install for mozleak 12:03:22 INFO - Running setup.py install for mozlog 12:03:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:03:22 INFO - Running setup.py install for moznetwork 12:03:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:03:23 INFO - Running setup.py install for mozprocess 12:03:23 INFO - Running setup.py install for mozprofile 12:03:23 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:03:23 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:03:23 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:03:23 INFO - Running setup.py install for mozrunner 12:03:23 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:03:23 INFO - Running setup.py install for mozscreenshot 12:03:23 INFO - Running setup.py install for moztest 12:03:23 INFO - Running setup.py install for mozversion 12:03:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:03:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:03:23 INFO - Cleaning up... 12:03:23 INFO - Return code: 0 12:03:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:03:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:23 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 0x108d23b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d26768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94dbdcc260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b01d30>, '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 0x108a28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108af6be8>, '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.WAEGzV9wHy/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.hIctt6F1Et/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:03:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:03:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WAEGzV9wHy/Render', 12:03:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:23 INFO - 'HOME': '/Users/cltbld', 12:03:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:23 INFO - 'LOGNAME': 'cltbld', 12:03:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:23 INFO - 'MOZ_NO_REMOTE': '1', 12:03:23 INFO - 'NO_EM_RESTART': '1', 12:03:23 INFO - 'PAGER': '/bin/cat', 12:03:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:23 INFO - 'PWD': '/builds/slave/test', 12:03:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:23 INFO - 'SHELL': '/bin/bash', 12:03:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIctt6F1Et/Listeners', 12:03:23 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 12:03:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:23 INFO - 'USER': 'cltbld', 12:03:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:23 INFO - 'XPC_FLAGS': '0x0', 12:03:23 INFO - 'XPC_SERVICE_NAME': '0', 12:03:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:03:24 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-Qqwu07-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:03:24 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:03:24 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-Dw9Jdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:03:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:03:24 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-jfPE94-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:03:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:03:24 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-Vj0H33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:03:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:03:24 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-YM3R5P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:03:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:03:24 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-i6iEnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:03:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:03:24 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-R1i_gj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:03:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:03:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:03:24 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-uLCDfN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:03:25 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-ZPnBHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:03:25 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-qlEASj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:03:25 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-A5KJr9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:03:25 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-_gJ7JM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:03:25 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-c5_YKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:03:25 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-t3z6l1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:25 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-h_4D8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:03:25 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-UXG0mc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:03:25 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:03:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:03:25 INFO - Running setup.py (path:/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/pip-LWuLBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:03:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:03:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:03:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:03:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:03:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:03:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:03:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:03:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:03:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:03:27 INFO - Downloading blessings-1.6.tar.gz 12:03:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:03:27 INFO - Installing collected packages: blessings 12:03:27 INFO - Running setup.py install for blessings 12:03:27 INFO - Successfully installed blessings 12:03:27 INFO - Cleaning up... 12:03:27 INFO - Return code: 0 12:03:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:03:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:03:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:03:28 INFO - Reading from file tmpfile_stdout 12:03:28 INFO - Current package versions: 12:03:28 INFO - blessings == 1.6 12:03:28 INFO - blobuploader == 1.2.4 12:03:28 INFO - docopt == 0.6.1 12:03:28 INFO - functools32 == 3.2.3-2 12:03:28 INFO - jsonschema == 2.5.1 12:03:28 INFO - manifestparser == 1.1 12:03:28 INFO - mozInstall == 1.12 12:03:28 INFO - mozcrash == 1.0 12:03:28 INFO - mozdebug == 0.1 12:03:28 INFO - mozdevice == 0.49 12:03:28 INFO - mozfile == 1.2 12:03:28 INFO - mozhttpd == 0.7 12:03:28 INFO - mozinfo == 0.9 12:03:28 INFO - mozleak == 0.1 12:03:28 INFO - mozlog == 3.4 12:03:28 INFO - moznetwork == 0.27 12:03:28 INFO - mozprocess == 0.25 12:03:28 INFO - mozprofile == 0.28 12:03:28 INFO - mozrunner == 6.13 12:03:28 INFO - mozscreenshot == 0.1 12:03:28 INFO - mozsystemmonitor == 0.3 12:03:28 INFO - moztest == 0.8 12:03:28 INFO - mozversion == 1.4 12:03:28 INFO - psutil == 3.1.1 12:03:28 INFO - requests == 1.2.3 12:03:28 INFO - wsgiref == 0.1.2 12:03:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:28 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 0x108d23b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d26768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94dbdcc260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b01d30>, '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 0x108a28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108af6be8>, '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.WAEGzV9wHy/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.hIctt6F1Et/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:03:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WAEGzV9wHy/Render', 12:03:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:28 INFO - 'HOME': '/Users/cltbld', 12:03:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:28 INFO - 'LOGNAME': 'cltbld', 12:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:28 INFO - 'MOZ_NO_REMOTE': '1', 12:03:28 INFO - 'NO_EM_RESTART': '1', 12:03:28 INFO - 'PAGER': '/bin/cat', 12:03:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:28 INFO - 'PWD': '/builds/slave/test', 12:03:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:28 INFO - 'SHELL': '/bin/bash', 12:03:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIctt6F1Et/Listeners', 12:03:28 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 12:03:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:28 INFO - 'USER': 'cltbld', 12:03:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:28 INFO - 'XPC_FLAGS': '0x0', 12:03:28 INFO - 'XPC_SERVICE_NAME': '0', 12:03:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:03:28 INFO - Cleaning up... 12:03:28 INFO - Return code: 0 12:03:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:03:28 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 0x108d23b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108d26768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f94dbdcc260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108b01d30>, '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 0x108a28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108af6be8>, '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.WAEGzV9wHy/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.hIctt6F1Et/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:03:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:03:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WAEGzV9wHy/Render', 12:03:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:28 INFO - 'HOME': '/Users/cltbld', 12:03:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:28 INFO - 'LOGNAME': 'cltbld', 12:03:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:28 INFO - 'MOZ_NO_REMOTE': '1', 12:03:28 INFO - 'NO_EM_RESTART': '1', 12:03:28 INFO - 'PAGER': '/bin/cat', 12:03:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:28 INFO - 'PWD': '/builds/slave/test', 12:03:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:28 INFO - 'SHELL': '/bin/bash', 12:03:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIctt6F1Et/Listeners', 12:03:28 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 12:03:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:28 INFO - 'USER': 'cltbld', 12:03:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:28 INFO - 'XPC_FLAGS': '0x0', 12:03:28 INFO - 'XPC_SERVICE_NAME': '0', 12:03:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:03:28 INFO - Cleaning up... 12:03:28 INFO - Return code: 0 12:03:28 INFO - Running post-action listener: _resource_record_post_action 12:03:28 INFO - Running post-action listener: _start_resource_monitoring 12:03:28 INFO - Starting resource monitoring. 12:03:28 INFO - [mozharness: 2017-05-16 19:03:28.642121Z] Finished create-virtualenv step (success) 12:03:28 INFO - [mozharness: 2017-05-16 19:03:28.643086Z] Running install step. 12:03:28 INFO - Running pre-action listener: _resource_record_pre_action 12:03:28 INFO - Running main action method: install 12:03:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:03:28 INFO - Reading from file tmpfile_stdout 12:03:28 INFO - Detecting whether we're running mozinstall >=1.0... 12:03:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:03:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:03:29 INFO - Reading from file tmpfile_stdout 12:03:29 INFO - Output received: 12:03:29 INFO - Usage: mozinstall [options] installer 12:03:29 INFO - Options: 12:03:29 INFO - -h, --help show this help message and exit 12:03:29 INFO - -d DEST, --destination=DEST 12:03:29 INFO - Directory to install application into. [default: 12:03:29 INFO - "/builds/slave/test"] 12:03:29 INFO - --app=APP Application being installed. [default: firefox] 12:03:29 INFO - mkdir: /builds/slave/test/build/application 12:03:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:03:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:03:44 INFO - Reading from file tmpfile_stdout 12:03:44 INFO - Output received: 12:03:44 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:03:44 INFO - Running post-action listener: _resource_record_post_action 12:03:44 INFO - [mozharness: 2017-05-16 19:03:44.419728Z] Finished install step (success) 12:03:44 INFO - [mozharness: 2017-05-16 19:03:44.419885Z] Running run-tests step. 12:03:44 INFO - Running pre-action listener: _resource_record_pre_action 12:03:44 INFO - Running main action method: run_tests 12:03:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:03:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:03:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:03:44 INFO - 2017-05-16 12:03:44.433 screenresolution[1635:8404] starting screenresolution argv=screenresolution get 12:03:44 INFO - 2017-05-16 12:03:44.436 screenresolution[1635:8404] Display 0: 1600x1200x32@60 12:03:44 INFO - 2017-05-16 12:03:44.442 screenresolution[1636:8406] starting screenresolution argv=screenresolution list 12:03:44 INFO - Available Modes on Display 0 12:03:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:03:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:03:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:03:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:03:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:03:44 INFO - Intel Iris: 12:03:44 INFO - Chipset Model: Intel Iris 12:03:44 INFO - Type: GPU 12:03:44 INFO - Bus: Built-In 12:03:44 INFO - VRAM (Dynamic, Max): 1536 MB 12:03:44 INFO - Vendor: Intel (0x8086) 12:03:44 INFO - Device ID: 0x0a2e 12:03:44 INFO - Revision ID: 0x0009 12:03:44 INFO - Displays: 12:03:44 INFO - Display: 12:03:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:03:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:03:44 INFO - Display Serial Number: 1600x1200 60 12:03:44 INFO - Main Display: Yes 12:03:44 INFO - Mirror: Off 12:03:44 INFO - Online: Yes 12:03:44 INFO - Rotation: Supported 12:03:44 INFO - Return code: 0 12:03:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:03:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:03:44 INFO - Python 2.7.3 12:03:44 INFO - Return code: 0 12:03:44 INFO - grabbing minidump binary from tooltool 12:03:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:03:44 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 0x108b01d30>, '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 0x108a28db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108af6be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:03:44 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:03:44 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:03:44 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:03:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:03:44 INFO - Return code: 0 12:03:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:03:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:03:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:03:44 INFO - ENV: RUST_BACKTRACE is now 1 12:03:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:03:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:03:44 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/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0190', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:03:44 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/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0190 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:03:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WAEGzV9wHy/Render', 12:03:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:03:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:03:44 INFO - 'HOME': '/Users/cltbld', 12:03:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:03:44 INFO - 'LOGNAME': 'cltbld', 12:03:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:03:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:03:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:03:44 INFO - 'MOZ_NO_REMOTE': '1', 12:03:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:03:44 INFO - 'NO_EM_RESTART': '1', 12:03:44 INFO - 'PAGER': '/bin/cat', 12:03:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:03:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:03:44 INFO - 'PWD': '/builds/slave/test', 12:03:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:03:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:03:44 INFO - 'RUST_BACKTRACE': '1', 12:03:44 INFO - 'SHELL': '/bin/bash', 12:03:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIctt6F1Et/Listeners', 12:03:44 INFO - 'TMPDIR': '/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/', 12:03:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:03:44 INFO - 'USER': 'cltbld', 12:03:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:03:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:03:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:03:44 INFO - 'XPC_FLAGS': '0x0', 12:03:44 INFO - 'XPC_SERVICE_NAME': '0', 12:03:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:03:44 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/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0190', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:03:45 INFO - mozversion application_buildid: 20170516105120 12:03:45 INFO - mozversion application_changeset: 925160e8379600a79d869cf18df4f158e7eb1868 12:03:45 INFO - mozversion application_display_name: Firefox 12:03:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:03:45 INFO - mozversion application_name: Firefox 12:03:45 INFO - mozversion application_remotingname: firefox 12:03:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:03:45 INFO - mozversion application_vendor: Mozilla 12:03:45 INFO - mozversion application_version: 54.0 12:03:45 INFO - mozversion platform_buildid: 20170516105120 12:03:45 INFO - mozversion platform_changeset: 925160e8379600a79d869cf18df4f158e7eb1868 12:03:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:03:45 INFO - mozversion platform_version: 54.0 12:03:45 INFO - using testdate: 1494961425 12:03:45 INFO - actual date: 1494961425 12:03:45 INFO - starting webserver on 'localhost:49219' 12:03:45 INFO - SUITE-START | Running 2 tests 12:03:45 INFO - TEST-START | dromaeo_css 12:03:45 INFO - operating with platform_type : mac_ 12:03:45 INFO - Initialising browser for dromaeo_css test... 12:03:45 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmp_2KlSK/profile http://localhost:49219/getInfo.html) 12:03:47 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 12:03:47 INFO - PROCESS | 1646 | colorDepth:24 12:03:47 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 12:03:47 INFO - PROCESS | 1646 | __metrics 12:03:47 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:03:47 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:03:48 INFO - TEST-INFO | 1646: exit 0 12:03:48 INFO - Browser initialized. 12:03:48 INFO - Running cycle 1/1 for dromaeo_css test... 12:03:48 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmp_2KlSK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:04:00 INFO - PROCESS | 1650 | RSS: Main: 164827136 12:04:00 INFO - PROCESS | 1650 | 12:07:04 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49219/tests/dromaeo/cssquery-ext.html) 12:07:06 INFO - PROCESS | 1650 | RSS: Main: 343646208 12:07:06 INFO - PROCESS | 1650 | 12:10:08 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-ext.html (next: http://localhost:49219/tests/dromaeo/cssquery-jquery.html) 12:10:09 INFO - PROCESS | 1650 | RSS: Main: 303087616 12:10:09 INFO - PROCESS | 1650 | 12:13:11 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49219/tests/dromaeo/cssquery-mootools.html) 12:13:12 INFO - PROCESS | 1650 | RSS: Main: 285966336 12:13:12 INFO - PROCESS | 1650 | 12:16:14 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49219/tests/dromaeo/cssquery-prototype.html) 12:16:15 INFO - PROCESS | 1650 | RSS: Main: 265326592 12:16:15 INFO - PROCESS | 1650 | 12:19:18 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49219/tests/dromaeo/cssquery-yui.html) 12:19:19 INFO - PROCESS | 1650 | RSS: Main: 267276288 12:19:19 INFO - PROCESS | 1650 | 12:22:22 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49219/tests/dromaeo/cssquery-yui.html (next: http://localhost:49219/tests/dromaeo/cssquery-dojo.html) 12:22:22 INFO - PROCESS | 1650 | RSS: Main: 502063104 12:22:22 INFO - PROCESS | 1650 | 12:22:22 INFO - PROCESS | 1650 | __start_tp_report 12:22:22 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 12:22:22 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 12:22:22 INFO - PROCESS | 1650 | |i|pagename|runs| 12:22:22 INFO - PROCESS | 1650 | |0;dojo.html;3470.3059388122374;3483.2511010132826;3503.0891968088304;3537.8761743338987;3538.3630946429666;6106.297775755789;6121.47967422769;6124.6937653117275;6157.938420615793;6164.537659675526;6134.2332208473945;6184.56708030438;6205.720742566584;6211.968940155304;6215.067739839015;5374.4894235047705;5398.163284690879;5435.592330575203;5446.891062178754;5543.861403464905;5365.302510673627;5366.168243922199;5414.187871819219;5535.280413546254;5552.972700050504;5431.619786614939;5466.207399927008;5471.50756431921;5475.890482190353;5489.204065410513;5531.3915469298345;5533.142362933731;5540.63985840919;5544.63959842613;5544.944550554484;5301.60237982149;5391.730413479349;5458.836234912959;5468.808591699271;5499.780008799643;6086.391360863922;6094.299155597118;6104.847378815521;6131.284916201153;6160.829442405929;6418.133551970518;6430.3891130342545;6450.935490645081;6454.612723236574;6458.741650333981;6487.318831522716;6508.804735857884;6529.836754081139;6533.705983230719;6554.213494380657;5059.443461219263;6033.1553582498045;6115.449609535163;6134.8346010261785;6195.535334849904;6712.630805305657;6718.966405167943;6732.764353247613;6738.427233685094;6740.224874139439;6369.458596019297;6377.394147555975;6384.968075159594;6396.968015159988;6403.295637479873;6298.937010629835;6415.647139407378;6520.510961677893;6532.118164047888;6554.606723596599;6300.7479700811455;6312.810615681537;6376.202974628171;6426.4216220540375;6440.227172739301;6249.125122482856;6254.999200128049;6294.307626161119;6317.210348706411;6364.427201551883;5206.895862082737;5226.529612334908;5236.083685355047;5244.187150991603;5257.447967963309;4707.199776038082;4723.126221649006;4768.237082066853;4772.2364421693055;4780.235162374003;5957.017092179742;6001.7599296027665;6046.455818976226;6197.349278325801;6202.472789812917;6371.808845734636;6422.678866056678;6442.581232219891;6531.902021469682;6535.705893234815;5657.783576531066;5697.373288938214;6183.721732522047;6185.536084793568;6195.752169913243;5659.066254068032;5759.6832174229985;5781.53747700183;5784.88430231393;5805.129230615442;6366.618002919654;6438.710258038306;6500.707468164039;6509.967450162719;6721.26066132725;6357.777477788255;6369.49044076482;6371.299157092623;6375.298717141203;6383.0744542042075;6559.639219842955;6613.834654133685;6658.467322614082;6702.865942681216;6705.664716764045;6388.968055159695;6389.9041514376395;6415.807525774234;6419.197600299963;6421.357864213542;6506.251781045051;6520.543561950618;6535.411812936859;6552.63960482178;6556.2788093308745;5033.245013248042;6132.0495871890025;6132.141360525156;6161.5686901918225;6166.013437849922;5319.308489896289;6268.592541484789;6359.936400635935;6370.044493326038;6372.235331760218;5286.286351342674;6255.624662520263;6264.68676566179;6271.247450305992;6289.371062893674;6342.968285158545;6363.7772677956045;6378.170837791057;6391.488680905423;6400.839979000562;6131.957816600114;6174.25908890936;6216.316205217513;6219.253689557282;6244.5316601256 12:22:22 INFO - PROCESS | 1650 | |1;ext.html;746.4327111395338;774.2064384006393;786.3001928283825;808.3613944983464;884.5046773806673;28371.432571348585;40547;40735;40759.38860917085;40948;42003.57996420035;42119.15761684772;42184.78907605465;42332.57667423325;42377;7966.32286255669;9625.518724063786;9767.560459779292;9780.706578802647;9803.803923921518;7917.6832926683155;11488.827667584992;11580.768384632302;11585.36280504572;11601.419929003558;11545.249558778652;11636.592719254828;11658.009069229125;11666.183367164302;11705.473253703562;11600.70998225047;11617.012553932926;11618.767624647544;11638.41807909601;11643.18497705161;11349.319040857492;11391.54433822646;11410.258333208314;11423.828642570285;11448.885511144865;38579.80710096432;38748.41877371842;38758.41862372067;38790.836274911795;38838;3968.285708572456;4018.3369743992207;4053.5946405359523;4064.4512990746166;4092.386142078682;23642.527149457088;27079.025442776237;29868.80524779028;30058.84970575155;30421.631026603856;3166.7313954530673;3255.1699316674335;3363;3437.9484307735406;3441.328940856534;361274;361497;362451;362960;363177.9999999974;52690.47309526932;52786.73606631942;52807;52977.2053419199;53096.73451632717;54681.1797823033;55287;55336;55363.446365536634;55438;15464.613384665248;15548.144852033247;15588.688226235412;15589.064656120669;15605.921970390076;11799.52801887915;11992.64022079339;13226.801597976038;13231.338433078308;13280.800787988188;12221.0223182147;12536.310502922244;12650.81023784644;12760.10679252462;12770.936145319214;5682.374938756733;5740.885182296414;5746.626469279484;5767.307923049261;5780.6820624865195;1089.6186334782922;1123.6404350607727;1139.4302848575712;1167.7897978363806;1169.3101070368523;26184.8690756545;26221;26312.89496840248;26471.602925956126;26814.731852681223;1272.5228039485194;1297.1114786371365;1298.0329654407337;1325.8674132586857;1339.6583871112998;13573.796393054112;13640.931795340959;13928.651783705285;13945.651358716113;13947.372368243454;440396.59603403555;441005.7949710295;441123.79438102606;441606;442076;53588.92822143535;53670;53833.730831345594;53916.19125713118;53944.460555394726;1451.8693317601255;1455.345094707386;1469.2653673163418;1477.5641185850416;1511.1008949674926;26856.32859178458;26895.731042690106;26985;27244;27391.315217119733;39662.40506392464;39814.00464988399;39817.80091099526;39857.60142398539;39925.2014959705;356630;414733;417803;417888;419818;29579.26051848721;30069.699303007565;30893.536596950613;31026.68973310328;31030.68969310278;38592.80703596464;39007.414888776126;39603.80198099106;39910.2017959645;40166.995825104605;27205;27221.911123554837;28543.714562854937;29169.854150729112;29283;27897.721022790323;28344.00795972131;28586.714132859237;28791.712082878905;28973;32464.513032305007;32943.67056329502;33025.33949320952;33193.17017074496;33323.50014749829;28033.878644853976;28053.579196311664;28214.43571128607;29025.98409055673;29070.7092929068;22871.252980370497;22983.88508057516;23059.769402306432;23477.76522234824;24087.759122409254 12:22:22 INFO - PROCESS | 1650 | |2;jquery.html;395.37333326677214;424.5651190422924;436.071168411284;441.9371411754729;446.13004640950146;17796.377126800566;21996.89001554992;22128.557428851414;22182.88908555457;22456.8877155614;23351.299461016155;23454.882725586347;23610.409739756513;23820.5235895282;24347;5638.859028524299;6286;6927.9653601731925;7154.463415243851;7225;6160.260768707762;7126.144862616493;7167.784966451015;7260.346568808806;7267.345938865501;6896.896546551806;7101.147862256536;7117.145942486909;7117.537360071605;7119.857602847966;6799.898001529981;6994.6152961587095;7008.614526201057;7082.893756593655;7092.078029856138;6874.621895795729;6925.58446493212;6943;6955.617441040741;6983.476239282023;27543.724562754316;27714.861425692943;27716.30709232265;27746.167614971582;28108.578371324445;2916.577096321043;2992.5062364709797;3091.48990416581;3109.486934655779;3155.2742869140225;16938;17004.234809433605;17017.57456063596;17199.054052027135;18142.727859082126;2056.609244243589;2105.4420578546697;2107.4942013916702;2115.693224482457;2122.607317646225;137851.31074344664;138851.30574347163;144636;145072.27463862614;148048;16830.495085147464;22552.774472255394;22559.88720056389;22706;23294.83525823736;31560.684393155774;38173.045673858374;38488.23023539514;39223.80388098098;39287.41068883969;9848.753781155385;11357.659270221906;11567.016803571621;11745;11748.471318790675;9481.952590237004;9509.762255943657;9731.221502279801;9736.853947190797;9902;10793.352398856094;11133.276337038067;11159.107271418266;11231.326120432801;11279.718007049889;4408.647308215335;4502.099580083931;4522.683412161117;4540.82040620504;4544.432170901034;891.527490430073;904.6878826804742;918.2424499787702;948.0756262643868;956.206348730552;21136.577268454545;21758.456038598844;21874.89062554677;21961.450963726034;22115.447113822283;930.5486838883136;934.7289286106953;940.6331530703031;952.9618815247451;954.8519979403204;11021.338719676845;11303.021971155014;11405.543778248943;11480.885191148149;11506.539738410369;787007;787295.3176170641;793898.0305098552;795246.023769889;800879;106463.4676826611;125720.37139814242;126137;131603.68396316105;136715.31642341724;1256.2211428914131;1318.0378323823663;1346.7912473566612;1353.9526116585873;1354.3499120422055;15232.923835380752;19393.32123375675;20641.793582063987;20691.793082068987;20694.379168624964;51211.487885122166;57811.710941445024;58678.706606466694;59077;59990.700046499485;76012.61993690218;78871.21128788638;79135;79393;85555;11549.538018479167;11598.304101753922;11624.302541847515;11688.649340519798;11938;11217.158713096713;12977.805332919825;13060.9551235899;13335.399907004403;13515.59453216405;11038.337699738042;11125.666230013112;11308.886911130783;11505.597304094317;11606.12954028468;9848.212143028397;10213.591456341961;10570.78858422812;10695;11010.339379637247;9580.377275477073;11726.824097638713;11844.526218951145;11888.34614096233;11936.672348891883;9595.328327017041;11578.536858525902;11691.883081169079;11773.76452470963;11890.881091189323;11288;11295.830562541403;11475.311481311148;11588.826167607323;11671.474783634587 12:22:22 INFO - PROCESS | 1650 | |3;mootools.html;1619.9271032803522;1621.7486289625106;1650.8761842861784;1654.520189145148;1666.9749953750695;10602.628907988223;11085.50115244814;11200.551977920893;11305.830412543819;11436.828447573293;11015.504302306395;11362.829557556643;11564.53741850327;11580.363080030595;11582.478788454519;2411.975880241197;2455.8035357171384;2457.5207834472285;2459.1761759810397;2463.6304554316907;2367.621180611103;2405.542946840095;2429.1983645396976;2435.8051355891484;2446.877656117196;2350.306659535441;2416.7704068113503;2421.69728783902;2421.69728783902;2423.0671191591277;2336.8013718833918;2387.271882075966;2388.0686532252435;2395.149721848745;2406.338256979327;2350.7061617297836;2373.299876536426;2374.8456350337347;2382.7855493005713;2383.41606306456;11375.772484550345;11601.825972610417;11620.767584648343;11625.534978600846;11628.418579071013;5712.371639119693;5877.764889404419;5902.173695682608;5902.966980778347;5911.852203694899;3464.7574669773126;3537.2748586539697;3545.94681079784;3618.131648404364;3627.0569651890432;1231.1443546735022;1260.8171815086853;1289.8323217981697;1300.5383089003458;1311.3115614302471;13525.661858453519;13653.453861845514;14040.789388159084;14967.476138335105;15551.300191491297;4502.077074199847;4663.347131401607;4668.276417155278;4687;4708.223143181405;12073.939630301793;12191.817122743167;12204.04735242357;12221.388930553438;12225.327606981702;4587.174308624413;4907.288443175719;5058.3929928408825;5741.684207368635;5976.342602313742;5217.973910130425;5249.370075590878;5348.786048558014;5419.105847535191;5438.211459338374;4056.553779084298;4091.97954010234;4158;4171.144915292358;4174.91650167001;954.5370495309769;978.4178413843752;1067.5836423794726;1105.3588918427292;1125.392288164382;980.2060331131806;996.342414006752;1007.0382784440841;1019.2406657040552;1029.9897001029894;6849.048021313902;6948.062011628466;6953.79138625842;7091.468139889529;7176.138863336433;967.9370840895319;1071.7802850415646;1082.1775450657558;1090.8981928252363;1096.5997410945108;4444.6666500012625;4575.862724118282;4709;4810.278458231223;4882;13477.393517291745;13903;13935.372908219155;14319.498817541544;14331.570052898429;14127.293635318398;14153.787693184611;14159.85840141606;14184.290785460893;14205.573832784827;9981.351212171186;10299.227557933185;10580.629677961235;10584.047435730821;10609.893901061045;6729.597442812867;6821.585103154695;6920.30796920304;7059.82350441243;7096.751613693703;11394.886051139714;12186.695332616755;12527.248365098123;12865.356732163167;12878.291693956548;11179.993800557988;12008.519659213534;12035.97694196003;12435.316057616918;12992.155509891827;9493.95253023758;9696.6121355145;9803.558839852367;9810.313278070467;9810.313278070753;10541.209409294486;10555.419451930218;10668.83996740034;10670.253082284165;10686.519106640064;8980.371374003757;9119.954400227956;9140.6800761973;9225.21585665226;9244.630214791334;7596.582187979714;8944.924352124579;9004.774880628036;9044.683436079706;9211.677591284273;8132.634031468479;8301.709440169565;8306.252437280673;8315.043769966289;8317;5985.461308482258;6044.365341639064;6085.300190478151;6151.3541078186145;6198.566100372931;5091.516305950929;6119.265688117454;6137.508999280136;6295.779647712308;6405.647689377129 12:22:22 INFO - PROCESS | 1650 | |4;prototype.html;354.16947258678397;387.5678618340551;388.7181793199932;392.47212499188583;398.20955403524016;4946.505349465061;5171.888348762734;5179.559737422319;5216.921746173811;5228.633995620308;4812.422509298889;5054.444011158769;5070.768861700849;5117.283580298761;5117.820876269331;3624.7281453890932;3808.352580061396;3902.941455878164;4212.768297743631;4296.613304802567;3511.6312787157362;4310.655147588185;4311.223979683656;4313.633341165989;4332.263515202423;4156.8129434175535;4306.483222013347;4312.956870431287;4317.503487098977;4327;3652.8904132876055;3655.4334078217917;3850.114473671071;3861.0540417597867;4218.97890510547;3652.415613501827;3960.7623542587535;3963.7117936670697;3979.303621866191;3981.5620281768984;4802.358702713494;4808.038392321515;4931.805346620755;5074.568661663763;5128.8461346159675;4755.476897541303;4992.900141997176;5005.598432438923;5016.347874776256;5050.924236136461;4808.567228949411;5072.771725272371;5287.550558202557;5506.339239291271;5523.398214517785;4002.4396584478195;4258.61672449481;4421.8452354167775;4450.421445212101;4457.064016556527;5461.808836690697;5490.121580547133;5526.696031718254;5609;5855.912161317584;4037.7779222142476;4398.362237475548;4533.9319910201375;4547.636189104864;4555.496686093647;4658.254679251304;4697.177993851063;4752.930590617153;4764.0471905619015;4774.785134668949;4367.890802729894;4784.091022705675;4944.777485013183;4976.715367857197;4993.900121997613;4678.0410015946645;4934.605231581465;5290.338707661543;5433.565314774887;5455.863603409867;1451.4484495891493;1466.8606482384157;1503.7218364390399;1549.635835578638;1572.2610373124612;1082.983755243672;1183.9052875769917;1187.0503597122267;1195.1753290229888;1217.2027322104036;4773.427188737373;4899.755012249373;4970.254461830682;5158.246196293278;5222.294990176353;5047.551937310402;5068.797248110108;5187.533122019036;5312.681239125587;5650.604457688005;4466.917826699631;5015.924761128586;5150.974245128826;5157.060475528988;5179.7928082877015;2566.666333376649;2916.3584011517432;2944.263934016496;2956.512175491063;2962.762978961678;5319.946800531945;5907.556933229939;5911.68945390574;5923.674197919069;5928.703564821742;5201.479852014844;5308.575313974873;5453.15476101205;5483.670979741229;5523.25436066134;4927.531884470969;5535.750891209906;5618.550515958714;5920.615160014499;5983.760649574142;4951.653384263067;5428.402875683751;5747.798827041033;5771.759071799584;5795.39148389413;5047.116754568009;5287.3919499256535;5648.71756412186;5667.716614169192;5692.459216374438;5884.176215329943;5887.174975757529;5922.3485416605;5932.6737029462065;5954.285485741739;4033.4756481657196;4053;4433.445819272591;4595.126925884071;4649.209634362099;5233.842984710464;5293.206019097012;5482.314710661168;5655.830325090254;5767.250257466502;5121.89879175979;5579.16312553104;5586.38549759518;5586.972065139811;5620.269364982526;4718.740469274223;5220.973895130652;5246.42289348179;5577.498025177753;5609.80365687199;4785.827472269316;4838.612910967185;5248.396434409939;5450.400455949922;5471.057774489998;4991.251312303184;5177.430482646982;5192.740362981911;5536.598404268536;5579.7489112990825;4729.976350118227;4922.3600931880055;5025.447200807981;5086.847394578169;5199 12:22:22 INFO - PROCESS | 1650 | |5;yui.html;738.6639079218955;755.2296657409443;775.1318523253957;845.1759534453909;860.74177746676;15451.304691288891;16657.167141642916;16703.582410439743;16733.247003884822;16773.49679509615;15748;15799.526014219564;15802.288896999635;15813.209339533007;15933.84066159338;952.5380190607567;961.1205746741758;970.8009857979098;979.9069088436587;994.393420013791;988.8170688422629;992.1417973452948;992.4194346307419;992.9602815887356;993.3295025857553;1026.8408396698487;1027.7687520307945;1027.9023492768213;1027.9229057820655;1029.490402250887;1024.451918223754;1024.7489365105596;1024.9026342497489;1025.9538320775582;1028.9742756431074;745.9888101678479;747.3610063395827;748.9962550187214;749.9625018749041;753.8982237397936;17141.971481711138;17143.399981000606;17266.827331726647;17924.641507169916;18126.728099078526;12888.54890078852;14496.855031449655;14530.346134423977;14616.780748288784;14653.70692586153;11618.825717614243;11652.883471165349;11714.062874969983;11819.34993575353;11841.763164736656;3714.7771133732062;3777.660010599059;3860.749051311656;3868.941965870514;3999.8200080996135;114298.42850785692;121693.78306217001;123168;123772;123939.3803030988;10957.868507567273;11242.887571124347;11306.94346528262;11432.714182145346;11433.77041476904;22562.097516099173;22858.314250572508;22933.770662293162;22967.770322296896;22987;7695.6921723130445;7840.13758486566;7842.764717058498;8044.879326810103;8064.233897779702;4398.934015989763;4462.709923854939;4545.65907556928;4662.463816661128;4720.881977950578;3438.157651375429;3458.3083383323437;3479.251960828434;3488.3720930232803;3515.5781306243416;607.5746977116037;613.5459759777732;620.7827260458803;622.9439350458481;626.3923993726079;635.2789583822395;654.6399480285824;676.9796906092826;683.550872151033;691.4442504365178;9945.80108397828;10954.616588419367;11399.373034483016;11501.654950351503;11540.884591153981;601.506764453153;625.8623102917351;626.4737620398887;663.0154220981838;743.4201322968094;4283.657307415399;4681.9297710534365;4890.12442417012;5081.898362032739;5157.277981082651;72757;91846.54076729574;92746.07253927509;96506.51746741221;96954.51522742341;54797.45202548003;65974.67012664906;71416;82618;86333.5683321592;70971.87084387477;71424.64287678528;71795.64102179455;74358.14104647427;74489.62755186298;10915.78168436611;11073.501692423699;11187.309917108796;11254;11329.206955513035;38125.046873828374;40025.59974400219;40202.59797401988;43486.69539913808;44065.67802965916;42600.786996064824;58992;59084.40915590789;59491;60098.39901600928;12894;13106.017048721193;13173.341332933123;13270.668233294246;13826.170429774245;14420.34779132512;14516;14869.702605947605;15073.321700523284;15200.163990980602;13421.463141474233;13422.798658020332;13446.46214151423;13913.860861391662;14147.858521414653;13276.867231327564;13756.65608359799;14360.497382591559;14593.635159121108;14629.268536572916;13795.034347595572;13854.479495110232;13878.73050670097;13936.039299112794;13996.720065598427;9754.9024509754;10150.492475376053;10256.12822910061;10359.430231337048;10365.378077315385;7859.882101768592;10039.269419993956;10072.244581656552;10334.780935627761;11447.02700270453 12:22:22 INFO - PROCESS | 1650 | __end_tp_report 12:22:22 INFO - PROCESS | 1650 | __start_cc_report 12:22:22 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,1829 12:22:22 INFO - PROCESS | 1650 | __end_cc_report 12:22:22 INFO - PROCESS | 1650 | __startTimestamp1494962542969__endTimestamp 12:22:22 INFO - PROCESS | 1650 | 12:22:22 INFO - PROCESS | 1650 | ------- Summary: start ------- 12:22:22 INFO - PROCESS | 1650 | Number of tests: 6 12:22:22 INFO - PROCESS | 1650 | 12:22:22 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5994.83 Median:6211.97 stddev:655.04 (10.5%) stddev-sans-first:626.40 12:22:22 INFO - PROCESS | 1650 | Values: 3470.3 3483.3 3503.1 3537.9 3538.4 6106.3 6121.5 6124.7 6157.9 6164.5 6134.2 6184.6 6205.7 6212.0 6215.1 5374.5 5398.2 5435.6 5446.9 5543.9 5365.3 5366.2 5414.2 5535.3 5553.0 5431.6 5466.2 5471.5 5475.9 5489.2 5531.4 5533.1 5540.6 5544.6 5544.9 5301.6 5391.7 5458.8 5468.8 5499.8 6086.4 6094.3 6104.8 6131.3 6160.8 6418.1 6430.4 6450.9 6454.6 6458.7 6487.3 6508.8 6529.8 6533.7 6554.2 5059.4 6033.2 6115.4 6134.8 6195.5 6712.6 6719.0 6732.8 6738.4 6740.2 6369.5 6377.4 6385.0 6397.0 6403.3 6298.9 6415.6 6520.5 6532.1 6554.6 6300.7 6312.8 6376.2 6426.4 6440.2 6249.1 6255.0 6294.3 6317.2 6364.4 5206.9 5226.5 5236.1 5244.2 5257.4 4707.2 4723.1 4768.2 4772.2 4780.2 5957.0 6001.8 6046.5 6197.3 6202.5 6371.8 6422.7 6442.6 6531.9 6535.7 5657.8 5697.4 6183.7 6185.5 6195.8 5659.1 5759.7 5781.5 5784.9 5805.1 6366.6 6438.7 6500.7 6510.0 6721.3 6357.8 6369.5 6371.3 6375.3 6383.1 6559.6 6613.8 6658.5 6702.9 6705.7 6389.0 6389.9 6415.8 6419.2 6421.4 6506.3 6520.5 6535.4 6552.6 6556.3 5033.2 6132.0 6132.1 6161.6 6166.0 5319.3 6268.6 6359.9 6370.0 6372.2 5286.3 6255.6 6264.7 6271.2 6289.4 6343.0 6363.8 6378.2 6391.5 6400.8 6132.0 6174.3 6216.3 6219.3 6244.5 12:22:22 INFO - PROCESS | 1650 | 12:22:22 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:54178.58 Median:30245.67 stddev:107080.58 (354.0%) stddev-sans-first:107305.82 12:22:22 INFO - PROCESS | 1650 | Values: 746.4 774.2 786.3 808.4 884.5 28371.4 40547.0 40735.0 40759.4 40948.0 42003.6 42119.2 42184.8 42332.6 42377.0 7966.3 9625.5 9767.6 9780.7 9803.8 7917.7 11488.8 11580.8 11585.4 11601.4 11545.2 11636.6 11658.0 11666.2 11705.5 11600.7 11617.0 11618.8 11638.4 11643.2 11349.3 11391.5 11410.3 11423.8 11448.9 38579.8 38748.4 38758.4 38790.8 38838.0 3968.3 4018.3 4053.6 4064.5 4092.4 23642.5 27079.0 29868.8 30058.8 30421.6 3166.7 3255.2 3363.0 3437.9 3441.3 361274.0 361497.0 362451.0 362960.0 363178.0 52690.5 52786.7 52807.0 52977.2 53096.7 54681.2 55287.0 55336.0 55363.4 55438.0 15464.6 15548.1 15588.7 15589.1 15605.9 11799.5 11992.6 13226.8 13231.3 13280.8 12221.0 12536.3 12650.8 12760.1 12770.9 5682.4 5740.9 5746.6 5767.3 5780.7 1089.6 1123.6 1139.4 1167.8 1169.3 26184.9 26221.0 26312.9 26471.6 26814.7 1272.5 1297.1 1298.0 1325.9 1339.7 13573.8 13640.9 13928.7 13945.7 13947.4 440396.6 441005.8 441123.8 441606.0 442076.0 53588.9 53670.0 53833.7 53916.2 53944.5 1451.9 1455.3 1469.3 1477.6 1511.1 26856.3 26895.7 26985.0 27244.0 27391.3 39662.4 39814.0 39817.8 39857.6 39925.2 356630.0 414733.0 417803.0 417888.0 419818.0 29579.3 30069.7 30893.5 31026.7 31030.7 38592.8 39007.4 39603.8 39910.2 40167.0 27205.0 27221.9 28543.7 29169.9 29283.0 27897.7 28344.0 28586.7 28791.7 28973.0 32464.5 32943.7 33025.3 33193.2 33323.5 28033.9 28053.6 28214.4 29026.0 29070.7 22871.3 22983.9 23059.8 23477.8 24087.8 12:22:22 INFO - PROCESS | 1650 | 12:22:22 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:43335.77 Median:23323.07 stddev:130905.13 (561.3%) stddev-sans-first:131232.65 12:22:22 INFO - PROCESS | 1650 | Values: 395.4 424.6 436.1 441.9 446.1 17796.4 21996.9 22128.6 22182.9 22456.9 23351.3 23454.9 23610.4 23820.5 24347.0 5638.9 6286.0 6928.0 7154.5 7225.0 6160.3 7126.1 7167.8 7260.3 7267.3 6896.9 7101.1 7117.1 7117.5 7119.9 6799.9 6994.6 7008.6 7082.9 7092.1 6874.6 6925.6 6943.0 6955.6 6983.5 27543.7 27714.9 27716.3 27746.2 28108.6 2916.6 2992.5 3091.5 3109.5 3155.3 16938.0 17004.2 17017.6 17199.1 18142.7 2056.6 2105.4 2107.5 2115.7 2122.6 137851.3 138851.3 144636.0 145072.3 148048.0 16830.5 22552.8 22559.9 22706.0 23294.8 31560.7 38173.0 38488.2 39223.8 39287.4 9848.8 11357.7 11567.0 11745.0 11748.5 9482.0 9509.8 9731.2 9736.9 9902.0 10793.4 11133.3 11159.1 11231.3 11279.7 4408.6 4502.1 4522.7 4540.8 4544.4 891.5 904.7 918.2 948.1 956.2 21136.6 21758.5 21874.9 21961.5 22115.4 930.5 934.7 940.6 953.0 954.9 11021.3 11303.0 11405.5 11480.9 11506.5 787007.0 787295.3 793898.0 795246.0 800879.0 106463.5 125720.4 126137.0 131603.7 136715.3 1256.2 1318.0 1346.8 1354.0 1354.3 15232.9 19393.3 20641.8 20691.8 20694.4 51211.5 57811.7 58678.7 59077.0 59990.7 76012.6 78871.2 79135.0 79393.0 85555.0 11549.5 11598.3 11624.3 11688.6 11938.0 11217.2 12977.8 13061.0 13335.4 13515.6 11038.3 11125.7 11308.9 11505.6 11606.1 9848.2 10213.6 10570.8 10695.0 11010.3 9580.4 11726.8 11844.5 11888.3 11936.7 9595.3 11578.5 11691.9 11773.8 11890.9 11288.0 11295.8 11475.3 11588.8 11671.5 12:22:22 INFO - PROCESS | 1650 | 12:22:22 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:6883.67 Median:2422.38 stddev:4263.68 (176.0%) stddev-sans-first:4257.29 12:22:22 INFO - PROCESS | 1650 | Values: 1619.9 1621.7 1650.9 1654.5 1667.0 10602.6 11085.5 11200.6 11305.8 11436.8 11015.5 11362.8 11564.5 11580.4 11582.5 2412.0 2455.8 2457.5 2459.2 2463.6 2367.6 2405.5 2429.2 2435.8 2446.9 2350.3 2416.8 2421.7 2421.7 2423.1 2336.8 2387.3 2388.1 2395.1 2406.3 2350.7 2373.3 2374.8 2382.8 2383.4 11375.8 11601.8 11620.8 11625.5 11628.4 5712.4 5877.8 5902.2 5903.0 5911.9 3464.8 3537.3 3545.9 3618.1 3627.1 1231.1 1260.8 1289.8 1300.5 1311.3 13525.7 13653.5 14040.8 14967.5 15551.3 4502.1 4663.3 4668.3 4687.0 4708.2 12073.9 12191.8 12204.0 12221.4 12225.3 4587.2 4907.3 5058.4 5741.7 5976.3 5218.0 5249.4 5348.8 5419.1 5438.2 4056.6 4092.0 4158.0 4171.1 4174.9 954.5 978.4 1067.6 1105.4 1125.4 980.2 996.3 1007.0 1019.2 1030.0 6849.0 6948.1 6953.8 7091.5 7176.1 967.9 1071.8 1082.2 1090.9 1096.6 4444.7 4575.9 4709.0 4810.3 4882.0 13477.4 13903.0 13935.4 14319.5 14331.6 14127.3 14153.8 14159.9 14184.3 14205.6 9981.4 10299.2 10580.6 10584.0 10609.9 6729.6 6821.6 6920.3 7059.8 7096.8 11394.9 12186.7 12527.2 12865.4 12878.3 11180.0 12008.5 12036.0 12435.3 12992.2 9494.0 9696.6 9803.6 9810.3 9810.3 10541.2 10555.4 10668.8 10670.3 10686.5 8980.4 9120.0 9140.7 9225.2 9244.6 7596.6 8944.9 9004.8 9044.7 9211.7 8132.6 8301.7 8306.3 8315.0 8317.0 5985.5 6044.4 6085.3 6151.4 6198.6 5091.5 6119.3 6137.5 6295.8 6405.6 12:22:22 INFO - PROCESS | 1650 | 12:22:22 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4596.81 Median:4993.40 stddev:1275.36 (25.5%) stddev-sans-first:1238.55 12:22:22 INFO - PROCESS | 1650 | Values: 354.2 387.6 388.7 392.5 398.2 4946.5 5171.9 5179.6 5216.9 5228.6 4812.4 5054.4 5070.8 5117.3 5117.8 3624.7 3808.4 3902.9 4212.8 4296.6 3511.6 4310.7 4311.2 4313.6 4332.3 4156.8 4306.5 4313.0 4317.5 4327.0 3652.9 3655.4 3850.1 3861.1 4219.0 3652.4 3960.8 3963.7 3979.3 3981.6 4802.4 4808.0 4931.8 5074.6 5128.8 4755.5 4992.9 5005.6 5016.3 5050.9 4808.6 5072.8 5287.6 5506.3 5523.4 4002.4 4258.6 4421.8 4450.4 4457.1 5461.8 5490.1 5526.7 5609.0 5855.9 4037.8 4398.4 4533.9 4547.6 4555.5 4658.3 4697.2 4752.9 4764.0 4774.8 4367.9 4784.1 4944.8 4976.7 4993.9 4678.0 4934.6 5290.3 5433.6 5455.9 1451.4 1466.9 1503.7 1549.6 1572.3 1083.0 1183.9 1187.1 1195.2 1217.2 4773.4 4899.8 4970.3 5158.2 5222.3 5047.6 5068.8 5187.5 5312.7 5650.6 4466.9 5015.9 5151.0 5157.1 5179.8 2566.7 2916.4 2944.3 2956.5 2962.8 5319.9 5907.6 5911.7 5923.7 5928.7 5201.5 5308.6 5453.2 5483.7 5523.3 4927.5 5535.8 5618.6 5920.6 5983.8 4951.7 5428.4 5747.8 5771.8 5795.4 5047.1 5287.4 5648.7 5667.7 5692.5 5884.2 5887.2 5922.3 5932.7 5954.3 4033.5 4053.0 4433.4 4595.1 4649.2 5233.8 5293.2 5482.3 5655.8 5767.3 5121.9 5579.2 5586.4 5587.0 5620.3 4718.7 5221.0 5246.4 5577.5 5609.8 4785.8 4838.6 5248.4 5450.4 5471.1 4991.3 5177.4 5192.7 5536.6 5579.7 4730.0 4922.4 5025.4 5086.8 5199.0 12:22:22 INFO - PROCESS | 1650 | 12:22:22 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:19658.71 Median:22977.39 stddev:27934.53 (121.6%) stddev-sans-first:27976.77 12:22:22 INFO - PROCESS | 1650 | Values: 738.7 755.2 775.1 845.2 860.7 15451.3 16657.2 16703.6 16733.2 16773.5 15748.0 15799.5 15802.3 15813.2 15933.8 952.5 961.1 970.8 979.9 994.4 988.8 992.1 992.4 993.0 993.3 1026.8 1027.8 1027.9 1027.9 1029.5 1024.5 1024.7 1024.9 1026.0 1029.0 746.0 747.4 749.0 750.0 753.9 17142.0 17143.4 17266.8 17924.6 18126.7 12888.5 14496.9 14530.3 14616.8 14653.7 11618.8 11652.9 11714.1 11819.3 11841.8 3714.8 3777.7 3860.7 3868.9 3999.8 114298.4 121693.8 123168.0 123772.0 123939.4 10957.9 11242.9 11306.9 11432.7 11433.8 22562.1 22858.3 22933.8 22967.8 22987.0 7695.7 7840.1 7842.8 8044.9 8064.2 4398.9 4462.7 4545.7 4662.5 4720.9 3438.2 3458.3 3479.3 3488.4 3515.6 607.6 613.5 620.8 622.9 626.4 635.3 654.6 677.0 683.6 691.4 9945.8 10954.6 11399.4 11501.7 11540.9 601.5 625.9 626.5 663.0 743.4 4283.7 4681.9 4890.1 5081.9 5157.3 72757.0 91846.5 92746.1 96506.5 96954.5 54797.5 65974.7 71416.0 82618.0 86333.6 70971.9 71424.6 71795.6 74358.1 74489.6 10915.8 11073.5 11187.3 11254.0 11329.2 38125.0 40025.6 40202.6 43486.7 44065.7 42600.8 58992.0 59084.4 59491.0 60098.4 12894.0 13106.0 13173.3 13270.7 13826.2 14420.3 14516.0 14869.7 15073.3 15200.2 13421.5 13422.8 13446.5 13913.9 14147.9 13276.9 13756.7 14360.5 14593.6 14629.3 13795.0 13854.5 13878.7 13936.0 13996.7 9754.9 10150.5 10256.1 10359.4 10365.4 7859.9 10039.3 10072.2 10334.8 11447.0 12:22:22 INFO - PROCESS | 1650 | -------- Summary: end -------- 12:22:22 INFO - PROCESS | 1650 | 12:22:23 INFO - TEST-INFO | 1650: exit 0 12:22:23 INFO - TEST-OK | dromaeo_css | took 1118531ms 12:22:23 INFO - TEST-START | kraken 12:22:23 INFO - operating with platform_type : mac_ 12:22:23 INFO - Initialising browser for kraken test... 12:22:23 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmpSe2pby/profile http://localhost:49219/getInfo.html) 12:22:25 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 12:22:25 INFO - PROCESS | 1658 | colorDepth:24 12:22:25 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 12:22:25 INFO - PROCESS | 1658 | __metrics 12:22:25 INFO - PROCESS | 1658 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:22:25 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:22:26 INFO - TEST-INFO | 1658: exit 0 12:22:26 INFO - Browser initialized. 12:22:26 INFO - Running cycle 1/1 for kraken test... 12:22:26 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/T/tmpSe2pby/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:22:38 INFO - PROCESS | 1660 | RSS: Main: 165076992 12:22:38 INFO - PROCESS | 1660 | 12:22:41 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection) 12:22:42 INFO - PROCESS | 1660 | RSS: Main: 217407488 12:22:42 INFO - PROCESS | 1660 | 12:22:44 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-dft) 12:22:45 INFO - PROCESS | 1660 | RSS: Main: 233807872 12:22:45 INFO - PROCESS | 1660 | 12:22:48 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-fft) 12:22:49 INFO - PROCESS | 1660 | RSS: Main: 328548352 12:22:49 INFO - PROCESS | 1660 | 12:22:51 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator) 12:22:51 INFO - PROCESS | 1660 | RSS: Main: 230813696 12:22:51 INFO - PROCESS | 1660 | 12:22:53 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:22:54 INFO - PROCESS | 1660 | RSS: Main: 231981056 12:22:54 INFO - PROCESS | 1660 | 12:22:57 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom) 12:22:57 INFO - PROCESS | 1660 | RSS: Main: 271486976 12:22:57 INFO - PROCESS | 1660 | 12:23:01 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate) 12:23:01 INFO - PROCESS | 1660 | RSS: Main: 273235968 12:23:01 INFO - PROCESS | 1660 | 12:23:04 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial) 12:23:05 INFO - PROCESS | 1660 | RSS: Main: 269058048 12:23:05 INFO - PROCESS | 1660 | 12:23:06 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:23:07 INFO - PROCESS | 1660 | RSS: Main: 247500800 12:23:07 INFO - PROCESS | 1660 | 12:23:09 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:23:10 INFO - PROCESS | 1660 | RSS: Main: 252375040 12:23:10 INFO - PROCESS | 1660 | 12:23:12 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:23:12 INFO - PROCESS | 1660 | RSS: Main: 258519040 12:23:12 INFO - PROCESS | 1660 | 12:23:15 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:23:16 INFO - PROCESS | 1660 | RSS: Main: 271519744 12:23:16 INFO - PROCESS | 1660 | 12:23:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:23:19 INFO - PROCESS | 1660 | RSS: Main: 265138176 12:23:19 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49219/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49219/tests/kraken/driver.html?testName=ai-astar) 12:23:21 INFO - PROCESS | 1660 | RSS: Main: 309047296 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | __start_tp_report 12:23:21 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 12:23:21 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 12:23:21 INFO - PROCESS | 1660 | |i|pagename|runs| 12:23:21 INFO - PROCESS | 1660 | |0;ai-astar;103;116;118;115;115;117;116;114;115;115 12:23:21 INFO - PROCESS | 1660 | |1;audio-beat-detection;142;109;119;145;108;107;108;108;127;108 12:23:21 INFO - PROCESS | 1660 | |2;audio-dft;185;194;256;204;185;185;181;185;185;186 12:23:21 INFO - PROCESS | 1660 | |3;audio-fft;82;61;58;91;61;60;60;60;76;59 12:23:21 INFO - PROCESS | 1660 | |4;audio-oscillator;85;68;85;69;70;67;83;68;70;82 12:23:21 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;91;91;91;92;91;90;91;91;96;91 12:23:21 INFO - PROCESS | 1660 | |6;imaging-darkroom;97;97;98;97;108;149;149;97;96;97 12:23:21 INFO - PROCESS | 1660 | |7;imaging-desaturate;85;100;136;127;86;84;85;85;85;86 12:23:21 INFO - PROCESS | 1660 | |8;json-parse-financial;63;60;59;61;62;65;64;66;63;64 12:23:21 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;54;66;62;64;60;58;57;57;59;58 12:23:21 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;84;83;83;81;84;80;80;81;81;81 12:23:21 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;136;138;134;124;135;131;136;140;181;147 12:23:21 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;160;158;162;161;161;161;161;160;161;162 12:23:21 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;64;63;62;63;63;56;55;82;76;77 12:23:21 INFO - PROCESS | 1660 | __end_tp_report 12:23:21 INFO - PROCESS | 1660 | __start_cc_report 12:23:21 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2170 12:23:21 INFO - PROCESS | 1660 | __end_cc_report 12:23:21 INFO - PROCESS | 1660 | __startTimestamp1494962601824__endTimestamp 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | ------- Summary: start ------- 12:23:21 INFO - PROCESS | 1660 | Number of tests: 14 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:114.40 Median:115.50 stddev:4.17 (3.6%) stddev-sans-first:1.22 12:23:21 INFO - PROCESS | 1660 | Values: 103.0 116.0 118.0 115.0 115.0 117.0 116.0 114.0 115.0 115.0 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:118.10 Median:114.00 stddev:14.87 (13.0%) stddev-sans-first:13.01 12:23:21 INFO - PROCESS | 1660 | Values: 142.0 109.0 119.0 145.0 108.0 107.0 108.0 108.0 127.0 108.0 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:194.60 Median:185.50 stddev:22.55 (12.2%) stddev-sans-first:23.65 12:23:21 INFO - PROCESS | 1660 | Values: 185.0 194.0 256.0 204.0 185.0 185.0 181.0 185.0 185.0 186.0 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:66.80 Median:61.00 stddev:11.76 (19.3%) stddev-sans-first:11.12 12:23:21 INFO - PROCESS | 1660 | Values: 82.0 61.0 58.0 91.0 61.0 60.0 60.0 60.0 76.0 59.0 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:74.70 Median:76.00 stddev:7.89 (10.4%) stddev-sans-first:7.43 12:23:21 INFO - PROCESS | 1660 | Values: 85.0 68.0 85.0 69.0 70.0 67.0 83.0 68.0 70.0 82.0 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:91.00 stddev:1.65 (1.8%) stddev-sans-first:1.74 12:23:21 INFO - PROCESS | 1660 | Values: 91.0 91.0 91.0 92.0 91.0 90.0 91.0 91.0 96.0 91.0 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:108.50 Median:97.00 stddev:21.62 (22.3%) stddev-sans-first:22.53 12:23:21 INFO - PROCESS | 1660 | Values: 97.0 97.0 98.0 97.0 108.0 149.0 149.0 97.0 96.0 97.0 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:85.00 stddev:19.45 (22.9%) stddev-sans-first:20.23 12:23:21 INFO - PROCESS | 1660 | Values: 85.0 100.0 136.0 127.0 86.0 84.0 85.0 85.0 85.0 86.0 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:63.50 stddev:2.21 (3.5%) stddev-sans-first:2.35 12:23:21 INFO - PROCESS | 1660 | Values: 63.0 60.0 59.0 61.0 62.0 65.0 64.0 66.0 63.0 64.0 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.50 stddev:3.60 (6.0%) stddev-sans-first:3.22 12:23:21 INFO - PROCESS | 1660 | Values: 54.0 66.0 62.0 64.0 60.0 58.0 57.0 57.0 59.0 58.0 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:81.80 Median:82.00 stddev:1.55 (1.9%) stddev-sans-first:1.42 12:23:21 INFO - PROCESS | 1660 | Values: 84.0 83.0 83.0 81.0 84.0 80.0 80.0 81.0 81.0 81.0 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:140.20 Median:137.00 stddev:15.50 (11.3%) stddev-sans-first:16.37 12:23:21 INFO - PROCESS | 1660 | Values: 136.0 138.0 134.0 124.0 135.0 131.0 136.0 140.0 181.0 147.0 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:160.70 Median:161.00 stddev:1.16 (0.7%) stddev-sans-first:1.20 12:23:21 INFO - PROCESS | 1660 | Values: 160.0 158.0 162.0 161.0 161.0 161.0 161.0 160.0 161.0 162.0 12:23:21 INFO - PROCESS | 1660 | 12:23:21 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:63.50 stddev:9.10 (14.3%) stddev-sans-first:9.62 12:23:21 INFO - PROCESS | 1660 | Values: 64.0 63.0 62.0 63.0 63.0 56.0 55.0 82.0 76.0 77.0 12:23:21 INFO - PROCESS | 1660 | -------- Summary: end -------- 12:23:21 INFO - PROCESS | 1660 | 12:23:22 INFO - TEST-INFO | 1660: exit 0 12:23:22 INFO - TEST-OK | kraken | took 59097ms 12:23:22 INFO - SUITE-END | took 1177s 12:23:22 INFO - Completed test suite (00:19:37) 12:23:22 INFO - javascript benchmark 12:23:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7806.536708280469, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3470.3059388122374, 3483.2511010132826, 3503.0891968088304, 3537.8761743338987, 3538.3630946429666, 6106.297775755789, 6121.47967422769, 6124.6937653117275, 6157.938420615793, 6164.537659675526, 6134.2332208473945, 6184.56708030438, 6205.720742566584, 6211.968940155304, 6215.067739839015, 5374.4894235047705, 5398.163284690879, 5435.592330575203, 5446.891062178754, 5543.861403464905, 5365.302510673627, 5366.168243922199, 5414.187871819219, 5535.280413546254, 5552.972700050504, 5431.619786614939, 5466.207399927008, 5471.50756431921, 5475.890482190353, 5489.204065410513, 5531.3915469298345, 5533.142362933731, 5540.63985840919, 5544.63959842613, 5544.944550554484, 5301.60237982149, 5391.730413479349, 5458.836234912959, 5468.808591699271, 5499.780008799643, 6086.391360863922, 6094.299155597118, 6104.847378815521, 6131.284916201153, 6160.829442405929, 6418.133551970518, 6430.3891130342545, 6450.935490645081, 6454.612723236574, 6458.741650333981, 6487.318831522716, 6508.804735857884, 6529.836754081139, 6533.705983230719, 6554.213494380657, 5059.443461219263, 6033.1553582498045, 6115.449609535163, 6134.8346010261785, 6195.535334849904, 6712.630805305657, 6718.966405167943, 6732.764353247613, 6738.427233685094, 6740.224874139439, 6369.458596019297, 6377.394147555975, 6384.968075159594, 6396.968015159988, 6403.295637479873, 6298.937010629835, 6415.647139407378, 6520.510961677893, 6532.118164047888, 6554.606723596599, 6300.7479700811455, 6312.810615681537, 6376.202974628171, 6426.4216220540375, 6440.227172739301, 6249.125122482856, 6254.999200128049, 6294.307626161119, 6317.210348706411, 6364.427201551883, 5206.895862082737, 5226.529612334908, 5236.083685355047, 5244.187150991603, 5257.447967963309, 4707.199776038082, 4723.126221649006, 4768.237082066853, 4772.2364421693055, 4780.235162374003, 5957.017092179742, 6001.7599296027665, 6046.455818976226, 6197.349278325801, 6202.472789812917, 6371.808845734636, 6422.678866056678, 6442.581232219891, 6531.902021469682, 6535.705893234815, 5657.783576531066, 5697.373288938214, 6183.721732522047, 6185.536084793568, 6195.752169913243, 5659.066254068032, 5759.6832174229985, 5781.53747700183, 5784.88430231393, 5805.129230615442, 6366.618002919654, 6438.710258038306, 6500.707468164039, 6509.967450162719, 6721.26066132725, 6357.777477788255, 6369.49044076482, 6371.299157092623, 6375.298717141203, 6383.0744542042075, 6559.639219842955, 6613.834654133685, 6658.467322614082, 6702.865942681216, 6705.664716764045, 6388.968055159695, 6389.9041514376395, 6415.807525774234, 6419.197600299963, 6421.357864213542, 6506.251781045051, 6520.543561950618, 6535.411812936859, 6552.63960482178, 6556.2788093308745, 5033.245013248042, 6132.0495871890025, 6132.141360525156, 6161.5686901918225, 6166.013437849922, 5319.308489896289, 6268.592541484789, 6359.936400635935, 6370.044493326038, 6372.235331760218, 5286.286351342674, 6255.624662520263, 6264.68676566179, 6271.247450305992, 6289.371062893674, 6342.968285158545, 6363.7772677956045, 6378.170837791057, 6391.488680905423, 6400.839979000562, 6131.957816600114, 6174.25908890936, 6216.316205217513, 6219.253689557282, 6244.5316601256], "value": 5954.609670613182, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.4327111395338, 774.2064384006393, 786.3001928283825, 808.3613944983464, 884.5046773806673, 28371.432571348585, 40547.0, 40735.0, 40759.38860917085, 40948.0, 42003.57996420035, 42119.15761684772, 42184.78907605465, 42332.57667423325, 42377.0, 7966.32286255669, 9625.518724063786, 9767.560459779292, 9780.706578802647, 9803.803923921518, 7917.6832926683155, 11488.827667584992, 11580.768384632302, 11585.36280504572, 11601.419929003558, 11545.249558778652, 11636.592719254828, 11658.009069229125, 11666.183367164302, 11705.473253703562, 11600.70998225047, 11617.012553932926, 11618.767624647544, 11638.41807909601, 11643.18497705161, 11349.319040857492, 11391.54433822646, 11410.258333208314, 11423.828642570285, 11448.885511144865, 38579.80710096432, 38748.41877371842, 38758.41862372067, 38790.836274911795, 38838.0, 3968.285708572456, 4018.3369743992207, 4053.5946405359523, 4064.4512990746166, 4092.386142078682, 23642.527149457088, 27079.025442776237, 29868.80524779028, 30058.84970575155, 30421.631026603856, 3166.7313954530673, 3255.1699316674335, 3363.0, 3437.9484307735406, 3441.328940856534, 361274.0, 361497.0, 362451.0, 362960.0, 363177.9999999974, 52690.47309526932, 52786.73606631942, 52807.0, 52977.2053419199, 53096.73451632717, 54681.1797823033, 55287.0, 55336.0, 55363.446365536634, 55438.0, 15464.613384665248, 15548.144852033247, 15588.688226235412, 15589.064656120669, 15605.921970390076, 11799.52801887915, 11992.64022079339, 13226.801597976038, 13231.338433078308, 13280.800787988188, 12221.0223182147, 12536.310502922244, 12650.81023784644, 12760.10679252462, 12770.936145319214, 5682.374938756733, 5740.885182296414, 5746.626469279484, 5767.307923049261, 5780.6820624865195, 1089.6186334782922, 1123.6404350607727, 1139.4302848575712, 1167.7897978363806, 1169.3101070368523, 26184.8690756545, 26221.0, 26312.89496840248, 26471.602925956126, 26814.731852681223, 1272.5228039485194, 1297.1114786371365, 1298.0329654407337, 1325.8674132586857, 1339.6583871112998, 13573.796393054112, 13640.931795340959, 13928.651783705285, 13945.651358716113, 13947.372368243454, 440396.59603403555, 441005.7949710295, 441123.79438102606, 441606.0, 442076.0, 53588.92822143535, 53670.0, 53833.730831345594, 53916.19125713118, 53944.460555394726, 1451.8693317601255, 1455.345094707386, 1469.2653673163418, 1477.5641185850416, 1511.1008949674926, 26856.32859178458, 26895.731042690106, 26985.0, 27244.0, 27391.315217119733, 39662.40506392464, 39814.00464988399, 39817.80091099526, 39857.60142398539, 39925.2014959705, 356630.0, 414733.0, 417803.0, 417888.0, 419818.0, 29579.26051848721, 30069.699303007565, 30893.536596950613, 31026.68973310328, 31030.68969310278, 38592.80703596464, 39007.414888776126, 39603.80198099106, 39910.2017959645, 40166.995825104605, 27205.0, 27221.911123554837, 28543.714562854937, 29169.854150729112, 29283.0, 27897.721022790323, 28344.00795972131, 28586.714132859237, 28791.712082878905, 28973.0, 32464.513032305007, 32943.67056329502, 33025.33949320952, 33193.17017074496, 33323.50014749829, 28033.878644853976, 28053.579196311664, 28214.43571128607, 29025.98409055673, 29070.7092929068, 22871.252980370497, 22983.88508057516, 23059.769402306432, 23477.76522234824, 24087.759122409254], "value": 18838.575784040724, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.37333326677214, 424.5651190422924, 436.071168411284, 441.9371411754729, 446.13004640950146, 17796.377126800566, 21996.89001554992, 22128.557428851414, 22182.88908555457, 22456.8877155614, 23351.299461016155, 23454.882725586347, 23610.409739756513, 23820.5235895282, 24347.0, 5638.859028524299, 6286.0, 6927.9653601731925, 7154.463415243851, 7225.0, 6160.260768707762, 7126.144862616493, 7167.784966451015, 7260.346568808806, 7267.345938865501, 6896.896546551806, 7101.147862256536, 7117.145942486909, 7117.537360071605, 7119.857602847966, 6799.898001529981, 6994.6152961587095, 7008.614526201057, 7082.893756593655, 7092.078029856138, 6874.621895795729, 6925.58446493212, 6943.0, 6955.617441040741, 6983.476239282023, 27543.724562754316, 27714.861425692943, 27716.30709232265, 27746.167614971582, 28108.578371324445, 2916.577096321043, 2992.5062364709797, 3091.48990416581, 3109.486934655779, 3155.2742869140225, 16938.0, 17004.234809433605, 17017.57456063596, 17199.054052027135, 18142.727859082126, 2056.609244243589, 2105.4420578546697, 2107.4942013916702, 2115.693224482457, 2122.607317646225, 137851.31074344664, 138851.30574347163, 144636.0, 145072.27463862614, 148048.0, 16830.495085147464, 22552.774472255394, 22559.88720056389, 22706.0, 23294.83525823736, 31560.684393155774, 38173.045673858374, 38488.23023539514, 39223.80388098098, 39287.41068883969, 9848.753781155385, 11357.659270221906, 11567.016803571621, 11745.0, 11748.471318790675, 9481.952590237004, 9509.762255943657, 9731.221502279801, 9736.853947190797, 9902.0, 10793.352398856094, 11133.276337038067, 11159.107271418266, 11231.326120432801, 11279.718007049889, 4408.647308215335, 4502.099580083931, 4522.683412161117, 4540.82040620504, 4544.432170901034, 891.527490430073, 904.6878826804742, 918.2424499787702, 948.0756262643868, 956.206348730552, 21136.577268454545, 21758.456038598844, 21874.89062554677, 21961.450963726034, 22115.447113822283, 930.5486838883136, 934.7289286106953, 940.6331530703031, 952.9618815247451, 954.8519979403204, 11021.338719676845, 11303.021971155014, 11405.543778248943, 11480.885191148149, 11506.539738410369, 787007.0, 787295.3176170641, 793898.0305098552, 795246.023769889, 800879.0, 106463.4676826611, 125720.37139814242, 126137.0, 131603.68396316105, 136715.31642341724, 1256.2211428914131, 1318.0378323823663, 1346.7912473566612, 1353.9526116585873, 1354.3499120422055, 15232.923835380752, 19393.32123375675, 20641.793582063987, 20691.793082068987, 20694.379168624964, 51211.487885122166, 57811.710941445024, 58678.706606466694, 59077.0, 59990.700046499485, 76012.61993690218, 78871.21128788638, 79135.0, 79393.0, 85555.0, 11549.538018479167, 11598.304101753922, 11624.302541847515, 11688.649340519798, 11938.0, 11217.158713096713, 12977.805332919825, 13060.9551235899, 13335.399907004403, 13515.59453216405, 11038.337699738042, 11125.666230013112, 11308.886911130783, 11505.597304094317, 11606.12954028468, 9848.212143028397, 10213.591456341961, 10570.78858422812, 10695.0, 11010.339379637247, 9580.377275477073, 11726.824097638713, 11844.526218951145, 11888.34614096233, 11936.672348891883, 9595.328327017041, 11578.536858525902, 11691.883081169079, 11773.76452470963, 11890.881091189323, 11288.0, 11295.830562541403, 11475.311481311148, 11588.826167607323, 11671.474783634587], "value": 11842.773965828017, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1619.9271032803522, 1621.7486289625106, 1650.8761842861784, 1654.520189145148, 1666.9749953750695, 10602.628907988223, 11085.50115244814, 11200.551977920893, 11305.830412543819, 11436.828447573293, 11015.504302306395, 11362.829557556643, 11564.53741850327, 11580.363080030595, 11582.478788454519, 2411.975880241197, 2455.8035357171384, 2457.5207834472285, 2459.1761759810397, 2463.6304554316907, 2367.621180611103, 2405.542946840095, 2429.1983645396976, 2435.8051355891484, 2446.877656117196, 2350.306659535441, 2416.7704068113503, 2421.69728783902, 2421.69728783902, 2423.0671191591277, 2336.8013718833918, 2387.271882075966, 2388.0686532252435, 2395.149721848745, 2406.338256979327, 2350.7061617297836, 2373.299876536426, 2374.8456350337347, 2382.7855493005713, 2383.41606306456, 11375.772484550345, 11601.825972610417, 11620.767584648343, 11625.534978600846, 11628.418579071013, 5712.371639119693, 5877.764889404419, 5902.173695682608, 5902.966980778347, 5911.852203694899, 3464.7574669773126, 3537.2748586539697, 3545.94681079784, 3618.131648404364, 3627.0569651890432, 1231.1443546735022, 1260.8171815086853, 1289.8323217981697, 1300.5383089003458, 1311.3115614302471, 13525.661858453519, 13653.453861845514, 14040.789388159084, 14967.476138335105, 15551.300191491297, 4502.077074199847, 4663.347131401607, 4668.276417155278, 4687.0, 4708.223143181405, 12073.939630301793, 12191.817122743167, 12204.04735242357, 12221.388930553438, 12225.327606981702, 4587.174308624413, 4907.288443175719, 5058.3929928408825, 5741.684207368635, 5976.342602313742, 5217.973910130425, 5249.370075590878, 5348.786048558014, 5419.105847535191, 5438.211459338374, 4056.553779084298, 4091.97954010234, 4158.0, 4171.144915292358, 4174.91650167001, 954.5370495309769, 978.4178413843752, 1067.5836423794726, 1105.3588918427292, 1125.392288164382, 980.2060331131806, 996.342414006752, 1007.0382784440841, 1019.2406657040552, 1029.9897001029894, 6849.048021313902, 6948.062011628466, 6953.79138625842, 7091.468139889529, 7176.138863336433, 967.9370840895319, 1071.7802850415646, 1082.1775450657558, 1090.8981928252363, 1096.5997410945108, 4444.6666500012625, 4575.862724118282, 4709.0, 4810.278458231223, 4882.0, 13477.393517291745, 13903.0, 13935.372908219155, 14319.498817541544, 14331.570052898429, 14127.293635318398, 14153.787693184611, 14159.85840141606, 14184.290785460893, 14205.573832784827, 9981.351212171186, 10299.227557933185, 10580.629677961235, 10584.047435730821, 10609.893901061045, 6729.597442812867, 6821.585103154695, 6920.30796920304, 7059.82350441243, 7096.751613693703, 11394.886051139714, 12186.695332616755, 12527.248365098123, 12865.356732163167, 12878.291693956548, 11179.993800557988, 12008.519659213534, 12035.97694196003, 12435.316057616918, 12992.155509891827, 9493.95253023758, 9696.6121355145, 9803.558839852367, 9810.313278070467, 9810.313278070753, 10541.209409294486, 10555.419451930218, 10668.83996740034, 10670.253082284165, 10686.519106640064, 8980.371374003757, 9119.954400227956, 9140.6800761973, 9225.21585665226, 9244.630214791334, 7596.582187979714, 8944.924352124579, 9004.774880628036, 9044.683436079706, 9211.677591284273, 8132.634031468479, 8301.709440169565, 8306.252437280673, 8315.043769966289, 8317.0, 5985.461308482258, 6044.365341639064, 6085.300190478151, 6151.3541078186145, 6198.566100372931, 5091.516305950929, 6119.265688117454, 6137.508999280136, 6295.779647712308, 6405.647689377129], "value": 5269.87702945114, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.16947258678397, 387.5678618340551, 388.7181793199932, 392.47212499188583, 398.20955403524016, 4946.505349465061, 5171.888348762734, 5179.559737422319, 5216.921746173811, 5228.633995620308, 4812.422509298889, 5054.444011158769, 5070.768861700849, 5117.283580298761, 5117.820876269331, 3624.7281453890932, 3808.352580061396, 3902.941455878164, 4212.768297743631, 4296.613304802567, 3511.6312787157362, 4310.655147588185, 4311.223979683656, 4313.633341165989, 4332.263515202423, 4156.8129434175535, 4306.483222013347, 4312.956870431287, 4317.503487098977, 4327.0, 3652.8904132876055, 3655.4334078217917, 3850.114473671071, 3861.0540417597867, 4218.97890510547, 3652.415613501827, 3960.7623542587535, 3963.7117936670697, 3979.303621866191, 3981.5620281768984, 4802.358702713494, 4808.038392321515, 4931.805346620755, 5074.568661663763, 5128.8461346159675, 4755.476897541303, 4992.900141997176, 5005.598432438923, 5016.347874776256, 5050.924236136461, 4808.567228949411, 5072.771725272371, 5287.550558202557, 5506.339239291271, 5523.398214517785, 4002.4396584478195, 4258.61672449481, 4421.8452354167775, 4450.421445212101, 4457.064016556527, 5461.808836690697, 5490.121580547133, 5526.696031718254, 5609.0, 5855.912161317584, 4037.7779222142476, 4398.362237475548, 4533.9319910201375, 4547.636189104864, 4555.496686093647, 4658.254679251304, 4697.177993851063, 4752.930590617153, 4764.0471905619015, 4774.785134668949, 4367.890802729894, 4784.091022705675, 4944.777485013183, 4976.715367857197, 4993.900121997613, 4678.0410015946645, 4934.605231581465, 5290.338707661543, 5433.565314774887, 5455.863603409867, 1451.4484495891493, 1466.8606482384157, 1503.7218364390399, 1549.635835578638, 1572.2610373124612, 1082.983755243672, 1183.9052875769917, 1187.0503597122267, 1195.1753290229888, 1217.2027322104036, 4773.427188737373, 4899.755012249373, 4970.254461830682, 5158.246196293278, 5222.294990176353, 5047.551937310402, 5068.797248110108, 5187.533122019036, 5312.681239125587, 5650.604457688005, 4466.917826699631, 5015.924761128586, 5150.974245128826, 5157.060475528988, 5179.7928082877015, 2566.666333376649, 2916.3584011517432, 2944.263934016496, 2956.512175491063, 2962.762978961678, 5319.946800531945, 5907.556933229939, 5911.68945390574, 5923.674197919069, 5928.703564821742, 5201.479852014844, 5308.575313974873, 5453.15476101205, 5483.670979741229, 5523.25436066134, 4927.531884470969, 5535.750891209906, 5618.550515958714, 5920.615160014499, 5983.760649574142, 4951.653384263067, 5428.402875683751, 5747.798827041033, 5771.759071799584, 5795.39148389413, 5047.116754568009, 5287.3919499256535, 5648.71756412186, 5667.716614169192, 5692.459216374438, 5884.176215329943, 5887.174975757529, 5922.3485416605, 5932.6737029462065, 5954.285485741739, 4033.4756481657196, 4053.0, 4433.445819272591, 4595.126925884071, 4649.209634362099, 5233.842984710464, 5293.206019097012, 5482.314710661168, 5655.830325090254, 5767.250257466502, 5121.89879175979, 5579.16312553104, 5586.38549759518, 5586.972065139811, 5620.269364982526, 4718.740469274223, 5220.973895130652, 5246.42289348179, 5577.498025177753, 5609.80365687199, 4785.827472269316, 4838.612910967185, 5248.396434409939, 5450.400455949922, 5471.057774489998, 4991.251312303184, 5177.430482646982, 5192.740362981911, 5536.598404268536, 5579.7489112990825, 4729.976350118227, 4922.3600931880055, 5025.447200807981, 5086.847394578169, 5199.0], "value": 4224.682906019562, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [738.6639079218955, 755.2296657409443, 775.1318523253957, 845.1759534453909, 860.74177746676, 15451.304691288891, 16657.167141642916, 16703.582410439743, 16733.247003884822, 16773.49679509615, 15748.0, 15799.526014219564, 15802.288896999635, 15813.209339533007, 15933.84066159338, 952.5380190607567, 961.1205746741758, 970.8009857979098, 979.9069088436587, 994.393420013791, 988.8170688422629, 992.1417973452948, 992.4194346307419, 992.9602815887356, 993.3295025857553, 1026.8408396698487, 1027.7687520307945, 1027.9023492768213, 1027.9229057820655, 1029.490402250887, 1024.451918223754, 1024.7489365105596, 1024.9026342497489, 1025.9538320775582, 1028.9742756431074, 745.9888101678479, 747.3610063395827, 748.9962550187214, 749.9625018749041, 753.8982237397936, 17141.971481711138, 17143.399981000606, 17266.827331726647, 17924.641507169916, 18126.728099078526, 12888.54890078852, 14496.855031449655, 14530.346134423977, 14616.780748288784, 14653.70692586153, 11618.825717614243, 11652.883471165349, 11714.062874969983, 11819.34993575353, 11841.763164736656, 3714.7771133732062, 3777.660010599059, 3860.749051311656, 3868.941965870514, 3999.8200080996135, 114298.42850785692, 121693.78306217001, 123168.0, 123772.0, 123939.3803030988, 10957.868507567273, 11242.887571124347, 11306.94346528262, 11432.714182145346, 11433.77041476904, 22562.097516099173, 22858.314250572508, 22933.770662293162, 22967.770322296896, 22987.0, 7695.6921723130445, 7840.13758486566, 7842.764717058498, 8044.879326810103, 8064.233897779702, 4398.934015989763, 4462.709923854939, 4545.65907556928, 4662.463816661128, 4720.881977950578, 3438.157651375429, 3458.3083383323437, 3479.251960828434, 3488.3720930232803, 3515.5781306243416, 607.5746977116037, 613.5459759777732, 620.7827260458803, 622.9439350458481, 626.3923993726079, 635.2789583822395, 654.6399480285824, 676.9796906092826, 683.550872151033, 691.4442504365178, 9945.80108397828, 10954.616588419367, 11399.373034483016, 11501.654950351503, 11540.884591153981, 601.506764453153, 625.8623102917351, 626.4737620398887, 663.0154220981838, 743.4201322968094, 4283.657307415399, 4681.9297710534365, 4890.12442417012, 5081.898362032739, 5157.277981082651, 72757.0, 91846.54076729574, 92746.07253927509, 96506.51746741221, 96954.51522742341, 54797.45202548003, 65974.67012664906, 71416.0, 82618.0, 86333.5683321592, 70971.87084387477, 71424.64287678528, 71795.64102179455, 74358.14104647427, 74489.62755186298, 10915.78168436611, 11073.501692423699, 11187.309917108796, 11254.0, 11329.206955513035, 38125.046873828374, 40025.59974400219, 40202.59797401988, 43486.69539913808, 44065.67802965916, 42600.786996064824, 58992.0, 59084.40915590789, 59491.0, 60098.39901600928, 12894.0, 13106.017048721193, 13173.341332933123, 13270.668233294246, 13826.170429774245, 14420.34779132512, 14516.0, 14869.702605947605, 15073.321700523284, 15200.163990980602, 13421.463141474233, 13422.798658020332, 13446.46214151423, 13913.860861391662, 14147.858521414653, 13276.867231327564, 13756.65608359799, 14360.497382591559, 14593.635159121108, 14629.268536572916, 13795.034347595572, 13854.479495110232, 13878.73050670097, 13936.039299112794, 13996.720065598427, 9754.9024509754, 10150.492475376053, 10256.12822910061, 10359.430231337048, 10365.378077315385, 7859.882101768592, 10039.269419993956, 10072.244581656552, 10334.780935627761, 11447.02700270453], "value": 7651.740091822821, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1435.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 116.0, 118.0, 115.0, 115.0, 117.0, 116.0, 114.0, 115.0, 115.0], "value": 114.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 109.0, 119.0, 145.0, 108.0, 107.0, 108.0, 108.0, 127.0, 108.0], "value": 118.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 194.0, 256.0, 204.0, 185.0, 185.0, 181.0, 185.0, 185.0, 186.0], "value": 194.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 61.0, 58.0, 91.0, 61.0, 60.0, 60.0, 60.0, 76.0, 59.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 68.0, 85.0, 69.0, 70.0, 67.0, 83.0, 68.0, 70.0, 82.0], "value": 74.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 91.0, 92.0, 91.0, 90.0, 91.0, 91.0, 96.0, 91.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 98.0, 97.0, 108.0, 149.0, 149.0, 97.0, 96.0, 97.0], "value": 108.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 100.0, 136.0, 127.0, 86.0, 84.0, 85.0, 85.0, 85.0, 86.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 60.0, 59.0, 61.0, 62.0, 65.0, 64.0, 66.0, 63.0, 64.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 66.0, 62.0, 64.0, 60.0, 58.0, 57.0, 57.0, 59.0, 58.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 81.0, 84.0, 80.0, 80.0, 81.0, 81.0, 81.0], "value": 81.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 138.0, 134.0, 124.0, 135.0, 131.0, 136.0, 140.0, 181.0, 147.0], "value": 140.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 158.0, 162.0, 161.0, 161.0, 161.0, 161.0, 160.0, 161.0, 162.0], "value": 160.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 62.0, 63.0, 63.0, 56.0, 55.0, 82.0, 76.0, 77.0], "value": 66.1, "unit": "score"}]}]} 12:23:23 INFO - Return code: 0 12:23:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:23:23 INFO - # TBPL SUCCESS # 12:23:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:23:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:23:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:23:23 INFO - 2017-05-16 12:23:23.144 screenresolution[1664:10726] starting screenresolution argv=screenresolution get 12:23:23 INFO - 2017-05-16 12:23:23.146 screenresolution[1664:10726] Display 0: 1600x1200x32@60 12:23:23 INFO - 2017-05-16 12:23:23.152 screenresolution[1665:10729] starting screenresolution argv=screenresolution list 12:23:23 INFO - Available Modes on Display 0 12:23:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:23:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:23:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:23:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:23:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:23:23 INFO - Intel Iris: 12:23:23 INFO - Chipset Model: Intel Iris 12:23:23 INFO - Type: GPU 12:23:23 INFO - Bus: Built-In 12:23:23 INFO - VRAM (Dynamic, Max): 1536 MB 12:23:23 INFO - Vendor: Intel (0x8086) 12:23:23 INFO - Device ID: 0x0a2e 12:23:23 INFO - Revision ID: 0x0009 12:23:23 INFO - Displays: 12:23:23 INFO - Display: 12:23:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:23:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:23:23 INFO - Display Serial Number: 1600x1200 60 12:23:23 INFO - Main Display: Yes 12:23:23 INFO - Mirror: Off 12:23:23 INFO - Online: Yes 12:23:23 INFO - Rotation: Supported 12:23:23 INFO - Return code: 0 12:23:23 INFO - Running post-action listener: _resource_record_post_action 12:23:23 INFO - [mozharness: 2017-05-16 19:23:23.388007Z] Finished run-tests step (success) 12:23:23 INFO - Running post-run listener: _resource_record_post_run 12:23:23 INFO - Total resource usage - Wall time: 1194s; CPU: 54.0%; Read bytes: 33762304; Write bytes: 423608832; Read time: 326; Write time: 1410 12:23:23 INFO - TinderboxPrint: CPU usage
53.9% 12:23:23 INFO - TinderboxPrint: I/O read bytes / time
33,762,304 / 326 12:23:23 INFO - TinderboxPrint: I/O write bytes / time
423,608,832 / 1,410 12:23:23 INFO - TinderboxPrint: CPU idle
2,202.1 (46.1%) 12:23:23 INFO - TinderboxPrint: CPU system
954.5 (20.0%) 12:23:23 INFO - TinderboxPrint: CPU user
1,621.0 (33.9%) 12:23:23 INFO - TinderboxPrint: Swap in / out
152,711,168 / 0 12:23:23 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 114303488; Write bytes: 112971776; Read time: 10772; Write time: 242 12:23:23 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 32828416; Write bytes: 300712448; Read time: 311; Write time: 1135 12:23:23 INFO - Running post-run listener: _upload_blobber_files 12:23:23 INFO - Blob upload gear active. 12:23:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:23:23 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:23:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:23:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:23:24 INFO - (blobuploader) - INFO - Open directory for files ... 12:23:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:23:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:23:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:23:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:24 INFO - (blobuploader) - INFO - Done attempting. 12:23:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:23:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:23:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:23:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:26 INFO - (blobuploader) - INFO - Done attempting. 12:23:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:23:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:23:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:27 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:23:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:27 INFO - (blobuploader) - INFO - Done attempting. 12:23:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:23:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:23:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:23:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:23:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:23:29 INFO - (blobuploader) - INFO - Done attempting. 12:23:29 INFO - (blobuploader) - INFO - Iteration through files over. 12:23:29 INFO - Return code: 0 12:23:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:23:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:23:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2faaae8e085665af863f72e3bebd2d3aa8581072f866a9ff3bdaaeeda654103738b36e7573a6a91c531b9b3d0a80fe2d6196211d758080561a11da45e78a8a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c65159ccc39a0e17103dc6db572e680119c1fd3c3c6b698b946a5215d5b606abfc4f88867a7ceed876ab47e6bb860a3ae5bf6e55c9f382f6791f3dd1bbff3637", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/300eb1f7c917b98375ef6fc34e2c268cda5897868d355ef6cd10d4a438dbde900dfbfccdd4ab1762c9a50af8370057dcf90b0380b6aaf004ceb8f9d3b8f04bee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86537d388014cf449e43d3b31c9e3768d1d545d6de7ab251770243dd014ecb60e842e621ba636ee317cc1fda97227b1cb26c4d632b35148709483f253d1a3c85"} 12:23:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:23:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:23:29 INFO - Contents: 12:23:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2faaae8e085665af863f72e3bebd2d3aa8581072f866a9ff3bdaaeeda654103738b36e7573a6a91c531b9b3d0a80fe2d6196211d758080561a11da45e78a8a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c65159ccc39a0e17103dc6db572e680119c1fd3c3c6b698b946a5215d5b606abfc4f88867a7ceed876ab47e6bb860a3ae5bf6e55c9f382f6791f3dd1bbff3637", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/300eb1f7c917b98375ef6fc34e2c268cda5897868d355ef6cd10d4a438dbde900dfbfccdd4ab1762c9a50af8370057dcf90b0380b6aaf004ceb8f9d3b8f04bee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86537d388014cf449e43d3b31c9e3768d1d545d6de7ab251770243dd014ecb60e842e621ba636ee317cc1fda97227b1cb26c4d632b35148709483f253d1a3c85"} 12:23:29 INFO - Running post-run listener: copy_logs_to_upload_dir 12:23:29 INFO - Copying logs to upload dir... 12:23:29 INFO - mkdir: /builds/slave/test/build/upload/logs 12:23:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.115159 ========= master_lag: 2.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-05-16 12:23:31.443644) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 12:23:31.446835) ========= 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.WAEGzV9wHy/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.hIctt6F1Et/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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/a2faaae8e085665af863f72e3bebd2d3aa8581072f866a9ff3bdaaeeda654103738b36e7573a6a91c531b9b3d0a80fe2d6196211d758080561a11da45e78a8a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c65159ccc39a0e17103dc6db572e680119c1fd3c3c6b698b946a5215d5b606abfc4f88867a7ceed876ab47e6bb860a3ae5bf6e55c9f382f6791f3dd1bbff3637", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/300eb1f7c917b98375ef6fc34e2c268cda5897868d355ef6cd10d4a438dbde900dfbfccdd4ab1762c9a50af8370057dcf90b0380b6aaf004ceb8f9d3b8f04bee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86537d388014cf449e43d3b31c9e3768d1d545d6de7ab251770243dd014ecb60e842e621ba636ee317cc1fda97227b1cb26c4d632b35148709483f253d1a3c85"} build_url:https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011040 build_url: 'https://queue.taskcluster.net/v1/task/b64UIzBuRM62D0SVfdwnCQ/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/a2faaae8e085665af863f72e3bebd2d3aa8581072f866a9ff3bdaaeeda654103738b36e7573a6a91c531b9b3d0a80fe2d6196211d758080561a11da45e78a8a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c65159ccc39a0e17103dc6db572e680119c1fd3c3c6b698b946a5215d5b606abfc4f88867a7ceed876ab47e6bb860a3ae5bf6e55c9f382f6791f3dd1bbff3637", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/300eb1f7c917b98375ef6fc34e2c268cda5897868d355ef6cd10d4a438dbde900dfbfccdd4ab1762c9a50af8370057dcf90b0380b6aaf004ceb8f9d3b8f04bee", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/86537d388014cf449e43d3b31c9e3768d1d545d6de7ab251770243dd014ecb60e842e621ba636ee317cc1fda97227b1cb26c4d632b35148709483f253d1a3c85"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 12:23:31.483791) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 12:23:31.484118) ========= 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.WAEGzV9wHy/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.hIctt6F1Et/Listeners TMPDIR=/var/folders/wv/r2n7k9s14895rpd35vpv6gkw00000w/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.005266 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 12:23:31.532364) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 12:23:31.532687) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 12:23:31.533008) ========= ========= Total master_lag: 2.17 =========