builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0067 starttime: 1496949523.12 results: success (0) buildid: 20170608104024 builduid: 06f8d2d1c9ae4824b093483da3e6d919 revision: d8424bcbf72f7a451e89f8f625a9ebafecded872 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 12:18:43.115349) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 12:18:43.115957) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 12:18:43.116232) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 12:18:43.143893) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:18:43.144299) ========= 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.I2LikoKNKN/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.BvN92RCM49/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-08 12:18:43-- 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% 75.9M=0s 2017-06-08 12:18:43 (75.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.139865 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:18:43.308570) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:18:43.308879) ========= 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.I2LikoKNKN/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.BvN92RCM49/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.033968 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:18:43.363360) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-08 12:18:43.363696) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d8424bcbf72f7a451e89f8f625a9ebafecded872 --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 d8424bcbf72f7a451e89f8f625a9ebafecded872 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.I2LikoKNKN/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.BvN92RCM49/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-08 12:18:43,458 truncating revision to first 12 chars 2017-06-08 12:18:43,458 Setting DEBUG logging. 2017-06-08 12:18:43,459 attempt 1/10 2017-06-08 12:18:43,459 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d8424bcbf72f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-08 12:18:45,337 unpacking tar archive at: mozilla-beta-d8424bcbf72f/testing/mozharness/ program finished with exit code 0 elapsedTime=2.160631 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-08 12:18:45.547681) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 12:18:45.548026) ========= script_repo_revision: d8424bcbf72f7a451e89f8f625a9ebafecded872 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 12:18:45.548421) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 12:18:45.548693) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 12:18:45.567719) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-06-08 12:18:45.568098) ========= /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.I2LikoKNKN/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.BvN92RCM49/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:18:45 INFO - MultiFileLogger online at 20170608 12:18:45 in /builds/slave/test 12:18:45 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:18:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:18:45 INFO - {'append_to_log': False, 12:18:45 INFO - 'base_work_dir': '/builds/slave/test', 12:18:45 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:18:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:18:45 INFO - 'branch': 'Mozilla-Beta', 12:18:45 INFO - 'buildbot_json_path': 'buildprops.json', 12:18:45 INFO - 'config_files': ('talos/mac_config.py',), 12:18:45 INFO - 'default_actions': ('clobber', 12:18:45 INFO - 'read-buildbot-config', 12:18:45 INFO - 'download-and-extract', 12:18:45 INFO - 'populate-webroot', 12:18:45 INFO - 'create-virtualenv', 12:18:45 INFO - 'install', 12:18:45 INFO - 'run-tests'), 12:18:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:18:45 INFO - 'download_minidump_stackwalk': True, 12:18:45 INFO - 'download_symbols': 'ondemand', 12:18:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:18:45 INFO - 'tooltool.py': '/tools/tooltool.py', 12:18:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:18:45 INFO - '/tools/misc-python/virtualenv.py')}, 12:18:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:18:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:18:45 INFO - 'gecko_profile': False, 12:18:45 INFO - 'gecko_profile_interval': 0, 12:18:45 INFO - 'installer_path': 'installer.exe', 12:18:45 INFO - 'log_level': 'info', 12:18:45 INFO - 'log_name': 'talos', 12:18:45 INFO - 'log_to_console': True, 12:18:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:18:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:18:45 INFO - 'opt_config_files': (), 12:18:45 INFO - 'pip_index': False, 12:18:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:18:45 INFO - 'cmd': ('bash', 12:18:45 INFO - '-c', 12:18:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:18:45 INFO - 'enabled': True, 12:18:45 INFO - 'halt_on_failure': False, 12:18:45 INFO - 'name': 'check_screen_resolution'},), 12:18:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:18:45 INFO - 'cmd': ('bash', 12:18:45 INFO - '-c', 12:18:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:18:45 INFO - 'enabled': True, 12:18:45 INFO - 'halt_on_failure': False, 12:18:45 INFO - 'name': 'check_screen_resolution'},), 12:18:45 INFO - 'run_cmd_checks_enabled': True, 12:18:45 INFO - 'suite': 'dromaeojs', 12:18:45 INFO - 'system_bits': '32', 12:18:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:18:45 INFO - 'title': 't-yosemite-r7-0067', 12:18:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:18:45 INFO - 'use_talos_json': True, 12:18:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:18:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:18:45 INFO - 'work_dir': 'build'} 12:18:45 INFO - [mozharness: 2017-06-08 19:18:45.780052Z] Running clobber step. 12:18:45 INFO - Running pre-action listener: _resource_record_pre_action 12:18:45 INFO - Running main action method: clobber 12:18:45 INFO - rmtree: /builds/slave/test/build 12:18:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:18:48 INFO - Running post-action listener: _resource_record_post_action 12:18:48 INFO - [mozharness: 2017-06-08 19:18:48.326756Z] Finished clobber step (success) 12:18:48 INFO - [mozharness: 2017-06-08 19:18:48.327012Z] Running read-buildbot-config step. 12:18:48 INFO - Running pre-action listener: _resource_record_pre_action 12:18:48 INFO - Running main action method: read_buildbot_config 12:18:48 INFO - Using buildbot properties: 12:18:48 INFO - { 12:18:48 INFO - "project": "", 12:18:48 INFO - "product": "firefox", 12:18:48 INFO - "script_repo_revision": "production", 12:18:48 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 12:18:48 INFO - "repository": "", 12:18:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 12:18:48 INFO - "buildid": "20170608104024", 12:18:48 INFO - "pgo_build": "False", 12:18:48 INFO - "basedir": "/builds/slave/test", 12:18:48 INFO - "buildnumber": 35, 12:18:48 INFO - "slavename": "t-yosemite-r7-0067", 12:18:48 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:18:48 INFO - "platform": "yosemite_r7", 12:18:48 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:18:48 INFO - "branch": "mozilla-beta", 12:18:48 INFO - "repo_path": "releases/mozilla-beta", 12:18:48 INFO - "revision": "d8424bcbf72f7a451e89f8f625a9ebafecded872", 12:18:48 INFO - "stage_platform": "macosx64", 12:18:48 INFO - "builduid": "06f8d2d1c9ae4824b093483da3e6d919", 12:18:48 INFO - "slavebuilddir": "test" 12:18:48 INFO - } 12:18:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 12:18:48 INFO - Running post-action listener: _resource_record_post_action 12:18:48 INFO - [mozharness: 2017-06-08 19:18:48.333116Z] Finished read-buildbot-config step (success) 12:18:48 INFO - [mozharness: 2017-06-08 19:18:48.333300Z] Running download-and-extract step. 12:18:48 INFO - Running pre-action listener: _resource_record_pre_action 12:18:48 INFO - Running main action method: download_and_extract 12:18:48 INFO - mkdir: /builds/slave/test/build/tests 12:18:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:48 INFO - trying https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 12:18:48 INFO - Downloading https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/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:18:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/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:18:49 INFO - Downloaded 1287 bytes. 12:18:49 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:18:49 INFO - Using the following test package requirements: 12:18:49 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 12:18:49 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:18:49 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 12:18:49 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 12:18:49 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:18:49 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 12:18:49 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:18:49 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 12:18:49 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 12:18:49 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 12:18:49 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:18:49 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 12:18:49 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 12:18:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:18:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:18:49 INFO - Fetch https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:18:51 INFO - Expected file size: 51397773 12:18:51 INFO - Obtained file size: 51397773 12:19:07 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:19:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:19:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:19:07 INFO - Fetch https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:19:08 INFO - Expected file size: 51397773 12:19:08 INFO - Obtained file size: 51397773 12:19:23 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 12:19:23 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 12:19:23 INFO - Fetch https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 12:19:25 INFO - Expected file size: 14404461 12:19:25 INFO - Obtained file size: 14404461 12:19:25 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:19:25 INFO - trying https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:19:25 INFO - Downloading https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 12:19:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/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:19:27 INFO - Downloaded 59679603 bytes. 12:19:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:19:27 INFO - mkdir: /builds/slave/test/properties 12:19:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:19:27 INFO - Writing to file /builds/slave/test/properties/build_url 12:19:27 INFO - Contents: 12:19:27 INFO - build_url:https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:19:28 INFO - Running post-action listener: _resource_record_post_action 12:19:28 INFO - Running post-action listener: set_extra_try_arguments 12:19:28 INFO - [mozharness: 2017-06-08 19:19:28.140370Z] Finished download-and-extract step (success) 12:19:28 INFO - [mozharness: 2017-06-08 19:19:28.140514Z] Running populate-webroot step. 12:19:28 INFO - Running pre-action listener: _resource_record_pre_action 12:19:28 INFO - Running main action method: populate_webroot 12:19:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:19:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:19:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:19:28 INFO - '--tppagecycles', 12:19:28 INFO - '7'], 12:19:28 INFO - 'tests': ['tsvgm']}}, 12:19:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:19:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:19:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:19:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:19:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:19:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:19:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:19:28 INFO - 'talos_options': ['--disable-e10s'], 12:19:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:19:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:19:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:19:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:19:28 INFO - 'talos_options': ['--disable-e10s'], 12:19:28 INFO - 'tests': ['damp', 'tps']}, 12:19:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:19:28 INFO - 'tests': ['damp', 'tps']}, 12:19:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:19:28 INFO - 'tests': ['dromaeo_dom']}, 12:19:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:19:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:19:28 INFO - 'tests': ['basic_compositor_video']}, 12:19:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:19:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:19:28 INFO - 'tests': ['a11yr', 12:19:28 INFO - 'ts_paint', 12:19:28 INFO - 'tpaint', 12:19:28 INFO - 'sessionrestore', 12:19:28 INFO - 'sessionrestore_no_auto_restore', 12:19:28 INFO - 'tabpaint']}, 12:19:28 INFO - 'other-e10s': {'tests': ['a11yr', 12:19:28 INFO - 'ts_paint', 12:19:28 INFO - 'tpaint', 12:19:28 INFO - 'sessionrestore', 12:19:28 INFO - 'sessionrestore_no_auto_restore', 12:19:28 INFO - 'tabpaint']}, 12:19:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:19:28 INFO - 'ts_paint', 12:19:28 INFO - 'tpaint', 12:19:28 INFO - 'sessionrestore', 12:19:28 INFO - 'sessionrestore_no_auto_restore', 12:19:28 INFO - 'tabpaint']}, 12:19:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:19:28 INFO - 'ts_paint', 12:19:28 INFO - 'tpaint', 12:19:28 INFO - 'sessionrestore', 12:19:28 INFO - 'sessionrestore_no_auto_restore', 12:19:28 INFO - 'tabpaint']}, 12:19:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:19:28 INFO - 'tests': ['a11yr', 12:19:28 INFO - 'ts_paint', 12:19:28 INFO - 'tpaint', 12:19:28 INFO - 'sessionrestore', 12:19:28 INFO - 'sessionrestore_no_auto_restore', 12:19:28 INFO - 'tabpaint']}, 12:19:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:19:28 INFO - 'tests': ['a11yr', 12:19:28 INFO - 'ts_paint', 12:19:28 INFO - 'tpaint', 12:19:28 INFO - 'sessionrestore', 12:19:28 INFO - 'sessionrestore_no_auto_restore', 12:19:28 INFO - 'tabpaint']}, 12:19:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:19:28 INFO - 'tests': ['tsvgx', 12:19:28 INFO - 'tsvgr_opacity', 12:19:28 INFO - 'tart', 12:19:28 INFO - 'tscrollx', 12:19:28 INFO - 'cart', 12:19:28 INFO - 'tsvg_static']}, 12:19:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:19:28 INFO - 'tsvgr_opacity', 12:19:28 INFO - 'tart', 12:19:28 INFO - 'tscrollx', 12:19:28 INFO - 'cart', 12:19:28 INFO - 'tsvg_static']}, 12:19:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:19:28 INFO - 'talos_options': ['--disable-e10s'], 12:19:28 INFO - 'tests': ['tp5o']}, 12:19:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:19:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:19:28 INFO - 'talos_options': ['--disable-e10s', 12:19:28 INFO - '--xperf_path', 12:19:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:19:28 INFO - 'tests': ['tp5n']}, 12:19:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:19:28 INFO - 'talos_options': ['--xperf_path', 12:19:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:19:28 INFO - 'tests': ['tp5n']}}, 12:19:28 INFO - 'talos.zip': {'path': '', 12:19:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:19:28 INFO - Running post-action listener: _resource_record_post_action 12:19:28 INFO - [mozharness: 2017-06-08 19:19:28.159354Z] Finished populate-webroot step (success) 12:19:28 INFO - [mozharness: 2017-06-08 19:19:28.159492Z] Running create-virtualenv step. 12:19:28 INFO - Running pre-action listener: _resource_record_pre_action 12:19:28 INFO - Running main action method: create_virtualenv 12:19:28 INFO - Creating virtualenv /builds/slave/test/build/venv 12:19:28 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:19:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:19:28 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:19:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:19:28 INFO - Using real prefix '/tools/python27' 12:19:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:19:29 INFO - Installing distribute.............................................................................................................................................................................................done. 12:19:31 INFO - Installing pip.................done. 12:19:31 INFO - Return code: 0 12:19:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:19:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:19:32 INFO - Reading from file tmpfile_stdout 12:19:32 INFO - Output received: 12:19:32 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:19:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:19:32 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:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:32 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:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:32 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 0x102fccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ca56e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102daad30>, '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 0x102cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d9fbe8>, '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.I2LikoKNKN/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.BvN92RCM49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:19:32 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:19:32 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:19:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I2LikoKNKN/Render', 12:19:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:32 INFO - 'HOME': '/Users/cltbld', 12:19:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:32 INFO - 'LOGNAME': 'cltbld', 12:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:32 INFO - 'MOZ_NO_REMOTE': '1', 12:19:32 INFO - 'NO_EM_RESTART': '1', 12:19:32 INFO - 'PAGER': '/bin/cat', 12:19:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:32 INFO - 'PWD': '/builds/slave/test', 12:19:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:32 INFO - 'SHELL': '/bin/bash', 12:19:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvN92RCM49/Listeners', 12:19:32 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 12:19:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:32 INFO - 'USER': 'cltbld', 12:19:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:32 INFO - 'XPC_FLAGS': '0x0', 12:19:32 INFO - 'XPC_SERVICE_NAME': '0', 12:19:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:32 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:19:32 INFO - Cleaning up... 12:19:32 INFO - Return code: 0 12:19:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:19:32 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:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:32 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:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:32 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 0x102fccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ca56e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102daad30>, '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 0x102cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d9fbe8>, '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.I2LikoKNKN/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.BvN92RCM49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:19:32 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:19:32 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:19:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I2LikoKNKN/Render', 12:19:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:32 INFO - 'HOME': '/Users/cltbld', 12:19:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:32 INFO - 'LOGNAME': 'cltbld', 12:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:32 INFO - 'MOZ_NO_REMOTE': '1', 12:19:32 INFO - 'NO_EM_RESTART': '1', 12:19:32 INFO - 'PAGER': '/bin/cat', 12:19:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:32 INFO - 'PWD': '/builds/slave/test', 12:19:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:32 INFO - 'SHELL': '/bin/bash', 12:19:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvN92RCM49/Listeners', 12:19:32 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 12:19:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:32 INFO - 'USER': 'cltbld', 12:19:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:32 INFO - 'XPC_FLAGS': '0x0', 12:19:32 INFO - 'XPC_SERVICE_NAME': '0', 12:19:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:32 INFO - Downloading/unpacking psutil>=3.1.1 12:19:32 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:19:32 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:19:32 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:19:32 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:19:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:19:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:19:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:19:34 INFO - Installing collected packages: psutil 12:19:34 INFO - Running setup.py install for psutil 12:19:34 INFO - building 'psutil._psutil_osx' extension 12:19:34 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:19:35 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:19:35 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:19:35 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:19:35 INFO - building 'psutil._psutil_posix' extension 12:19:35 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:19:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:19:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:19:35 INFO - ^ 12:19:35 INFO - 1 warning generated. 12:19:35 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:19:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:19:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:19:35 INFO - Successfully installed psutil 12:19:35 INFO - Cleaning up... 12:19:35 INFO - Return code: 0 12:19:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:19:35 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:19:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:35 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:19:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:35 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 0x102fccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ca56e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102daad30>, '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 0x102cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d9fbe8>, '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.I2LikoKNKN/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.BvN92RCM49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:19:35 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:19:35 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:19:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I2LikoKNKN/Render', 12:19:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:35 INFO - 'HOME': '/Users/cltbld', 12:19:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:35 INFO - 'LOGNAME': 'cltbld', 12:19:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:35 INFO - 'MOZ_NO_REMOTE': '1', 12:19:35 INFO - 'NO_EM_RESTART': '1', 12:19:35 INFO - 'PAGER': '/bin/cat', 12:19:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:35 INFO - 'PWD': '/builds/slave/test', 12:19:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:35 INFO - 'SHELL': '/bin/bash', 12:19:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvN92RCM49/Listeners', 12:19:35 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 12:19:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:35 INFO - 'USER': 'cltbld', 12:19:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:35 INFO - 'XPC_FLAGS': '0x0', 12:19:35 INFO - 'XPC_SERVICE_NAME': '0', 12:19:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:19:36 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:19:36 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:19:36 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:19:36 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:19:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:19:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:19:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:19:37 INFO - Installing collected packages: mozsystemmonitor 12:19:37 INFO - Running setup.py install for mozsystemmonitor 12:19:37 INFO - Successfully installed mozsystemmonitor 12:19:37 INFO - Cleaning up... 12:19:37 INFO - Return code: 0 12:19:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:19:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:38 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 0x102fccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ca56e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102daad30>, '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 0x102cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d9fbe8>, '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.I2LikoKNKN/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.BvN92RCM49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:19:38 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:19:38 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:19:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I2LikoKNKN/Render', 12:19:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:38 INFO - 'HOME': '/Users/cltbld', 12:19:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:38 INFO - 'LOGNAME': 'cltbld', 12:19:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:38 INFO - 'MOZ_NO_REMOTE': '1', 12:19:38 INFO - 'NO_EM_RESTART': '1', 12:19:38 INFO - 'PAGER': '/bin/cat', 12:19:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:38 INFO - 'PWD': '/builds/slave/test', 12:19:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:38 INFO - 'SHELL': '/bin/bash', 12:19:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvN92RCM49/Listeners', 12:19:38 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 12:19:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:38 INFO - 'USER': 'cltbld', 12:19:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:38 INFO - 'XPC_FLAGS': '0x0', 12:19:38 INFO - 'XPC_SERVICE_NAME': '0', 12:19:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:38 INFO - Downloading/unpacking jsonschema==2.5.1 12:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:19:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:19:40 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:19:40 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:19:40 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:19:40 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:19:40 INFO - Downloading functools32-3.2.3-2.tar.gz 12:19:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:19:40 INFO - warning: no files found matching '*.txt' 12:19:40 INFO - no previously-included directories found matching 'build' 12:19:40 INFO - no previously-included directories found matching 'dist' 12:19:40 INFO - no previously-included directories found matching '.git*' 12:19:40 INFO - Installing collected packages: jsonschema, functools32 12:19:40 INFO - Running setup.py install for functools32 12:19:40 INFO - warning: no files found matching '*.txt' 12:19:40 INFO - no previously-included directories found matching 'build' 12:19:40 INFO - no previously-included directories found matching 'dist' 12:19:40 INFO - no previously-included directories found matching '.git*' 12:19:40 INFO - Successfully installed jsonschema functools32 12:19:40 INFO - Cleaning up... 12:19:41 INFO - Return code: 0 12:19:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102fccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ca56e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102daad30>, '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 0x102cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d9fbe8>, '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.I2LikoKNKN/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.BvN92RCM49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:19:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:19:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:19:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I2LikoKNKN/Render', 12:19:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:41 INFO - 'HOME': '/Users/cltbld', 12:19:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:41 INFO - 'LOGNAME': 'cltbld', 12:19:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:41 INFO - 'MOZ_NO_REMOTE': '1', 12:19:41 INFO - 'NO_EM_RESTART': '1', 12:19:41 INFO - 'PAGER': '/bin/cat', 12:19:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:41 INFO - 'PWD': '/builds/slave/test', 12:19:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:41 INFO - 'SHELL': '/bin/bash', 12:19:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvN92RCM49/Listeners', 12:19:41 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 12:19:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:41 INFO - 'USER': 'cltbld', 12:19:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:41 INFO - 'XPC_FLAGS': '0x0', 12:19:41 INFO - 'XPC_SERVICE_NAME': '0', 12:19:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:19:41 INFO - Cleaning up... 12:19:41 INFO - Return code: 0 12:19:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102fccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ca56e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102daad30>, '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 0x102cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d9fbe8>, '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.I2LikoKNKN/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.BvN92RCM49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:19:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:19:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:19:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I2LikoKNKN/Render', 12:19:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:41 INFO - 'HOME': '/Users/cltbld', 12:19:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:41 INFO - 'LOGNAME': 'cltbld', 12:19:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:41 INFO - 'MOZ_NO_REMOTE': '1', 12:19:41 INFO - 'NO_EM_RESTART': '1', 12:19:41 INFO - 'PAGER': '/bin/cat', 12:19:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:41 INFO - 'PWD': '/builds/slave/test', 12:19:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:41 INFO - 'SHELL': '/bin/bash', 12:19:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvN92RCM49/Listeners', 12:19:41 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 12:19:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:41 INFO - 'USER': 'cltbld', 12:19:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:41 INFO - 'XPC_FLAGS': '0x0', 12:19:41 INFO - 'XPC_SERVICE_NAME': '0', 12:19:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:41 INFO - Downloading/unpacking blobuploader==1.2.4 12:19:41 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:19:41 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:19:41 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:19:41 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:19:42 INFO - Downloading blobuploader-1.2.4.tar.gz 12:19:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:19:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:19:43 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:19:43 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:19:43 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:19:43 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:19:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:19:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:19:43 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:19:43 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:19:43 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:19:43 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:19:43 INFO - Downloading docopt-0.6.1.tar.gz 12:19:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:19:43 INFO - Installing collected packages: blobuploader, requests, docopt 12:19:43 INFO - Running setup.py install for blobuploader 12:19:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:19:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:19:44 INFO - Running setup.py install for requests 12:19:44 INFO - Running setup.py install for docopt 12:19:44 INFO - Successfully installed blobuploader requests docopt 12:19:44 INFO - Cleaning up... 12:19:44 INFO - Return code: 0 12:19:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19: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:19:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19: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:19:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:44 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 0x102fccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ca56e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102daad30>, '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 0x102cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d9fbe8>, '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.I2LikoKNKN/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.BvN92RCM49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:19:44 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:19:44 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:19:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I2LikoKNKN/Render', 12:19:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:44 INFO - 'HOME': '/Users/cltbld', 12:19:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:44 INFO - 'LOGNAME': 'cltbld', 12:19:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:44 INFO - 'MOZ_NO_REMOTE': '1', 12:19:44 INFO - 'NO_EM_RESTART': '1', 12:19:44 INFO - 'PAGER': '/bin/cat', 12:19:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:44 INFO - 'PWD': '/builds/slave/test', 12:19:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:44 INFO - 'SHELL': '/bin/bash', 12:19:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvN92RCM49/Listeners', 12:19:44 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 12:19:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:44 INFO - 'USER': 'cltbld', 12:19:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:44 INFO - 'XPC_FLAGS': '0x0', 12:19:44 INFO - 'XPC_SERVICE_NAME': '0', 12:19:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:19:44 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-pveVdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:19:45 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-fFBjke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:19:45 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-cx9LCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:19:45 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-UATRU6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:19:45 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-GBTYlo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:19:45 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-4gweWB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:19:45 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-SziRR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:19:45 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Eqc9sk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:19:45 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-KZntSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:19:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:19:45 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-_oGERK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:19:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ubPNtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:19:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-ZJ9EEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:19:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-C2jdkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:19:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-qBXAyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-R5HTA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:19:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-eIFaJA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:19:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:19:46 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-prUhph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:19:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:19:46 INFO - Running setup.py install for manifestparser 12:19:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:19:46 INFO - Running setup.py install for mozcrash 12:19:47 INFO - Running setup.py install for mozdebug 12:19:47 INFO - Running setup.py install for mozdevice 12:19:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:19:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:19:47 INFO - Running setup.py install for mozfile 12:19:47 INFO - Running setup.py install for mozhttpd 12:19:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:19:47 INFO - Running setup.py install for mozinfo 12:19:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:19:47 INFO - Running setup.py install for mozInstall 12:19:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:19:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:19:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:19:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:19:47 INFO - Running setup.py install for mozleak 12:19:48 INFO - Running setup.py install for mozlog 12:19:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:19:48 INFO - Running setup.py install for moznetwork 12:19:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:19:48 INFO - Running setup.py install for mozprocess 12:19:48 INFO - Running setup.py install for mozprofile 12:19:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:19:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:19:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:19:48 INFO - Running setup.py install for mozrunner 12:19:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:19:48 INFO - Running setup.py install for mozscreenshot 12:19:49 INFO - Running setup.py install for moztest 12:19:49 INFO - Running setup.py install for mozversion 12:19:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:19:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:19:49 INFO - Cleaning up... 12:19:49 INFO - Return code: 0 12:19:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:19:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:49 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 0x102fccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ca56e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102daad30>, '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 0x102cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d9fbe8>, '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.I2LikoKNKN/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.BvN92RCM49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:19:49 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:19:49 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:19:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I2LikoKNKN/Render', 12:19:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:49 INFO - 'HOME': '/Users/cltbld', 12:19:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:49 INFO - 'LOGNAME': 'cltbld', 12:19:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:49 INFO - 'MOZ_NO_REMOTE': '1', 12:19:49 INFO - 'NO_EM_RESTART': '1', 12:19:49 INFO - 'PAGER': '/bin/cat', 12:19:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:49 INFO - 'PWD': '/builds/slave/test', 12:19:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:49 INFO - 'SHELL': '/bin/bash', 12:19:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvN92RCM49/Listeners', 12:19:49 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 12:19:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:49 INFO - 'USER': 'cltbld', 12:19:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:49 INFO - 'XPC_FLAGS': '0x0', 12:19:49 INFO - 'XPC_SERVICE_NAME': '0', 12:19:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:19:49 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-GuD84o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:19:49 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:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:19:49 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-1q10q_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:19:49 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:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:19:49 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Qct_OH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:19:49 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:19:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:19:49 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-9InUUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:19:50 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:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:19:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-4H3vhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:19:50 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:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:19:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-uP_C6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:19:50 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:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:19:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-TcZHkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:19:50 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:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:19:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-qMSUvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:19:50 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:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:19:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-IxXnsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:19:50 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:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:19:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-DDcQeb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:19:50 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:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:19:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-WzT0Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:19:50 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:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:19:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-a9BtMh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:19:50 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:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:19:50 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-Iq6yGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:19:51 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:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:19:51 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-m_Yn7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:19:51 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:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:51 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-lCC326-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:19:51 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:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:19:51 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-peAd98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:19:51 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:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:19:51 INFO - Running setup.py (path:/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/pip-sQHLcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:19:51 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:19:51 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:19:51 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:19:51 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:19:51 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:19:51 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:19:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:19:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:19:52 INFO - Downloading blessings-1.6.tar.gz 12:19:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:19:52 INFO - Installing collected packages: blessings 12:19:52 INFO - Running setup.py install for blessings 12:19:53 INFO - Successfully installed blessings 12:19:53 INFO - Cleaning up... 12:19:53 INFO - Return code: 0 12:19:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:19:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:19:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:19:53 INFO - Reading from file tmpfile_stdout 12:19:53 INFO - Current package versions: 12:19:53 INFO - blessings == 1.6 12:19:53 INFO - blobuploader == 1.2.4 12:19:53 INFO - docopt == 0.6.1 12:19:53 INFO - functools32 == 3.2.3-2 12:19:53 INFO - jsonschema == 2.5.1 12:19:53 INFO - manifestparser == 1.1 12:19:53 INFO - mozInstall == 1.12 12:19:53 INFO - mozcrash == 1.0 12:19:53 INFO - mozdebug == 0.1 12:19:53 INFO - mozdevice == 0.49 12:19:53 INFO - mozfile == 1.2 12:19:53 INFO - mozhttpd == 0.7 12:19:53 INFO - mozinfo == 0.9 12:19:53 INFO - mozleak == 0.1 12:19:53 INFO - mozlog == 3.4 12:19:53 INFO - moznetwork == 0.27 12:19:53 INFO - mozprocess == 0.25 12:19:53 INFO - mozprofile == 0.28 12:19:53 INFO - mozrunner == 6.13 12:19:53 INFO - mozscreenshot == 0.1 12:19:53 INFO - mozsystemmonitor == 0.3 12:19:53 INFO - moztest == 0.8 12:19:53 INFO - mozversion == 1.4 12:19:53 INFO - psutil == 3.1.1 12:19:53 INFO - requests == 1.2.3 12:19:53 INFO - wsgiref == 0.1.2 12:19:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:19:53 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:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:53 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:19:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:53 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 0x102fccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ca56e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102daad30>, '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 0x102cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d9fbe8>, '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.I2LikoKNKN/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.BvN92RCM49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:19:53 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:19:53 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:19:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I2LikoKNKN/Render', 12:19:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:53 INFO - 'HOME': '/Users/cltbld', 12:19:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:53 INFO - 'LOGNAME': 'cltbld', 12:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:53 INFO - 'MOZ_NO_REMOTE': '1', 12:19:53 INFO - 'NO_EM_RESTART': '1', 12:19:53 INFO - 'PAGER': '/bin/cat', 12:19:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:53 INFO - 'PWD': '/builds/slave/test', 12:19:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:53 INFO - 'SHELL': '/bin/bash', 12:19:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvN92RCM49/Listeners', 12:19:53 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 12:19:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:53 INFO - 'USER': 'cltbld', 12:19:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:53 INFO - 'XPC_FLAGS': '0x0', 12:19:53 INFO - 'XPC_SERVICE_NAME': '0', 12:19:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:53 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:19:53 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:19:53 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:19:53 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:19:53 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:19:53 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:19:53 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:19:53 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:19:53 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:19:53 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:19:53 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:19:53 INFO - Cleaning up... 12:19:53 INFO - Return code: 0 12:19:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:19:53 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:19:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:19:53 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:19:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:19:53 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 0x102fccb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102fcf768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe7ca56e7c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102daad30>, '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 0x102cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d9fbe8>, '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.I2LikoKNKN/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.BvN92RCM49/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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:19:53 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:19:53 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:19:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I2LikoKNKN/Render', 12:19:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:19:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:19:53 INFO - 'HOME': '/Users/cltbld', 12:19:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:19:53 INFO - 'LOGNAME': 'cltbld', 12:19:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:19:53 INFO - 'MOZ_NO_REMOTE': '1', 12:19:53 INFO - 'NO_EM_RESTART': '1', 12:19:53 INFO - 'PAGER': '/bin/cat', 12:19:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:19:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:19:53 INFO - 'PWD': '/builds/slave/test', 12:19:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:19:53 INFO - 'SHELL': '/bin/bash', 12:19:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvN92RCM49/Listeners', 12:19:53 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 12:19:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:19:53 INFO - 'USER': 'cltbld', 12:19:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:19:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:19:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:19:53 INFO - 'XPC_FLAGS': '0x0', 12:19:53 INFO - 'XPC_SERVICE_NAME': '0', 12:19:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:19:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:19:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:19:53 INFO - Cleaning up... 12:19:53 INFO - Return code: 0 12:19:53 INFO - Running post-action listener: _resource_record_post_action 12:19:53 INFO - Running post-action listener: _start_resource_monitoring 12:19:54 INFO - Starting resource monitoring. 12:19:54 INFO - [mozharness: 2017-06-08 19:19:54.021273Z] Finished create-virtualenv step (success) 12:19:54 INFO - [mozharness: 2017-06-08 19:19:54.022214Z] Running install step. 12:19:54 INFO - Running pre-action listener: _resource_record_pre_action 12:19:54 INFO - Running main action method: install 12:19:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:19:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:19:54 INFO - Reading from file tmpfile_stdout 12:19:54 INFO - Detecting whether we're running mozinstall >=1.0... 12:19:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:19:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:19:54 INFO - Reading from file tmpfile_stdout 12:19:54 INFO - Output received: 12:19:54 INFO - Usage: mozinstall [options] installer 12:19:54 INFO - Options: 12:19:54 INFO - -h, --help show this help message and exit 12:19:54 INFO - -d DEST, --destination=DEST 12:19:54 INFO - Directory to install application into. [default: 12:19:54 INFO - "/builds/slave/test"] 12:19:54 INFO - --app=APP Application being installed. [default: firefox] 12:19:54 INFO - mkdir: /builds/slave/test/build/application 12:19:54 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:19:54 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:20:09 INFO - Reading from file tmpfile_stdout 12:20:09 INFO - Output received: 12:20:09 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:20:09 INFO - Running post-action listener: _resource_record_post_action 12:20:09 INFO - [mozharness: 2017-06-08 19:20:09.194271Z] Finished install step (success) 12:20:09 INFO - [mozharness: 2017-06-08 19:20:09.194423Z] Running run-tests step. 12:20:09 INFO - Running pre-action listener: _resource_record_pre_action 12:20:09 INFO - Running main action method: run_tests 12:20:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:20:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:20:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:20:09 INFO - 2017-06-08 12:20:09.209 screenresolution[1629:7567] starting screenresolution argv=screenresolution get 12:20:09 INFO - 2017-06-08 12:20:09.211 screenresolution[1629:7567] Display 0: 1600x1200x32@60 12:20:09 INFO - 2017-06-08 12:20:09.217 screenresolution[1630:7569] starting screenresolution argv=screenresolution list 12:20:09 INFO - Available Modes on Display 0 12:20:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:20:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:20:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:20:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:20:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:20:09 INFO - Intel Iris: 12:20:09 INFO - Chipset Model: Intel Iris 12:20:09 INFO - Type: GPU 12:20:09 INFO - Bus: Built-In 12:20:09 INFO - VRAM (Dynamic, Max): 1536 MB 12:20:09 INFO - Vendor: Intel (0x8086) 12:20:09 INFO - Device ID: 0x0a2e 12:20:09 INFO - Revision ID: 0x0009 12:20:09 INFO - Displays: 12:20:09 INFO - Display: 12:20:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:20:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:20:09 INFO - Display Serial Number: 1600x1200 60 12:20:09 INFO - Main Display: Yes 12:20:09 INFO - Mirror: Off 12:20:09 INFO - Online: Yes 12:20:09 INFO - Rotation: Supported 12:20:09 INFO - Return code: 0 12:20:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:20:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:20:09 INFO - Python 2.7.3 12:20:09 INFO - Return code: 0 12:20:09 INFO - grabbing minidump binary from tooltool 12:20:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:20:09 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 0x102daad30>, '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 0x102cd1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d9fbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:20:09 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:20:09 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:20:09 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:20:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:20:09 INFO - Return code: 0 12:20:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:20:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:20:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:20:09 INFO - ENV: RUST_BACKTRACE is now 1 12:20:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:20:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:20:09 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/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--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:20:09 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/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0067 --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:20:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.I2LikoKNKN/Render', 12:20:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:20:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:20:09 INFO - 'HOME': '/Users/cltbld', 12:20:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:20:09 INFO - 'LOGNAME': 'cltbld', 12:20:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:20:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:20:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:20:09 INFO - 'MOZ_NO_REMOTE': '1', 12:20:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:20:09 INFO - 'NO_EM_RESTART': '1', 12:20:09 INFO - 'PAGER': '/bin/cat', 12:20:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:20:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:20:09 INFO - 'PWD': '/builds/slave/test', 12:20:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:20:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:20:09 INFO - 'RUST_BACKTRACE': '1', 12:20:09 INFO - 'SHELL': '/bin/bash', 12:20:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BvN92RCM49/Listeners', 12:20:09 INFO - 'TMPDIR': '/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/', 12:20:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:20:09 INFO - 'USER': 'cltbld', 12:20:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:20:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:20:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:20:09 INFO - 'XPC_FLAGS': '0x0', 12:20:09 INFO - 'XPC_SERVICE_NAME': '0', 12:20:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:20:09 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/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0067', '--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:20:09 INFO - mozversion application_buildid: 20170608104024 12:20:09 INFO - mozversion application_changeset: d8424bcbf72f7a451e89f8f625a9ebafecded872 12:20:09 INFO - mozversion application_display_name: Firefox 12:20:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:20:09 INFO - mozversion application_name: Firefox 12:20:09 INFO - mozversion application_remotingname: firefox 12:20:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:20:09 INFO - mozversion application_vendor: Mozilla 12:20:09 INFO - mozversion application_version: 54.0 12:20:09 INFO - mozversion platform_buildid: 20170608104024 12:20:09 INFO - mozversion platform_changeset: d8424bcbf72f7a451e89f8f625a9ebafecded872 12:20:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:20:09 INFO - mozversion platform_version: 54.0 12:20:09 INFO - using testdate: 1496949609 12:20:09 INFO - actual date: 1496949609 12:20:09 INFO - starting webserver on 'localhost:49229' 12:20:09 INFO - SUITE-START | Running 2 tests 12:20:09 INFO - TEST-START | dromaeo_css 12:20:09 INFO - operating with platform_type : mac_ 12:20:09 INFO - Initialising browser for dromaeo_css test... 12:20:09 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmplLpC5b/profile http://localhost:49229/getInfo.html) 12:20:12 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 12:20:12 INFO - PROCESS | 1640 | colorDepth:24 12:20:12 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 12:20:12 INFO - PROCESS | 1640 | __metrics 12:20:12 INFO - PROCESS | 1640 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:20:12 INFO - PROCESS | 1640 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:20:13 INFO - TEST-INFO | 1640: exit 0 12:20:13 INFO - Browser initialized. 12:20:13 INFO - Running cycle 1/1 for dromaeo_css test... 12:20:13 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmplLpC5b/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:20:25 INFO - PROCESS | 1643 | RSS: Main: 169037824 12:20:25 INFO - PROCESS | 1643 | 12:23:28 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49229/tests/dromaeo/cssquery-ext.html) 12:23:30 INFO - PROCESS | 1643 | RSS: Main: 281858048 12:23:30 INFO - PROCESS | 1643 | 12:26:33 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-ext.html (next: http://localhost:49229/tests/dromaeo/cssquery-jquery.html) 12:26:34 INFO - PROCESS | 1643 | RSS: Main: 303349760 12:26:34 INFO - PROCESS | 1643 | 12:29:36 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49229/tests/dromaeo/cssquery-mootools.html) 12:29:37 INFO - PROCESS | 1643 | RSS: Main: 267214848 12:29:37 INFO - PROCESS | 1643 | 12:32:39 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49229/tests/dromaeo/cssquery-prototype.html) 12:32:40 INFO - PROCESS | 1643 | RSS: Main: 300490752 12:32:40 INFO - PROCESS | 1643 | 12:35:43 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49229/tests/dromaeo/cssquery-yui.html) 12:35:44 INFO - PROCESS | 1643 | RSS: Main: 266469376 12:35:44 INFO - PROCESS | 1643 | 12:38:47 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49229/tests/dromaeo/cssquery-yui.html (next: http://localhost:49229/tests/dromaeo/cssquery-dojo.html) 12:38:48 INFO - PROCESS | 1643 | RSS: Main: 346546176 12:38:48 INFO - PROCESS | 1643 | 12:38:48 INFO - PROCESS | 1643 | __start_tp_report 12:38:48 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 12:38:48 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 12:38:48 INFO - PROCESS | 1643 | |i|pagename|runs| 12:38:48 INFO - PROCESS | 1643 | |0;dojo.html;3214.967850321497;3568.3933731265683;3584.1039740064984;3585.5697316322044;3625.3111908737337;4360.629346505543;6129.448349648542;6130.693465326738;6145.446909778119;6150.876982460354;6080.331163572015;6107.4808641265545;6154.3537928517535;6170.691465426733;6181.969090154555;5395.136778115502;5428.67427954324;5448.945510544903;5458.399576046631;5552.500274975251;4312.525622181573;5338.946610533883;5385.434529374418;5439.211314359415;5554.166874968747;5464.0711079116645;5502.312210973629;5526.834194974137;5527.060399732035;5572.916406253909;4474.239379305526;5493.862653433696;5504.862378440531;5506.559475241971;5521.779128834882;5430.15832545956;5457.808976685797;5460.34475862899;5462.562994960394;5462.863428414322;5869.090291004901;5892.469677728982;5991.281046274475;6013.6993150342305;6016.398360163992;6386.521010924199;6395.264544577387;6407.070974708641;6460.3216662250725;6521.380468855451;6029.909551356734;6580.4406625436895;6589.86820263592;6618.834529136762;6643.734250629969;5297.470252974749;6245.18812554365;6259.155014073087;6261.843453913689;6278.434940855299;6425.614463132227;6507.739690412331;6586.407223349922;6758.087658166183;6771.864562708718;6327.114204011442;6366.808995730135;6366.968165159237;6420.165378500765;6456.7740129095255;6550.475961923034;6633.303503132198;6633.336666333328;6641.535092543476;6641.833954151185;6508.381703738232;6520.673966301666;6533.216014078341;6544.705488252945;6622.602643841385;6311.33730958252;6364.236291644939;6426.518011149183;6437.6137431753305;6557.9344206557325;5233.162693968947;5233.764480598382;5252.921206181911;5270.156774915995;5283;3579.982100089535;4626.907461850744;4637.281221410687;4661.510541393172;4719.858404247878;5021.895183059721;5851.180834683148;6187.876242475125;6200.286966998853;6202.069689546604;6398.520110991694;6438.3883531064475;6460.709268082947;6549.803505894831;6570.211574610982;5107.770150343244;6108.930937085992;6190.288116866528;6210.472109870621;6215.782447614311;5690.5447564194765;5694.9430505695245;5705.258316418922;5706.11555208935;5710.971445142748;6512;6607.801765947125;6648;6690.264070952094;6692.297308782508;6538.509611779041;6546.083548303337;6547.214334279917;6556.868862622626;6575.934240657532;6652.168478940132;6786.626735529396;6793.286704895915;6800.081988931431;6800.5239633227175;6292;6350.301466838551;6423.710933008119;6469;6493.318201588954;6596.571222870498;6597.406233439014;6598.868022639616;6604.7027883746305;6628.072069910313;6212.624091725233;6259.467945224706;6314.684265786784;6410.711517981796;6443.065755465337;4520.841770538015;6231.781887633911;6259.12372267892;6300.59046161998;6321.841953951188;6117.035835319669;6146.108814222002;6224.284207316216;6239.563230573816;6250.562460627712;6220.906686399617;6307.400796924285;6308.053791931247;6389.93610063912;6416.647084410402;6307.526935479949;6313.936860631335;6321.810345689637;6327.620342779448;6347.841303967253 12:38:48 INFO - PROCESS | 1643 | |1;ext.html;793.456482309618;815.3314282288524;835.1940377535677;915.4827522449815;922.7370199493146;30497.695023049764;42969.35545966809;43547.346789798175;43630.563694363045;43695.126097478074;44123;44411;44445.33332000014;44500.10999780011;44598.10803783922;9673.854892176623;9850.50747462624;9856.063673950963;9860.408375497458;9866.309358344919;7969.163237860028;8110.513369197837;8193.221643943845;8193.754187374388;8209.753707388758;10159;11881.40592970348;12062;12311.4459849307;12330.383480825967;11977.520899164023;12037.217580857216;12049.698757531045;12050.87949120515;12055.397230138546;11621.07031437483;11655.941720291428;11677.124215683858;11683.824742628867;11698.941505292503;42002.369964450554;42187.57812421867;42271.365929511085;42306.57693423088;42398.36402453966;4009.3585026395635;4040.1717647882115;4049.250888585621;4093.692973027005;4100.91798164035;29484;29833.25416864574;30353.848230758926;30609.846950765328;31463;2699.0701648321547;2855.7144285571485;3273.967260327414;3314.2708604107056;3428.125827913866;339606;343880.2805985979;344014.27992860123;344244.2787786045;344927;55431;55486.44513554894;55487.16769248465;55589.722051389486;55607.721961390744;45450;46990.76504617455;57805.421945780006;57960.13059804106;58019.12971305434;15406.22968851553;15858.603534911488;15883.28525216368;15902.76145857813;15910.204489775464;13698.65753356174;13706.794398084037;13720.725585488235;13740.450381984809;13780.242086685128;9790.188815069236;11437.542498299976;12734.426950787236;12775.233485990684;13075.777117889982;5876.589618491531;5882.529397648178;5890.879283457952;5912.083627037816;5919.520119970008;982.8427451607852;1150.4075401168404;1157.7510835170478;1170.2276497511602;1219.5487669562142;24890.253292401256;25435.61846572303;28045.71954280472;28586.571201431994;28768.424631507252;1095.2606990281529;1099.2689861242184;1099.9450027498754;1141.49774099396;1142.3488611491537;13620.863791362157;13727.313634318245;13770.380332885044;13889.930550347184;13932;496292;496542;497765;499814;500450;42107.789461052496;42780.93047673833;44680;48217;49426.75286623616;1489.6871656952162;1495.7980672609058;1496.551034689567;1513.8107736532934;1517.1958861803262;26951.977457582332;27051;27507.31231719223;27649.861750691118;27757.58363624507;36986.44520332143;47262.76368618135;48350;50669.746651266505;50829.49170508247;443038;447682;466930;470911.87264382123;471052;30340.696593033786;30650.628430873032;31318.373632526767;31693.683063169072;31817.8863739768;39294.41058384069;39344.40983385309;39368.40947385849;39940.60059399485;39993.40009899912;27879.303017424725;28531;28843.711562884102;29221.707782921898;29571.556426654046;26105.73894261033;28966.855165724035;29144.417111657225;29302.41395172127;29371.55942660819;32829.50755738618;34042.82978585091;34054.48918266178;34088.14779630529;34106.48840267448;25657.717114143845;27102.457950841268;29254.976075837243;29469.410611787214;29785.404291913608;22154.44613884666;23712.644310335014;23791.167309144097;24042.87978560096;24102.63846042346 12:38:48 INFO - PROCESS | 1643 | |2;jquery.html;429.2530996066845;429.3082373518042;433.94575678040246;443.43019220301915;447.39824935461814;19389.22443102276;20751.273705420313;21599.46001349967;21786.564268714636;21793.564128717455;19241.80758192421;21072.683909741365;21759.45601359967;21956.45108872275;22550.43623909399;6697.832554186148;7170.247124051978;7173;7182;7186.748463803768;7498.587577683225;7810.49231799934;7982.28159465648;7990.800229994238;7998.440109192359;6749.932500675004;7682.6158692065455;7832.255935686101;7923.683052677886;7950.56271905045;7695.769126926145;7900.407469439786;7966.123726390092;7967.64145613443;7968.521888686697;7492.838610015457;7724.806879827993;7751.961240193819;7762.689492420296;7768.689252429896;24520.264392068268;24923.376915577075;24937.251882443557;25291.367715807068;25371.112011079524;3167.255694911694;3359.395308844407;3428.862845486178;3481.7388695847912;3584;15229.543113706606;15329.233538323151;15356.76964845517;15388.692226155526;15492;1969.803019698033;2066.95866082679;2124.776898425674;2143.9464013399634;2152.332776839169;149845;169538.60991384677;169787;169892;170199.14900425542;14648.487302944346;16480.587985300463;17089.401870934456;19375.90312048459;19388;29644;29924.700752992627;30095.548566771515;30141.547876781864;30158.84920575383;9979.301448898508;10084.344517606332;10245.48772561369;10257.435841028666;10405.606710456637;8652.653893844301;8663.748841011744;8671.479711217347;8886.200241978253;8907.287417006624;8491.575421229038;8599.355048371397;8610.933375989718;8683.696070637498;8737.737867863974;1965.6756635155325;3674.2467794102145;3711.3505136601525;3768.4158955361963;3795.9620403795607;870.56906831119;952.590386133969;1013.1337706261188;1040.266612038511;1082.6589624268488;20610.176875058714;21019;21954.670679939813;22020.898955052504;22095.779042209695;1091.9890801091888;1110.9222354435112;1115.609536662178;1161.5760247509597;1164.301419148504;10762.892371076345;11034.337939723642;11071.391073490875;11097.223194376478;11105.888941110485;801368;808789;814586;823349;841820;174977;176348;176375.0037155561;177719;178066;1326.4826717579958;1334.9733005339838;1364.808926750236;1365.9112157709717;1368.4457794593206;17941.372051978116;18545.629087417907;18560.628787424444;18655;18683.439496815117;47861;50102.74743131451;58073.65579906512;58364.4163558359;58387.416125839896;78027.21972780199;78589.42821143659;80062;80084.59957700175;80383.19616803757;7701.730439434593;9600.471974041495;11102;11520.659008887329;11611.361375124448;7715.845683086419;12945.093843431247;13223.206607603575;13293.069485135948;13420.597382078553;8897.91102088971;11158.776824463628;11271.774564508827;11312.321260724382;11657.125715571197;10502.317349372266;10626.84059739089;10644.988726071011;10940.1794865387;10953.671389858317;9752.414855108716;11214.439278036229;11305.491252893804;11537.250078744855;11716.355600442057;10600.25798194119;10794.625182459846;11219;11327;11572.826407603725;11185.832212516656;11232.494537745984;11607.30356178632;11663.300201987908;11668.824967625322 12:38:48 INFO - PROCESS | 1643 | |3;mootools.html;1611.8710503159748;1622.716024695678;1659.8506134447898;1676.5138109948111;1679.689257487365;10800.67597972061;11432.771344573124;11447.885521144786;11448.08415326775;11478.655640330804;11139.387333696643;11188.776224475527;11447.542098316078;11696.532138714463;11706.824397634042;2377.3581133094053;2422.0311875249863;2427.077710470028;2427.2718184544656;2434.902603895842;2318.1770471482632;2392.3660230039054;2394.73657897632;2399.592069348206;2400.375902265415;2322.7909488146065;2379.3694670912223;2381.4879800842814;2401.6997875265592;2405.7233418156875;2291.5073259249343;2352.2472808701223;2355.2463211772238;2366.4912043910554;2379.48840999186;2341.484873327865;2366.2309749331534;2368.6328619064075;2370.158593699229;2372.6678265042906;11321.490532926038;11604.12969027318;11614.535418583246;11616.651500455;11625.069994400428;5588.776448942038;5777.45114214149;5779.797707080274;5789.131630255453;5794.130880367937;3402.8298585070893;3510.8771193008124;3511.6312787157235;3569.821508924543;3580.910477238064;1164.48180559651;1169.7309618787726;1206.619914726867;1214.7752665756864;1241.4351470081092;11914.46384912681;13799.724005519833;14126.929365353108;16091.436799711953;16272.75590866138;3646.726495512847;3753.6246375362794;4567.771611419415;4583.954160458419;4586.862394128181;12305.200161989442;12309.446074926649;12318.199317044364;12341.50633974631;12344.506219751289;4890.535399137077;5044.54599086084;5621.49406553404;5749.597528172988;5806.68063256525;5076.441591425014;5250.238715386324;5274;5378.058839703036;5379.058664733656;3348.665133486632;3975.8807235782974;4003.5195776507007;4039.5758445363404;4095.1604920991126;930.5672862119012;1036.3781730961514;1047.3349423116224;1062.3731998121145;1064.8030114428855;725.9256300675031;841.9957900210583;856.7729551668813;865.6667183134539;867.9826403471895;6656.500762442836;6669.199696036507;6695.330466953265;6725.596464212163;6935.4104901082965;895.4806212396794;1008.5007921079113;1022;1036.0002597493303;1043.014351437884;3594.903554415876;4135.834566617302;4260.850870219528;4266.210751011073;4350.564943505624;13372;13407.530736424178;13472.663183420493;13814.861851381558;13990.580282591538;13411.597652070453;13646.863531364757;13783.931080344533;13833.308334583231;13846;10160.628315177357;10415.114715249138;10430.904755000767;10433.165346772392;10450.947745261225;6442.710078046593;6491.902621460777;6528.477721782341;6530.346965303432;6560.737570497317;12425.62723118308;12536.623901282612;12587.874121259036;12715.618531444072;12815.487380504675;12698.111132220656;12728;12733.745325093261;12855.614331570068;12942.676433089247;9305.069493050642;9521.095495927875;9521.381110227812;9539.284553658365;9557.900841403327;10302.793944121226;10313.9484302578;10439.895601044196;10495.21285903545;10546.998035186645;8800;8927.642894284156;8935.865962010444;9076.047015063587;9162.679306224516;8626.525541095049;8712.21590056898;8770;8821.382503224713;8823.911760882309;7816.257455541714;7999.560024198725;8034.357251419756;8034.718784842503;8045.316148127475;5701.572382071278;5711.828645140652;5790.843546505203;5875.882482350415;5956.583039187389;5696.772129114789;5777.277840269966;6000.969995149996;6019.638821670714;6114.174586430953 12:38:48 INFO - PROCESS | 1643 | |4;prototype.html;376.59704116595253;392.72116797074074;395.1031159268459;397.6043836382798;400.9799510024498;4258.318669012959;4527.615152712019;4669.295707066917;4924.827631032915;5222.68663880167;4129.4012368206595;4190.916181676373;4543.727376357421;4996.875078123048;5015.147424937761;3936.8976686527676;4250.128723611652;4374.781260936957;4391.736495810245;4404.29531274996;3750.768292865754;4179;4456.384954956746;4476.709013914102;4498.527654596253;3737;3741.9709579865485;4322.632576231008;4380.430544029272;4383.890402739925;4340.804663790122;4362.585554372329;4392.670549708785;4436.755978421185;4492.640588752892;4092.611201935841;4129.958700412987;4205.453291072172;4235.91528169438;4293;4203.285441474956;4321.925475771616;4495.527969563214;4515.435570553681;5154.299081303161;4330.610245077926;4765.499622539618;4890.413150421937;4890.413150421937;4897.853064408074;4730.692504987165;5004.499550045003;5229.450907654679;5422.2951016368015;5460.34475862895;3738.065483629093;4031.6169963853386;4093.283675356801;4320.805563749639;4417.977910110461;4659.6505262105475;4796.3524924135145;5644;5688.468631458961;5692.580653222447;3969.4442778011103;4026.174634199982;4458.264386376277;4465.687401881902;4499.460064792246;4292.4938504733145;4415.955840441618;4479.126570318756;4557.339185818038;4599.4710608280475;4327.978360108179;4694.286585396306;4846.80612775485;4897.229133567204;4921.827736029221;5095.949040509621;5438.477226376621;5444.047291723932;5482.23248745176;5490.972545137329;1474.837767845536;1480.1341215388902;1483.323844056231;1487.68014876802;1547.7136729705041;1122.680036189682;1165.7843298989715;1165.7959857024987;1181.373871847922;1181.8936295733424;4352.890013046653;4654.837080702227;4993.17612593918;5032.672876263031;5035.269885866529;5182.844514664566;5237.521918759482;5301.496357845998;5411.485908838654;5516.917246241229;4665.932470405768;5016.247562865599;5030.245463180552;5047.545720885138;5081.923771143436;2455.502143692346;2523.6719226500804;2878.7984841061343;2901.5202246853833;2940.7353338199664;5766.538676905921;5793.681347525842;5802.24570805801;5814.156947242626;5821.7380217890295;5170.715610641376;5388.946110538923;5683.573731970118;5689.374168841424;5748.19525266463;4819.59033482158;5573.965502591606;5696.857578560486;5772;5850.3564607893095;4863.95136048635;4894.95105048945;5562.164485719783;5562.189899918569;5651.802186923438;4944.431390390007;5010.09818232707;5102.540771330598;5215.582753379645;5400.9459905400445;5345.670007294104;5752.71236438168;5764.59647824665;5769.394213607511;5851.180834683233;3793.905152371213;3964.980175099106;4464.15181115587;4510.751908644925;4520.011195074157;4578.4963653997365;5007.118059891722;5188.481151884932;5296.894062118813;5596.832095037155;4663.650226233114;5328.987492376436;5412.78348866041;5458.6724796512335;5461.590380721383;4999.70013793659;5003.098203042154;5438.564914806727;5531.03206938776;5535.197396377584;4332.9133417332105;4796.56830885222;5129.659482158127;5254.842354729364;5372.677639341652;4854.490278520704;5175.456577059505;5415.620906536505;5488.753006114654;5508.053191542505;4626.745528995937;4733.621310295237;5098.954714283489;5346.973265133805;5381.515663590296 12:38:48 INFO - PROCESS | 1643 | |5;yui.html;767.3822572828874;788.8027993001749;805.5086397297647;887.9911200887989;909.3407279722201;16290.10404427756;16574.502764917055;16589.585260368483;17297.74053389197;17492.650146997083;15874.84125158748;16073.758893616605;16086.276117574707;16102.7584586231;16103;988.8220120378329;1014.8274793285159;1016.5222345497641;1054.0144964458218;1056.6354607660344;1039.173856783855;1055.3826011783117;1060.1095080132725;1062.5112448273765;1063.8882917293688;1086.6794295682791;1087.141158484796;1087.2389327470762;1087.722630729167;1088.771358014818;1077.2082519348273;1077.7090185649909;1078.6925726168004;1079.3308148947624;1080.71361089311;805.8589746794346;806.1334065879204;806.2018601584448;806.6128258435925;808.6118663041714;17569.23813131821;17723;17938.103094845206;18275.543111422186;18534.258629654796;15625.984311019747;15965.680686386324;15986.600334991603;16091.356345746157;16262.031317339444;11684.824727629093;12132.939335303356;12307.4461649226;12347.012239020856;12731.172473789267;4027.51669799623;4062.837486500536;4068.2677118118727;4157.771322577257;4194.8112334945;115342;123351.38324308321;123978;125567;127445;11826.763464730657;11841.88158118425;12044;12514.3742812859;12579.622611321674;26889.596656050177;27365.589516157273;27564.448711026067;27584.448311033666;27590;8345.082040975487;8404.74785756416;8443.324534037385;8535.445196062235;8615.181557752003;4474.149911516802;4614.353990441341;4658.277966915133;4700.929486057712;4780.020095880336;3369.4440417331357;3459.3254315408253;3464.4803279507773;3467.289205712823;3472.930541389158;605.3855336833118;607.313735478906;612.1796792298342;614.744880874441;615.1203778596649;609.9566452384477;631.9665605095523;639.8080575827233;671.5735507952485;716.2694052066863;8153.633086511121;10364.844527332096;10370.481475926174;10451.216158788122;10684.839727404094;667.3493343989571;713.4363852556422;721.2030706069822;725.4595326481805;726.2338233164063;4737.478877323491;4996.500349964975;4997.372934283723;5018.197088465901;5024.974875125601;110883.44558277319;111974;112028;112975;113327;54814.177787333225;66357;87525.99999999872;94444;100679.49660251799;70714;71412.64293678498;71658.92511612434;72338.63830680813;73239.26760732324;11226.382548959558;11274.379909105079;11308.547658093585;11408.54365825391;11521.654350369503;39562;51385.74307128441;52810.73594632002;53228.14698827735;53775.6556086101;65745.34254657393;65933;66359;68744.62510749657;68762;12807.423665934868;13584.660383490493;14376.35306411193;14470.493532726305;14506.709865802835;15157.621059473602;15586.9220653896;15634.843651563338;15902.60243493922;16480;13890.166590004632;14762.63093422673;14800.185989770665;14989.250537473301;15081;12334.50661973511;14128.78806817878;14675.41298348054;14707;14755.704885902436;14212.21832799206;14401.279936003368;14560.344784484936;14651.413943442569;15216.315265813286;9757.51212439361;10097.141742951637;10212.489375531046;10548.789024219626;10716.089132423831;10820.513076911715;10866.67399978002;11360.977512023957;11431.59989400367;11495.770084598094 12:38:48 INFO - PROCESS | 1643 | __end_tp_report 12:38:48 INFO - PROCESS | 1643 | __start_cc_report 12:38:48 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,1576 12:38:48 INFO - PROCESS | 1643 | __end_cc_report 12:38:48 INFO - PROCESS | 1643 | __startTimestamp1496950728207__endTimestamp 12:38:48 INFO - PROCESS | 1643 | 12:38:48 INFO - PROCESS | 1643 | ------- Summary: start ------- 12:38:48 INFO - PROCESS | 1643 | Number of tests: 6 12:38:48 INFO - PROCESS | 1643 | 12:38:48 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5991.41 Median:6259.12 stddev:735.69 (11.8%) stddev-sans-first:704.97 12:38:48 INFO - PROCESS | 1643 | Values: 3215.0 3568.4 3584.1 3585.6 3625.3 4360.6 6129.4 6130.7 6145.4 6150.9 6080.3 6107.5 6154.4 6170.7 6182.0 5395.1 5428.7 5448.9 5458.4 5552.5 4312.5 5338.9 5385.4 5439.2 5554.2 5464.1 5502.3 5526.8 5527.1 5572.9 4474.2 5493.9 5504.9 5506.6 5521.8 5430.2 5457.8 5460.3 5462.6 5462.9 5869.1 5892.5 5991.3 6013.7 6016.4 6386.5 6395.3 6407.1 6460.3 6521.4 6029.9 6580.4 6589.9 6618.8 6643.7 5297.5 6245.2 6259.2 6261.8 6278.4 6425.6 6507.7 6586.4 6758.1 6771.9 6327.1 6366.8 6367.0 6420.2 6456.8 6550.5 6633.3 6633.3 6641.5 6641.8 6508.4 6520.7 6533.2 6544.7 6622.6 6311.3 6364.2 6426.5 6437.6 6557.9 5233.2 5233.8 5252.9 5270.2 5283.0 3580.0 4626.9 4637.3 4661.5 4719.9 5021.9 5851.2 6187.9 6200.3 6202.1 6398.5 6438.4 6460.7 6549.8 6570.2 5107.8 6108.9 6190.3 6210.5 6215.8 5690.5 5694.9 5705.3 5706.1 5711.0 6512.0 6607.8 6648.0 6690.3 6692.3 6538.5 6546.1 6547.2 6556.9 6575.9 6652.2 6786.6 6793.3 6800.1 6800.5 6292.0 6350.3 6423.7 6469.0 6493.3 6596.6 6597.4 6598.9 6604.7 6628.1 6212.6 6259.5 6314.7 6410.7 6443.1 4520.8 6231.8 6259.1 6300.6 6321.8 6117.0 6146.1 6224.3 6239.6 6250.6 6220.9 6307.4 6308.1 6389.9 6416.6 6307.5 6313.9 6321.8 6327.6 6347.8 12:38:48 INFO - PROCESS | 1643 | 12:38:48 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:57083.07 Median:17545.93 stddev:116591.14 (664.5%) stddev-sans-first:116841.61 12:38:48 INFO - PROCESS | 1643 | Values: 793.5 815.3 835.2 915.5 922.7 30497.7 42969.4 43547.3 43630.6 43695.1 44123.0 44411.0 44445.3 44500.1 44598.1 9673.9 9850.5 9856.1 9860.4 9866.3 7969.2 8110.5 8193.2 8193.8 8209.8 10159.0 11881.4 12062.0 12311.4 12330.4 11977.5 12037.2 12049.7 12050.9 12055.4 11621.1 11655.9 11677.1 11683.8 11698.9 42002.4 42187.6 42271.4 42306.6 42398.4 4009.4 4040.2 4049.3 4093.7 4100.9 29484.0 29833.3 30353.8 30609.8 31463.0 2699.1 2855.7 3274.0 3314.3 3428.1 339606.0 343880.3 344014.3 344244.3 344927.0 55431.0 55486.4 55487.2 55589.7 55607.7 45450.0 46990.8 57805.4 57960.1 58019.1 15406.2 15858.6 15883.3 15902.8 15910.2 13698.7 13706.8 13720.7 13740.5 13780.2 9790.2 11437.5 12734.4 12775.2 13075.8 5876.6 5882.5 5890.9 5912.1 5919.5 982.8 1150.4 1157.8 1170.2 1219.5 24890.3 25435.6 28045.7 28586.6 28768.4 1095.3 1099.3 1099.9 1141.5 1142.3 13620.9 13727.3 13770.4 13889.9 13932.0 496292.0 496542.0 497765.0 499814.0 500450.0 42107.8 42780.9 44680.0 48217.0 49426.8 1489.7 1495.8 1496.6 1513.8 1517.2 26952.0 27051.0 27507.3 27649.9 27757.6 36986.4 47262.8 48350.0 50669.7 50829.5 443038.0 447682.0 466930.0 470911.9 471052.0 30340.7 30650.6 31318.4 31693.7 31817.9 39294.4 39344.4 39368.4 39940.6 39993.4 27879.3 28531.0 28843.7 29221.7 29571.6 26105.7 28966.9 29144.4 29302.4 29371.6 32829.5 34042.8 34054.5 34088.1 34106.5 25657.7 27102.5 29255.0 29469.4 29785.4 22154.4 23712.6 23791.2 24042.9 24102.6 12:38:48 INFO - PROCESS | 1643 | 12:38:48 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:45331.03 Median:21773.01 stddev:136676.56 (627.7%) stddev-sans-first:137018.39 12:38:48 INFO - PROCESS | 1643 | Values: 429.3 429.3 433.9 443.4 447.4 19389.2 20751.3 21599.5 21786.6 21793.6 19241.8 21072.7 21759.5 21956.5 22550.4 6697.8 7170.2 7173.0 7182.0 7186.7 7498.6 7810.5 7982.3 7990.8 7998.4 6749.9 7682.6 7832.3 7923.7 7950.6 7695.8 7900.4 7966.1 7967.6 7968.5 7492.8 7724.8 7752.0 7762.7 7768.7 24520.3 24923.4 24937.3 25291.4 25371.1 3167.3 3359.4 3428.9 3481.7 3584.0 15229.5 15329.2 15356.8 15388.7 15492.0 1969.8 2067.0 2124.8 2143.9 2152.3 149845.0 169538.6 169787.0 169892.0 170199.1 14648.5 16480.6 17089.4 19375.9 19388.0 29644.0 29924.7 30095.5 30141.5 30158.8 9979.3 10084.3 10245.5 10257.4 10405.6 8652.7 8663.7 8671.5 8886.2 8907.3 8491.6 8599.4 8610.9 8683.7 8737.7 1965.7 3674.2 3711.4 3768.4 3796.0 870.6 952.6 1013.1 1040.3 1082.7 20610.2 21019.0 21954.7 22020.9 22095.8 1092.0 1110.9 1115.6 1161.6 1164.3 10762.9 11034.3 11071.4 11097.2 11105.9 801368.0 808789.0 814586.0 823349.0 841820.0 174977.0 176348.0 176375.0 177719.0 178066.0 1326.5 1335.0 1364.8 1365.9 1368.4 17941.4 18545.6 18560.6 18655.0 18683.4 47861.0 50102.7 58073.7 58364.4 58387.4 78027.2 78589.4 80062.0 80084.6 80383.2 7701.7 9600.5 11102.0 11520.7 11611.4 7715.8 12945.1 13223.2 13293.1 13420.6 8897.9 11158.8 11271.8 11312.3 11657.1 10502.3 10626.8 10645.0 10940.2 10953.7 9752.4 11214.4 11305.5 11537.3 11716.4 10600.3 10794.6 11219.0 11327.0 11572.8 11185.8 11232.5 11607.3 11663.3 11668.8 12:38:48 INFO - PROCESS | 1643 | 12:38:48 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:6793.90 Median:2399.98 stddev:4301.07 (179.2%) stddev-sans-first:4295.51 12:38:48 INFO - PROCESS | 1643 | Values: 1611.9 1622.7 1659.9 1676.5 1679.7 10800.7 11432.8 11447.9 11448.1 11478.7 11139.4 11188.8 11447.5 11696.5 11706.8 2377.4 2422.0 2427.1 2427.3 2434.9 2318.2 2392.4 2394.7 2399.6 2400.4 2322.8 2379.4 2381.5 2401.7 2405.7 2291.5 2352.2 2355.2 2366.5 2379.5 2341.5 2366.2 2368.6 2370.2 2372.7 11321.5 11604.1 11614.5 11616.7 11625.1 5588.8 5777.5 5779.8 5789.1 5794.1 3402.8 3510.9 3511.6 3569.8 3580.9 1164.5 1169.7 1206.6 1214.8 1241.4 11914.5 13799.7 14126.9 16091.4 16272.8 3646.7 3753.6 4567.8 4584.0 4586.9 12305.2 12309.4 12318.2 12341.5 12344.5 4890.5 5044.5 5621.5 5749.6 5806.7 5076.4 5250.2 5274.0 5378.1 5379.1 3348.7 3975.9 4003.5 4039.6 4095.2 930.6 1036.4 1047.3 1062.4 1064.8 725.9 842.0 856.8 865.7 868.0 6656.5 6669.2 6695.3 6725.6 6935.4 895.5 1008.5 1022.0 1036.0 1043.0 3594.9 4135.8 4260.9 4266.2 4350.6 13372.0 13407.5 13472.7 13814.9 13990.6 13411.6 13646.9 13783.9 13833.3 13846.0 10160.6 10415.1 10430.9 10433.2 10450.9 6442.7 6491.9 6528.5 6530.3 6560.7 12425.6 12536.6 12587.9 12715.6 12815.5 12698.1 12728.0 12733.7 12855.6 12942.7 9305.1 9521.1 9521.4 9539.3 9557.9 10302.8 10313.9 10439.9 10495.2 10547.0 8800.0 8927.6 8935.9 9076.0 9162.7 8626.5 8712.2 8770.0 8821.4 8823.9 7816.3 7999.6 8034.4 8034.7 8045.3 5701.6 5711.8 5790.8 5875.9 5956.6 5696.8 5777.3 6001.0 6019.6 6114.2 12:38:48 INFO - PROCESS | 1643 | 12:38:48 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4513.42 Median:4808.08 stddev:1241.67 (25.8%) stddev-sans-first:1205.70 12:38:48 INFO - PROCESS | 1643 | Values: 376.6 392.7 395.1 397.6 401.0 4258.3 4527.6 4669.3 4924.8 5222.7 4129.4 4190.9 4543.7 4996.9 5015.1 3936.9 4250.1 4374.8 4391.7 4404.3 3750.8 4179.0 4456.4 4476.7 4498.5 3737.0 3742.0 4322.6 4380.4 4383.9 4340.8 4362.6 4392.7 4436.8 4492.6 4092.6 4130.0 4205.5 4235.9 4293.0 4203.3 4321.9 4495.5 4515.4 5154.3 4330.6 4765.5 4890.4 4890.4 4897.9 4730.7 5004.5 5229.5 5422.3 5460.3 3738.1 4031.6 4093.3 4320.8 4418.0 4659.7 4796.4 5644.0 5688.5 5692.6 3969.4 4026.2 4458.3 4465.7 4499.5 4292.5 4416.0 4479.1 4557.3 4599.5 4328.0 4694.3 4846.8 4897.2 4921.8 5095.9 5438.5 5444.0 5482.2 5491.0 1474.8 1480.1 1483.3 1487.7 1547.7 1122.7 1165.8 1165.8 1181.4 1181.9 4352.9 4654.8 4993.2 5032.7 5035.3 5182.8 5237.5 5301.5 5411.5 5516.9 4665.9 5016.2 5030.2 5047.5 5081.9 2455.5 2523.7 2878.8 2901.5 2940.7 5766.5 5793.7 5802.2 5814.2 5821.7 5170.7 5388.9 5683.6 5689.4 5748.2 4819.6 5574.0 5696.9 5772.0 5850.4 4864.0 4895.0 5562.2 5562.2 5651.8 4944.4 5010.1 5102.5 5215.6 5400.9 5345.7 5752.7 5764.6 5769.4 5851.2 3793.9 3965.0 4464.2 4510.8 4520.0 4578.5 5007.1 5188.5 5296.9 5596.8 4663.7 5329.0 5412.8 5458.7 5461.6 4999.7 5003.1 5438.6 5531.0 5535.2 4332.9 4796.6 5129.7 5254.8 5372.7 4854.5 5175.5 5415.6 5488.8 5508.1 4626.7 4733.6 5099.0 5347.0 5381.5 12:38:48 INFO - PROCESS | 1643 | 12:38:48 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:21483.18 Median:16182.52 stddev:30845.59 (190.6%) stddev-sans-first:30892.90 12:38:48 INFO - PROCESS | 1643 | Values: 767.4 788.8 805.5 888.0 909.3 16290.1 16574.5 16589.6 17297.7 17492.7 15874.8 16073.8 16086.3 16102.8 16103.0 988.8 1014.8 1016.5 1054.0 1056.6 1039.2 1055.4 1060.1 1062.5 1063.9 1086.7 1087.1 1087.2 1087.7 1088.8 1077.2 1077.7 1078.7 1079.3 1080.7 805.9 806.1 806.2 806.6 808.6 17569.2 17723.0 17938.1 18275.5 18534.3 15626.0 15965.7 15986.6 16091.4 16262.0 11684.8 12132.9 12307.4 12347.0 12731.2 4027.5 4062.8 4068.3 4157.8 4194.8 115342.0 123351.4 123978.0 125567.0 127445.0 11826.8 11841.9 12044.0 12514.4 12579.6 26889.6 27365.6 27564.4 27584.4 27590.0 8345.1 8404.7 8443.3 8535.4 8615.2 4474.1 4614.4 4658.3 4700.9 4780.0 3369.4 3459.3 3464.5 3467.3 3472.9 605.4 607.3 612.2 614.7 615.1 610.0 632.0 639.8 671.6 716.3 8153.6 10364.8 10370.5 10451.2 10684.8 667.3 713.4 721.2 725.5 726.2 4737.5 4996.5 4997.4 5018.2 5025.0 110883.4 111974.0 112028.0 112975.0 113327.0 54814.2 66357.0 87526.0 94444.0 100679.5 70714.0 71412.6 71658.9 72338.6 73239.3 11226.4 11274.4 11308.5 11408.5 11521.7 39562.0 51385.7 52810.7 53228.1 53775.7 65745.3 65933.0 66359.0 68744.6 68762.0 12807.4 13584.7 14376.4 14470.5 14506.7 15157.6 15586.9 15634.8 15902.6 16480.0 13890.2 14762.6 14800.2 14989.3 15081.0 12334.5 14128.8 14675.4 14707.0 14755.7 14212.2 14401.3 14560.3 14651.4 15216.3 9757.5 10097.1 10212.5 10548.8 10716.1 10820.5 10866.7 11361.0 11431.6 11495.8 12:38:48 INFO - PROCESS | 1643 | -------- Summary: end -------- 12:38:48 INFO - PROCESS | 1643 | 12:38:48 INFO - TEST-INFO | 1643: exit 0 12:38:48 INFO - TEST-OK | dromaeo_css | took 1119004ms 12:38:48 INFO - TEST-START | kraken 12:38:48 INFO - operating with platform_type : mac_ 12:38:48 INFO - Initialising browser for kraken test... 12:38:48 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpEWS0gX/profile http://localhost:49229/getInfo.html) 12:38:51 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 12:38:51 INFO - PROCESS | 1655 | colorDepth:24 12:38:51 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 12:38:51 INFO - PROCESS | 1655 | __metrics 12:38:51 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:38:51 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:38:51 INFO - TEST-INFO | 1655: exit 0 12:38:51 INFO - Browser initialized. 12:38:51 INFO - Running cycle 1/1 for kraken test... 12:38:51 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/T/tmpEWS0gX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:39:03 INFO - PROCESS | 1657 | RSS: Main: 165609472 12:39:03 INFO - PROCESS | 1657 | 12:39:06 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection) 12:39:07 INFO - PROCESS | 1657 | RSS: Main: 216584192 12:39:07 INFO - PROCESS | 1657 | 12:39:10 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-dft) 12:39:10 INFO - PROCESS | 1657 | RSS: Main: 231870464 12:39:10 INFO - PROCESS | 1657 | 12:39:13 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-fft) 12:39:14 INFO - PROCESS | 1657 | RSS: Main: 327966720 12:39:14 INFO - PROCESS | 1657 | 12:39:16 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator) 12:39:17 INFO - PROCESS | 1657 | RSS: Main: 230645760 12:39:17 INFO - PROCESS | 1657 | 12:39:18 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:39:19 INFO - PROCESS | 1657 | RSS: Main: 231960576 12:39:19 INFO - PROCESS | 1657 | 12:39:22 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom) 12:39:23 INFO - PROCESS | 1657 | RSS: Main: 272420864 12:39:23 INFO - PROCESS | 1657 | 12:39:26 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate) 12:39:26 INFO - PROCESS | 1657 | RSS: Main: 273645568 12:39:26 INFO - PROCESS | 1657 | 12:39:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial) 12:39:30 INFO - PROCESS | 1657 | RSS: Main: 269963264 12:39:30 INFO - PROCESS | 1657 | 12:39:31 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:39:32 INFO - PROCESS | 1657 | RSS: Main: 248119296 12:39:32 INFO - PROCESS | 1657 | 12:39:34 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:39:34 INFO - PROCESS | 1657 | RSS: Main: 251940864 12:39:34 INFO - PROCESS | 1657 | 12:39:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:39:37 INFO - PROCESS | 1657 | RSS: Main: 256827392 12:39:37 INFO - PROCESS | 1657 | 12:39:40 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:39:41 INFO - PROCESS | 1657 | RSS: Main: 271540224 12:39:41 INFO - PROCESS | 1657 | 12:39:43 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:39:44 INFO - PROCESS | 1657 | RSS: Main: 264921088 12:39:44 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49229/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49229/tests/kraken/driver.html?testName=ai-astar) 12:39:46 INFO - PROCESS | 1657 | RSS: Main: 309694464 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | __start_tp_report 12:39:46 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 12:39:46 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 12:39:46 INFO - PROCESS | 1657 | |i|pagename|runs| 12:39:46 INFO - PROCESS | 1657 | |0;ai-astar;103;115;115;115;124;171;171;115;115;113 12:39:46 INFO - PROCESS | 1657 | |1;audio-beat-detection;133;105;107;139;108;107;108;105;124;109 12:39:46 INFO - PROCESS | 1657 | |2;audio-dft;181;185;182;183;184;184;181;183;181;184 12:39:46 INFO - PROCESS | 1657 | |3;audio-fft;82;59;61;93;63;58;62;75;89;61 12:39:46 INFO - PROCESS | 1657 | |4;audio-oscillator;88;69;85;68;69;68;79;67;66;81 12:39:46 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;89;88;87;86;89;87;88;88;87;87 12:39:46 INFO - PROCESS | 1657 | |6;imaging-darkroom;94;94;95;93;94;95;93;103;94;93 12:39:46 INFO - PROCESS | 1657 | |7;imaging-desaturate;83;84;84;84;83;86;83;84;83;84 12:39:46 INFO - PROCESS | 1657 | |8;json-parse-financial;63;59;60;60;62;63;65;62;64;67 12:39:46 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;54;64;68;61;59;56;58;57;57;57 12:39:46 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;78;83;81;85;83;83;79;80;82;89 12:39:46 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;135;137;146;138;125;134;138;137;177;137 12:39:46 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;163;170;161;160;161;163;166;160;160;156 12:39:46 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;66;64;65;64;63;62;63;63;62;62 12:39:46 INFO - PROCESS | 1657 | __end_tp_report 12:39:46 INFO - PROCESS | 1657 | __start_cc_report 12:39:46 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2084 12:39:46 INFO - PROCESS | 1657 | __end_cc_report 12:39:46 INFO - PROCESS | 1657 | __startTimestamp1496950786449__endTimestamp 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | ------- Summary: start ------- 12:39:46 INFO - PROCESS | 1657 | Number of tests: 14 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:125.70 Median:115.00 stddev:24.40 (21.2%) stddev-sans-first:24.45 12:39:46 INFO - PROCESS | 1657 | Values: 103.0 115.0 115.0 115.0 124.0 171.0 171.0 115.0 115.0 113.0 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:114.50 Median:108.50 stddev:12.65 (11.7%) stddev-sans-first:11.51 12:39:46 INFO - PROCESS | 1657 | Values: 133.0 105.0 107.0 139.0 108.0 107.0 108.0 105.0 124.0 109.0 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.50 stddev:1.48 (0.8%) stddev-sans-first:1.41 12:39:46 INFO - PROCESS | 1657 | Values: 181.0 185.0 182.0 183.0 184.0 184.0 181.0 183.0 181.0 184.0 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:70.30 Median:69.00 stddev:13.33 (19.3%) stddev-sans-first:13.44 12:39:46 INFO - PROCESS | 1657 | Values: 82.0 59.0 61.0 93.0 63.0 58.0 62.0 75.0 89.0 61.0 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:74.00 Median:74.00 stddev:8.34 (11.3%) stddev-sans-first:7.14 12:39:46 INFO - PROCESS | 1657 | Values: 88.0 69.0 85.0 68.0 69.0 68.0 79.0 67.0 66.0 81.0 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.00 stddev:0.97 (1.1%) stddev-sans-first:0.88 12:39:46 INFO - PROCESS | 1657 | Values: 89.0 88.0 87.0 86.0 89.0 87.0 88.0 88.0 87.0 87.0 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:94.00 stddev:2.97 (3.2%) stddev-sans-first:3.14 12:39:46 INFO - PROCESS | 1657 | Values: 94.0 94.0 95.0 93.0 94.0 95.0 93.0 103.0 94.0 93.0 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:83.80 Median:84.00 stddev:0.92 (1.1%) stddev-sans-first:0.93 12:39:46 INFO - PROCESS | 1657 | Values: 83.0 84.0 84.0 84.0 83.0 86.0 83.0 84.0 83.0 84.0 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:63.00 stddev:2.46 (3.9%) stddev-sans-first:2.60 12:39:46 INFO - PROCESS | 1657 | Values: 63.0 59.0 60.0 60.0 62.0 63.0 65.0 62.0 64.0 67.0 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:58.50 stddev:4.18 (7.1%) stddev-sans-first:4.00 12:39:46 INFO - PROCESS | 1657 | Values: 54.0 64.0 68.0 61.0 59.0 56.0 58.0 57.0 57.0 57.0 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:83.00 stddev:3.16 (3.8%) stddev-sans-first:2.95 12:39:46 INFO - PROCESS | 1657 | Values: 78.0 83.0 81.0 85.0 83.0 83.0 79.0 80.0 82.0 89.0 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:140.40 Median:137.50 stddev:13.84 (10.1%) stddev-sans-first:14.54 12:39:46 INFO - PROCESS | 1657 | Values: 135.0 137.0 146.0 138.0 125.0 134.0 138.0 137.0 177.0 137.0 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.00 Median:162.00 stddev:3.83 (2.4%) stddev-sans-first:4.04 12:39:46 INFO - PROCESS | 1657 | Values: 163.0 170.0 161.0 160.0 161.0 163.0 166.0 160.0 160.0 156.0 12:39:46 INFO - PROCESS | 1657 | 12:39:46 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.40 Median:63.50 stddev:1.35 (2.1%) stddev-sans-first:1.05 12:39:46 INFO - PROCESS | 1657 | Values: 66.0 64.0 65.0 64.0 63.0 62.0 63.0 63.0 62.0 62.0 12:39:46 INFO - PROCESS | 1657 | -------- Summary: end -------- 12:39:46 INFO - PROCESS | 1657 | 12:39:47 INFO - TEST-INFO | 1657: exit 0 12:39:47 INFO - TEST-OK | kraken | took 58475ms 12:39:47 INFO - SUITE-END | took 1177s 12:39:47 INFO - Completed test suite (00:19:37) 12:39:47 INFO - javascript benchmark 12:39:47 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7819.478422452551, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3214.967850321497, 3568.3933731265683, 3584.1039740064984, 3585.5697316322044, 3625.3111908737337, 4360.629346505543, 6129.448349648542, 6130.693465326738, 6145.446909778119, 6150.876982460354, 6080.331163572015, 6107.4808641265545, 6154.3537928517535, 6170.691465426733, 6181.969090154555, 5395.136778115502, 5428.67427954324, 5448.945510544903, 5458.399576046631, 5552.500274975251, 4312.525622181573, 5338.946610533883, 5385.434529374418, 5439.211314359415, 5554.166874968747, 5464.0711079116645, 5502.312210973629, 5526.834194974137, 5527.060399732035, 5572.916406253909, 4474.239379305526, 5493.862653433696, 5504.862378440531, 5506.559475241971, 5521.779128834882, 5430.15832545956, 5457.808976685797, 5460.34475862899, 5462.562994960394, 5462.863428414322, 5869.090291004901, 5892.469677728982, 5991.281046274475, 6013.6993150342305, 6016.398360163992, 6386.521010924199, 6395.264544577387, 6407.070974708641, 6460.3216662250725, 6521.380468855451, 6029.909551356734, 6580.4406625436895, 6589.86820263592, 6618.834529136762, 6643.734250629969, 5297.470252974749, 6245.18812554365, 6259.155014073087, 6261.843453913689, 6278.434940855299, 6425.614463132227, 6507.739690412331, 6586.407223349922, 6758.087658166183, 6771.864562708718, 6327.114204011442, 6366.808995730135, 6366.968165159237, 6420.165378500765, 6456.7740129095255, 6550.475961923034, 6633.303503132198, 6633.336666333328, 6641.535092543476, 6641.833954151185, 6508.381703738232, 6520.673966301666, 6533.216014078341, 6544.705488252945, 6622.602643841385, 6311.33730958252, 6364.236291644939, 6426.518011149183, 6437.6137431753305, 6557.9344206557325, 5233.162693968947, 5233.764480598382, 5252.921206181911, 5270.156774915995, 5283.0, 3579.982100089535, 4626.907461850744, 4637.281221410687, 4661.510541393172, 4719.858404247878, 5021.895183059721, 5851.180834683148, 6187.876242475125, 6200.286966998853, 6202.069689546604, 6398.520110991694, 6438.3883531064475, 6460.709268082947, 6549.803505894831, 6570.211574610982, 5107.770150343244, 6108.930937085992, 6190.288116866528, 6210.472109870621, 6215.782447614311, 5690.5447564194765, 5694.9430505695245, 5705.258316418922, 5706.11555208935, 5710.971445142748, 6512.0, 6607.801765947125, 6648.0, 6690.264070952094, 6692.297308782508, 6538.509611779041, 6546.083548303337, 6547.214334279917, 6556.868862622626, 6575.934240657532, 6652.168478940132, 6786.626735529396, 6793.286704895915, 6800.081988931431, 6800.5239633227175, 6292.0, 6350.301466838551, 6423.710933008119, 6469.0, 6493.318201588954, 6596.571222870498, 6597.406233439014, 6598.868022639616, 6604.7027883746305, 6628.072069910313, 6212.624091725233, 6259.467945224706, 6314.684265786784, 6410.711517981796, 6443.065755465337, 4520.841770538015, 6231.781887633911, 6259.12372267892, 6300.59046161998, 6321.841953951188, 6117.035835319669, 6146.108814222002, 6224.284207316216, 6239.563230573816, 6250.562460627712, 6220.906686399617, 6307.400796924285, 6308.053791931247, 6389.93610063912, 6416.647084410402, 6307.526935479949, 6313.936860631335, 6321.810345689637, 6327.620342779448, 6347.841303967253], "value": 5944.99305333504, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [793.456482309618, 815.3314282288524, 835.1940377535677, 915.4827522449815, 922.7370199493146, 30497.695023049764, 42969.35545966809, 43547.346789798175, 43630.563694363045, 43695.126097478074, 44123.0, 44411.0, 44445.33332000014, 44500.10999780011, 44598.10803783922, 9673.854892176623, 9850.50747462624, 9856.063673950963, 9860.408375497458, 9866.309358344919, 7969.163237860028, 8110.513369197837, 8193.221643943845, 8193.754187374388, 8209.753707388758, 10159.0, 11881.40592970348, 12062.0, 12311.4459849307, 12330.383480825967, 11977.520899164023, 12037.217580857216, 12049.698757531045, 12050.87949120515, 12055.397230138546, 11621.07031437483, 11655.941720291428, 11677.124215683858, 11683.824742628867, 11698.941505292503, 42002.369964450554, 42187.57812421867, 42271.365929511085, 42306.57693423088, 42398.36402453966, 4009.3585026395635, 4040.1717647882115, 4049.250888585621, 4093.692973027005, 4100.91798164035, 29484.0, 29833.25416864574, 30353.848230758926, 30609.846950765328, 31463.0, 2699.0701648321547, 2855.7144285571485, 3273.967260327414, 3314.2708604107056, 3428.125827913866, 339606.0, 343880.2805985979, 344014.27992860123, 344244.2787786045, 344927.0, 55431.0, 55486.44513554894, 55487.16769248465, 55589.722051389486, 55607.721961390744, 45450.0, 46990.76504617455, 57805.421945780006, 57960.13059804106, 58019.12971305434, 15406.22968851553, 15858.603534911488, 15883.28525216368, 15902.76145857813, 15910.204489775464, 13698.65753356174, 13706.794398084037, 13720.725585488235, 13740.450381984809, 13780.242086685128, 9790.188815069236, 11437.542498299976, 12734.426950787236, 12775.233485990684, 13075.777117889982, 5876.589618491531, 5882.529397648178, 5890.879283457952, 5912.083627037816, 5919.520119970008, 982.8427451607852, 1150.4075401168404, 1157.7510835170478, 1170.2276497511602, 1219.5487669562142, 24890.253292401256, 25435.61846572303, 28045.71954280472, 28586.571201431994, 28768.424631507252, 1095.2606990281529, 1099.2689861242184, 1099.9450027498754, 1141.49774099396, 1142.3488611491537, 13620.863791362157, 13727.313634318245, 13770.380332885044, 13889.930550347184, 13932.0, 496292.0, 496542.0, 497765.0, 499814.0, 500450.0, 42107.789461052496, 42780.93047673833, 44680.0, 48217.0, 49426.75286623616, 1489.6871656952162, 1495.7980672609058, 1496.551034689567, 1513.8107736532934, 1517.1958861803262, 26951.977457582332, 27051.0, 27507.31231719223, 27649.861750691118, 27757.58363624507, 36986.44520332143, 47262.76368618135, 48350.0, 50669.746651266505, 50829.49170508247, 443038.0, 447682.0, 466930.0, 470911.87264382123, 471052.0, 30340.696593033786, 30650.628430873032, 31318.373632526767, 31693.683063169072, 31817.8863739768, 39294.41058384069, 39344.40983385309, 39368.40947385849, 39940.60059399485, 39993.40009899912, 27879.303017424725, 28531.0, 28843.711562884102, 29221.707782921898, 29571.556426654046, 26105.73894261033, 28966.855165724035, 29144.417111657225, 29302.41395172127, 29371.55942660819, 32829.50755738618, 34042.82978585091, 34054.48918266178, 34088.14779630529, 34106.48840267448, 25657.717114143845, 27102.457950841268, 29254.976075837243, 29469.410611787214, 29785.404291913608, 22154.44613884666, 23712.644310335014, 23791.167309144097, 24042.87978560096, 24102.63846042346], "value": 19019.779690551797, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [429.2530996066845, 429.3082373518042, 433.94575678040246, 443.43019220301915, 447.39824935461814, 19389.22443102276, 20751.273705420313, 21599.46001349967, 21786.564268714636, 21793.564128717455, 19241.80758192421, 21072.683909741365, 21759.45601359967, 21956.45108872275, 22550.43623909399, 6697.832554186148, 7170.247124051978, 7173.0, 7182.0, 7186.748463803768, 7498.587577683225, 7810.49231799934, 7982.28159465648, 7990.800229994238, 7998.440109192359, 6749.932500675004, 7682.6158692065455, 7832.255935686101, 7923.683052677886, 7950.56271905045, 7695.769126926145, 7900.407469439786, 7966.123726390092, 7967.64145613443, 7968.521888686697, 7492.838610015457, 7724.806879827993, 7751.961240193819, 7762.689492420296, 7768.689252429896, 24520.264392068268, 24923.376915577075, 24937.251882443557, 25291.367715807068, 25371.112011079524, 3167.255694911694, 3359.395308844407, 3428.862845486178, 3481.7388695847912, 3584.0, 15229.543113706606, 15329.233538323151, 15356.76964845517, 15388.692226155526, 15492.0, 1969.803019698033, 2066.95866082679, 2124.776898425674, 2143.9464013399634, 2152.332776839169, 149845.0, 169538.60991384677, 169787.0, 169892.0, 170199.14900425542, 14648.487302944346, 16480.587985300463, 17089.401870934456, 19375.90312048459, 19388.0, 29644.0, 29924.700752992627, 30095.548566771515, 30141.547876781864, 30158.84920575383, 9979.301448898508, 10084.344517606332, 10245.48772561369, 10257.435841028666, 10405.606710456637, 8652.653893844301, 8663.748841011744, 8671.479711217347, 8886.200241978253, 8907.287417006624, 8491.575421229038, 8599.355048371397, 8610.933375989718, 8683.696070637498, 8737.737867863974, 1965.6756635155325, 3674.2467794102145, 3711.3505136601525, 3768.4158955361963, 3795.9620403795607, 870.56906831119, 952.590386133969, 1013.1337706261188, 1040.266612038511, 1082.6589624268488, 20610.176875058714, 21019.0, 21954.670679939813, 22020.898955052504, 22095.779042209695, 1091.9890801091888, 1110.9222354435112, 1115.609536662178, 1161.5760247509597, 1164.301419148504, 10762.892371076345, 11034.337939723642, 11071.391073490875, 11097.223194376478, 11105.888941110485, 801368.0, 808789.0, 814586.0, 823349.0, 841820.0, 174977.0, 176348.0, 176375.0037155561, 177719.0, 178066.0, 1326.4826717579958, 1334.9733005339838, 1364.808926750236, 1365.9112157709717, 1368.4457794593206, 17941.372051978116, 18545.629087417907, 18560.628787424444, 18655.0, 18683.439496815117, 47861.0, 50102.74743131451, 58073.65579906512, 58364.4163558359, 58387.416125839896, 78027.21972780199, 78589.42821143659, 80062.0, 80084.59957700175, 80383.19616803757, 7701.730439434593, 9600.471974041495, 11102.0, 11520.659008887329, 11611.361375124448, 7715.845683086419, 12945.093843431247, 13223.206607603575, 13293.069485135948, 13420.597382078553, 8897.91102088971, 11158.776824463628, 11271.774564508827, 11312.321260724382, 11657.125715571197, 10502.317349372266, 10626.84059739089, 10644.988726071011, 10940.1794865387, 10953.671389858317, 9752.414855108716, 11214.439278036229, 11305.491252893804, 11537.250078744855, 11716.355600442057, 10600.25798194119, 10794.625182459846, 11219.0, 11327.0, 11572.826407603725, 11185.832212516656, 11232.494537745984, 11607.30356178632, 11663.300201987908, 11668.824967625322], "value": 11680.710052611084, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1611.8710503159748, 1622.716024695678, 1659.8506134447898, 1676.5138109948111, 1679.689257487365, 10800.67597972061, 11432.771344573124, 11447.885521144786, 11448.08415326775, 11478.655640330804, 11139.387333696643, 11188.776224475527, 11447.542098316078, 11696.532138714463, 11706.824397634042, 2377.3581133094053, 2422.0311875249863, 2427.077710470028, 2427.2718184544656, 2434.902603895842, 2318.1770471482632, 2392.3660230039054, 2394.73657897632, 2399.592069348206, 2400.375902265415, 2322.7909488146065, 2379.3694670912223, 2381.4879800842814, 2401.6997875265592, 2405.7233418156875, 2291.5073259249343, 2352.2472808701223, 2355.2463211772238, 2366.4912043910554, 2379.48840999186, 2341.484873327865, 2366.2309749331534, 2368.6328619064075, 2370.158593699229, 2372.6678265042906, 11321.490532926038, 11604.12969027318, 11614.535418583246, 11616.651500455, 11625.069994400428, 5588.776448942038, 5777.45114214149, 5779.797707080274, 5789.131630255453, 5794.130880367937, 3402.8298585070893, 3510.8771193008124, 3511.6312787157235, 3569.821508924543, 3580.910477238064, 1164.48180559651, 1169.7309618787726, 1206.619914726867, 1214.7752665756864, 1241.4351470081092, 11914.46384912681, 13799.724005519833, 14126.929365353108, 16091.436799711953, 16272.75590866138, 3646.726495512847, 3753.6246375362794, 4567.771611419415, 4583.954160458419, 4586.862394128181, 12305.200161989442, 12309.446074926649, 12318.199317044364, 12341.50633974631, 12344.506219751289, 4890.535399137077, 5044.54599086084, 5621.49406553404, 5749.597528172988, 5806.68063256525, 5076.441591425014, 5250.238715386324, 5274.0, 5378.058839703036, 5379.058664733656, 3348.665133486632, 3975.8807235782974, 4003.5195776507007, 4039.5758445363404, 4095.1604920991126, 930.5672862119012, 1036.3781730961514, 1047.3349423116224, 1062.3731998121145, 1064.8030114428855, 725.9256300675031, 841.9957900210583, 856.7729551668813, 865.6667183134539, 867.9826403471895, 6656.500762442836, 6669.199696036507, 6695.330466953265, 6725.596464212163, 6935.4104901082965, 895.4806212396794, 1008.5007921079113, 1022.0, 1036.0002597493303, 1043.014351437884, 3594.903554415876, 4135.834566617302, 4260.850870219528, 4266.210751011073, 4350.564943505624, 13372.0, 13407.530736424178, 13472.663183420493, 13814.861851381558, 13990.580282591538, 13411.597652070453, 13646.863531364757, 13783.931080344533, 13833.308334583231, 13846.0, 10160.628315177357, 10415.114715249138, 10430.904755000767, 10433.165346772392, 10450.947745261225, 6442.710078046593, 6491.902621460777, 6528.477721782341, 6530.346965303432, 6560.737570497317, 12425.62723118308, 12536.623901282612, 12587.874121259036, 12715.618531444072, 12815.487380504675, 12698.111132220656, 12728.0, 12733.745325093261, 12855.614331570068, 12942.676433089247, 9305.069493050642, 9521.095495927875, 9521.381110227812, 9539.284553658365, 9557.900841403327, 10302.793944121226, 10313.9484302578, 10439.895601044196, 10495.21285903545, 10546.998035186645, 8800.0, 8927.642894284156, 8935.865962010444, 9076.047015063587, 9162.679306224516, 8626.525541095049, 8712.21590056898, 8770.0, 8821.382503224713, 8823.911760882309, 7816.257455541714, 7999.560024198725, 8034.357251419756, 8034.718784842503, 8045.316148127475, 5701.572382071278, 5711.828645140652, 5790.843546505203, 5875.882482350415, 5956.583039187389, 5696.772129114789, 5777.277840269966, 6000.969995149996, 6019.638821670714, 6114.174586430953], "value": 5140.285500326657, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.59704116595253, 392.72116797074074, 395.1031159268459, 397.6043836382798, 400.9799510024498, 4258.318669012959, 4527.615152712019, 4669.295707066917, 4924.827631032915, 5222.68663880167, 4129.4012368206595, 4190.916181676373, 4543.727376357421, 4996.875078123048, 5015.147424937761, 3936.8976686527676, 4250.128723611652, 4374.781260936957, 4391.736495810245, 4404.29531274996, 3750.768292865754, 4179.0, 4456.384954956746, 4476.709013914102, 4498.527654596253, 3737.0, 3741.9709579865485, 4322.632576231008, 4380.430544029272, 4383.890402739925, 4340.804663790122, 4362.585554372329, 4392.670549708785, 4436.755978421185, 4492.640588752892, 4092.611201935841, 4129.958700412987, 4205.453291072172, 4235.91528169438, 4293.0, 4203.285441474956, 4321.925475771616, 4495.527969563214, 4515.435570553681, 5154.299081303161, 4330.610245077926, 4765.499622539618, 4890.413150421937, 4890.413150421937, 4897.853064408074, 4730.692504987165, 5004.499550045003, 5229.450907654679, 5422.2951016368015, 5460.34475862895, 3738.065483629093, 4031.6169963853386, 4093.283675356801, 4320.805563749639, 4417.977910110461, 4659.6505262105475, 4796.3524924135145, 5644.0, 5688.468631458961, 5692.580653222447, 3969.4442778011103, 4026.174634199982, 4458.264386376277, 4465.687401881902, 4499.460064792246, 4292.4938504733145, 4415.955840441618, 4479.126570318756, 4557.339185818038, 4599.4710608280475, 4327.978360108179, 4694.286585396306, 4846.80612775485, 4897.229133567204, 4921.827736029221, 5095.949040509621, 5438.477226376621, 5444.047291723932, 5482.23248745176, 5490.972545137329, 1474.837767845536, 1480.1341215388902, 1483.323844056231, 1487.68014876802, 1547.7136729705041, 1122.680036189682, 1165.7843298989715, 1165.7959857024987, 1181.373871847922, 1181.8936295733424, 4352.890013046653, 4654.837080702227, 4993.17612593918, 5032.672876263031, 5035.269885866529, 5182.844514664566, 5237.521918759482, 5301.496357845998, 5411.485908838654, 5516.917246241229, 4665.932470405768, 5016.247562865599, 5030.245463180552, 5047.545720885138, 5081.923771143436, 2455.502143692346, 2523.6719226500804, 2878.7984841061343, 2901.5202246853833, 2940.7353338199664, 5766.538676905921, 5793.681347525842, 5802.24570805801, 5814.156947242626, 5821.7380217890295, 5170.715610641376, 5388.946110538923, 5683.573731970118, 5689.374168841424, 5748.19525266463, 4819.59033482158, 5573.965502591606, 5696.857578560486, 5772.0, 5850.3564607893095, 4863.95136048635, 4894.95105048945, 5562.164485719783, 5562.189899918569, 5651.802186923438, 4944.431390390007, 5010.09818232707, 5102.540771330598, 5215.582753379645, 5400.9459905400445, 5345.670007294104, 5752.71236438168, 5764.59647824665, 5769.394213607511, 5851.180834683233, 3793.905152371213, 3964.980175099106, 4464.15181115587, 4510.751908644925, 4520.011195074157, 4578.4963653997365, 5007.118059891722, 5188.481151884932, 5296.894062118813, 5596.832095037155, 4663.650226233114, 5328.987492376436, 5412.78348866041, 5458.6724796512335, 5461.590380721383, 4999.70013793659, 5003.098203042154, 5438.564914806727, 5531.03206938776, 5535.197396377584, 4332.9133417332105, 4796.56830885222, 5129.659482158127, 5254.842354729364, 5372.677639341652, 4854.490278520704, 5175.456577059505, 5415.620906536505, 5488.753006114654, 5508.053191542505, 4626.745528995937, 4733.621310295237, 5098.954714283489, 5346.973265133805, 5381.515663590296], "value": 4157.7165079370925, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [767.3822572828874, 788.8027993001749, 805.5086397297647, 887.9911200887989, 909.3407279722201, 16290.10404427756, 16574.502764917055, 16589.585260368483, 17297.74053389197, 17492.650146997083, 15874.84125158748, 16073.758893616605, 16086.276117574707, 16102.7584586231, 16103.0, 988.8220120378329, 1014.8274793285159, 1016.5222345497641, 1054.0144964458218, 1056.6354607660344, 1039.173856783855, 1055.3826011783117, 1060.1095080132725, 1062.5112448273765, 1063.8882917293688, 1086.6794295682791, 1087.141158484796, 1087.2389327470762, 1087.722630729167, 1088.771358014818, 1077.2082519348273, 1077.7090185649909, 1078.6925726168004, 1079.3308148947624, 1080.71361089311, 805.8589746794346, 806.1334065879204, 806.2018601584448, 806.6128258435925, 808.6118663041714, 17569.23813131821, 17723.0, 17938.103094845206, 18275.543111422186, 18534.258629654796, 15625.984311019747, 15965.680686386324, 15986.600334991603, 16091.356345746157, 16262.031317339444, 11684.824727629093, 12132.939335303356, 12307.4461649226, 12347.012239020856, 12731.172473789267, 4027.51669799623, 4062.837486500536, 4068.2677118118727, 4157.771322577257, 4194.8112334945, 115342.0, 123351.38324308321, 123978.0, 125567.0, 127445.0, 11826.763464730657, 11841.88158118425, 12044.0, 12514.3742812859, 12579.622611321674, 26889.596656050177, 27365.589516157273, 27564.448711026067, 27584.448311033666, 27590.0, 8345.082040975487, 8404.74785756416, 8443.324534037385, 8535.445196062235, 8615.181557752003, 4474.149911516802, 4614.353990441341, 4658.277966915133, 4700.929486057712, 4780.020095880336, 3369.4440417331357, 3459.3254315408253, 3464.4803279507773, 3467.289205712823, 3472.930541389158, 605.3855336833118, 607.313735478906, 612.1796792298342, 614.744880874441, 615.1203778596649, 609.9566452384477, 631.9665605095523, 639.8080575827233, 671.5735507952485, 716.2694052066863, 8153.633086511121, 10364.844527332096, 10370.481475926174, 10451.216158788122, 10684.839727404094, 667.3493343989571, 713.4363852556422, 721.2030706069822, 725.4595326481805, 726.2338233164063, 4737.478877323491, 4996.500349964975, 4997.372934283723, 5018.197088465901, 5024.974875125601, 110883.44558277319, 111974.0, 112028.0, 112975.0, 113327.0, 54814.177787333225, 66357.0, 87525.99999999872, 94444.0, 100679.49660251799, 70714.0, 71412.64293678498, 71658.92511612434, 72338.63830680813, 73239.26760732324, 11226.382548959558, 11274.379909105079, 11308.547658093585, 11408.54365825391, 11521.654350369503, 39562.0, 51385.74307128441, 52810.73594632002, 53228.14698827735, 53775.6556086101, 65745.34254657393, 65933.0, 66359.0, 68744.62510749657, 68762.0, 12807.423665934868, 13584.660383490493, 14376.35306411193, 14470.493532726305, 14506.709865802835, 15157.621059473602, 15586.9220653896, 15634.843651563338, 15902.60243493922, 16480.0, 13890.166590004632, 14762.63093422673, 14800.185989770665, 14989.250537473301, 15081.0, 12334.50661973511, 14128.78806817878, 14675.41298348054, 14707.0, 14755.704885902436, 14212.21832799206, 14401.279936003368, 14560.344784484936, 14651.413943442569, 15216.315265813286, 9757.51212439361, 10097.141742951637, 10212.489375531046, 10548.789024219626, 10716.089132423831, 10820.513076911715, 10866.67399978002, 11360.977512023957, 11431.59989400367, 11495.770084598094], "value": 8097.5964643213965, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1403.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 115.0, 115.0, 115.0, 124.0, 171.0, 171.0, 115.0, 115.0, 113.0], "value": 125.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 105.0, 107.0, 139.0, 108.0, 107.0, 108.0, 105.0, 124.0, 109.0], "value": 114.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 185.0, 182.0, 183.0, 184.0, 184.0, 181.0, 183.0, 181.0, 184.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 61.0, 93.0, 63.0, 58.0, 62.0, 75.0, 89.0, 61.0], "value": 70.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 85.0, 68.0, 69.0, 68.0, 79.0, 67.0, 66.0, 81.0], "value": 74.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 87.0, 86.0, 89.0, 87.0, 88.0, 88.0, 87.0, 87.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 93.0, 94.0, 95.0, 93.0, 103.0, 94.0, 93.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 84.0, 83.0, 86.0, 83.0, 84.0, 83.0, 84.0], "value": 83.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 59.0, 60.0, 60.0, 62.0, 63.0, 65.0, 62.0, 64.0, 67.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 64.0, 68.0, 61.0, 59.0, 56.0, 58.0, 57.0, 57.0, 57.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 83.0, 81.0, 85.0, 83.0, 83.0, 79.0, 80.0, 82.0, 89.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 137.0, 146.0, 138.0, 125.0, 134.0, 138.0, 137.0, 177.0, 137.0], "value": 140.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 170.0, 161.0, 160.0, 161.0, 163.0, 166.0, 160.0, 160.0, 156.0], "value": 162.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 64.0, 63.0, 62.0, 63.0, 63.0, 62.0, 62.0], "value": 63.4, "unit": "score"}]}]} 12:39:47 INFO - Return code: 0 12:39:47 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:39:47 INFO - # TBPL SUCCESS # 12:39:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:39:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:39:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:39:47 INFO - 2017-06-08 12:39:47.790 screenresolution[1661:9983] starting screenresolution argv=screenresolution get 12:39:47 INFO - 2017-06-08 12:39:47.793 screenresolution[1661:9983] Display 0: 1600x1200x32@60 12:39:47 INFO - 2017-06-08 12:39:47.799 screenresolution[1662:9986] starting screenresolution argv=screenresolution list 12:39:47 INFO - Available Modes on Display 0 12:39:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:39:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:39:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:39:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:39:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:39:48 INFO - Intel Iris: 12:39:48 INFO - Chipset Model: Intel Iris 12:39:48 INFO - Type: GPU 12:39:48 INFO - Bus: Built-In 12:39:48 INFO - VRAM (Dynamic, Max): 1536 MB 12:39:48 INFO - Vendor: Intel (0x8086) 12:39:48 INFO - Device ID: 0x0a2e 12:39:48 INFO - Revision ID: 0x0009 12:39:48 INFO - Displays: 12:39:48 INFO - Display: 12:39:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:39:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:39:48 INFO - Display Serial Number: 1600x1200 60 12:39:48 INFO - Main Display: Yes 12:39:48 INFO - Mirror: Off 12:39:48 INFO - Online: Yes 12:39:48 INFO - Rotation: Supported 12:39:48 INFO - Return code: 0 12:39:48 INFO - Running post-action listener: _resource_record_post_action 12:39:48 INFO - [mozharness: 2017-06-08 19:39:48.036719Z] Finished run-tests step (success) 12:39:48 INFO - Running post-run listener: _resource_record_post_run 12:39:48 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 45102080; Write bytes: 423219712; Read time: 475; Write time: 1537 12:39:48 INFO - TinderboxPrint: CPU usage
53.7% 12:39:48 INFO - TinderboxPrint: I/O read bytes / time
45,102,080 / 475 12:39:48 INFO - TinderboxPrint: I/O write bytes / time
423,219,712 / 1,537 12:39:48 INFO - TinderboxPrint: CPU idle
2,210.3 (46.3%) 12:39:48 INFO - TinderboxPrint: CPU system
953.4 (20.0%) 12:39:48 INFO - TinderboxPrint: CPU user
1,610.0 (33.7%) 12:39:48 INFO - TinderboxPrint: Swap in / out
162,172,928 / 0 12:39:48 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121934336; Write bytes: 114253824; Read time: 11091; Write time: 227 12:39:48 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 39863296; Write bytes: 298816000; Read time: 442; Write time: 1275 12:39:48 INFO - Running post-run listener: _upload_blobber_files 12:39:48 INFO - Blob upload gear active. 12:39:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:39:48 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:39:48 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:39:48 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:39:48 INFO - (blobuploader) - INFO - Open directory for files ... 12:39:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:39:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:39:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:39:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:39:49 INFO - (blobuploader) - INFO - Done attempting. 12:39:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:39:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:39:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:39:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:39:50 INFO - (blobuploader) - INFO - Done attempting. 12:39:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:39:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:39:51 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:39:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:39:51 INFO - (blobuploader) - INFO - Done attempting. 12:39:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:39:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:39:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:39:53 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:39:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:39:53 INFO - (blobuploader) - INFO - Done attempting. 12:39:53 INFO - (blobuploader) - INFO - Iteration through files over. 12:39:53 INFO - Return code: 0 12:39:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:39:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:39:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/213bf3acc9fc87d146d88bd223d0a36c76d0ea875159f40ee0172f963144eb4ac9b3120a00fffe6d5b93ff34ca905b2584442094b874fd708183abc6bfb4ff56", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e9b5fbab0a0ce88434c6ca97b58c3eef7d3fb74fb50cde0a3d0071d4ecf9b953d124caae6d4008c880fc4d47f02fde7520e09012bbbdba6e1ffc82cdd4a086a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/722db6ac24143a402cf950d17b7217cea21ea086ae68e2260fc2c3f850acfdfe4941ff1871fd8a5a5890958280be152e0c3d9a7a8e8f4861b0839772702fd407", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/563c87fafcb1d4cb4fa1f58fcbebd1cd06a9ea9ba466553789f1afe7a8f5804b3b71bbc544b18bce4a4c870afdb266681974d424979aaab510b4197a7a7b4716"} 12:39:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:39:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:39:53 INFO - Contents: 12:39:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/213bf3acc9fc87d146d88bd223d0a36c76d0ea875159f40ee0172f963144eb4ac9b3120a00fffe6d5b93ff34ca905b2584442094b874fd708183abc6bfb4ff56", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e9b5fbab0a0ce88434c6ca97b58c3eef7d3fb74fb50cde0a3d0071d4ecf9b953d124caae6d4008c880fc4d47f02fde7520e09012bbbdba6e1ffc82cdd4a086a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/722db6ac24143a402cf950d17b7217cea21ea086ae68e2260fc2c3f850acfdfe4941ff1871fd8a5a5890958280be152e0c3d9a7a8e8f4861b0839772702fd407", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/563c87fafcb1d4cb4fa1f58fcbebd1cd06a9ea9ba466553789f1afe7a8f5804b3b71bbc544b18bce4a4c870afdb266681974d424979aaab510b4197a7a7b4716"} 12:39:53 INFO - Running post-run listener: copy_logs_to_upload_dir 12:39:53 INFO - Copying logs to upload dir... 12:39:53 INFO - mkdir: /builds/slave/test/build/upload/logs 12:39:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.169407 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-06-08 12:39:53.774708) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 12:39:53.777719) ========= 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.I2LikoKNKN/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.BvN92RCM49/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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/213bf3acc9fc87d146d88bd223d0a36c76d0ea875159f40ee0172f963144eb4ac9b3120a00fffe6d5b93ff34ca905b2584442094b874fd708183abc6bfb4ff56", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e9b5fbab0a0ce88434c6ca97b58c3eef7d3fb74fb50cde0a3d0071d4ecf9b953d124caae6d4008c880fc4d47f02fde7520e09012bbbdba6e1ffc82cdd4a086a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/722db6ac24143a402cf950d17b7217cea21ea086ae68e2260fc2c3f850acfdfe4941ff1871fd8a5a5890958280be152e0c3d9a7a8e8f4861b0839772702fd407", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/563c87fafcb1d4cb4fa1f58fcbebd1cd06a9ea9ba466553789f1afe7a8f5804b3b71bbc544b18bce4a4c870afdb266681974d424979aaab510b4197a7a7b4716"} build_url:https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011092 build_url: 'https://queue.taskcluster.net/v1/task/C5iGyK-OQJO8dcNgBoTD6w/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/213bf3acc9fc87d146d88bd223d0a36c76d0ea875159f40ee0172f963144eb4ac9b3120a00fffe6d5b93ff34ca905b2584442094b874fd708183abc6bfb4ff56", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2e9b5fbab0a0ce88434c6ca97b58c3eef7d3fb74fb50cde0a3d0071d4ecf9b953d124caae6d4008c880fc4d47f02fde7520e09012bbbdba6e1ffc82cdd4a086a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/722db6ac24143a402cf950d17b7217cea21ea086ae68e2260fc2c3f850acfdfe4941ff1871fd8a5a5890958280be152e0c3d9a7a8e8f4861b0839772702fd407", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/563c87fafcb1d4cb4fa1f58fcbebd1cd06a9ea9ba466553789f1afe7a8f5804b3b71bbc544b18bce4a4c870afdb266681974d424979aaab510b4197a7a7b4716"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 12:39:53.811229) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:39:53.811595) ========= 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.I2LikoKNKN/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.BvN92RCM49/Listeners TMPDIR=/var/folders/z3/t5k6xjnj1b7c750r159xpw7800000w/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.005308 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:39:53.860276) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 12:39:53.860620) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 12:39:53.860992) ========= ========= Total master_lag: 0.17 =========