builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs slave: t-yosemite-r7-0095 starttime: 1496948987.61 results: success (0) buildid: 20170608104024 builduid: 6c076a982fa74dafa72304d8c38ff2af revision: d8424bcbf72f7a451e89f8f625a9ebafecded872 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 12:09:47.613178) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-08 12:09:47.613619) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 12:09:47.613900) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-08 12:09:47.639070) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:09:47.639617) ========= 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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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:09:47-- 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% 9.42M=0.001s 2017-06-08 12:09:47 (9.42 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.111218 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:09:47.774756) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:09:47.775102) ========= 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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.028695 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:09:47.825781) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2017-06-08 12:09:47.826143) ========= 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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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:09:47,920 truncating revision to first 12 chars 2017-06-08 12:09:47,920 Setting DEBUG logging. 2017-06-08 12:09:47,920 attempt 1/10 2017-06-08 12:09:47,921 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:09:49,944 unpacking tar archive at: mozilla-beta-d8424bcbf72f/testing/mozharness/ program finished with exit code 0 elapsedTime=2.292094 ========= master_lag: 2.82 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2017-06-08 12:09:52.937887) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 12:09:52.938268) ========= script_repo_revision: d8424bcbf72f7a451e89f8f625a9ebafecded872 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-08 12:09:52.938735) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 12:09:52.939057) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-08 12:09:52.962045) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-06-08 12:09:52.962618) ========= /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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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:09:53 INFO - MultiFileLogger online at 20170608 12:09:53 in /builds/slave/test 12:09:53 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:09:53 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:09:53 INFO - {'append_to_log': False, 12:09:53 INFO - 'base_work_dir': '/builds/slave/test', 12:09:53 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:09:53 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:09:53 INFO - 'branch': 'Mozilla-Beta', 12:09:53 INFO - 'buildbot_json_path': 'buildprops.json', 12:09:53 INFO - 'config_files': ('talos/mac_config.py',), 12:09:53 INFO - 'default_actions': ('clobber', 12:09:53 INFO - 'read-buildbot-config', 12:09:53 INFO - 'download-and-extract', 12:09:53 INFO - 'populate-webroot', 12:09:53 INFO - 'create-virtualenv', 12:09:53 INFO - 'install', 12:09:53 INFO - 'run-tests'), 12:09:53 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:09:53 INFO - 'download_minidump_stackwalk': True, 12:09:53 INFO - 'download_symbols': 'ondemand', 12:09:53 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:09:53 INFO - 'tooltool.py': '/tools/tooltool.py', 12:09:53 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:09:53 INFO - '/tools/misc-python/virtualenv.py')}, 12:09:53 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:09:53 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:09:53 INFO - 'gecko_profile': False, 12:09:53 INFO - 'gecko_profile_interval': 0, 12:09:53 INFO - 'installer_path': 'installer.exe', 12:09:53 INFO - 'log_level': 'info', 12:09:53 INFO - 'log_name': 'talos', 12:09:53 INFO - 'log_to_console': True, 12:09:53 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:09:53 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:09:53 INFO - 'opt_config_files': (), 12:09:53 INFO - 'pip_index': False, 12:09:53 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:09:53 INFO - 'cmd': ('bash', 12:09:53 INFO - '-c', 12:09:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:09:53 INFO - 'enabled': True, 12:09:53 INFO - 'halt_on_failure': False, 12:09:53 INFO - 'name': 'check_screen_resolution'},), 12:09:53 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:09:53 INFO - 'cmd': ('bash', 12:09:53 INFO - '-c', 12:09:53 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:09:53 INFO - 'enabled': True, 12:09:53 INFO - 'halt_on_failure': False, 12:09:53 INFO - 'name': 'check_screen_resolution'},), 12:09:53 INFO - 'run_cmd_checks_enabled': True, 12:09:53 INFO - 'suite': 'dromaeojs', 12:09:53 INFO - 'system_bits': '32', 12:09:53 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:09:53 INFO - 'title': 't-yosemite-r7-0095', 12:09:53 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:09:53 INFO - 'use_talos_json': True, 12:09:53 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:09:53 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:09:53 INFO - 'work_dir': 'build'} 12:09:53 INFO - [mozharness: 2017-06-08 19:09:53.164153Z] Running clobber step. 12:09:53 INFO - Running pre-action listener: _resource_record_pre_action 12:09:53 INFO - Running main action method: clobber 12:09:53 INFO - rmtree: /builds/slave/test/build 12:09:53 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:09:55 INFO - Running post-action listener: _resource_record_post_action 12:09:55 INFO - [mozharness: 2017-06-08 19:09:55.776917Z] Finished clobber step (success) 12:09:55 INFO - [mozharness: 2017-06-08 19:09:55.777060Z] Running read-buildbot-config step. 12:09:55 INFO - Running pre-action listener: _resource_record_pre_action 12:09:55 INFO - Running main action method: read_buildbot_config 12:09:55 INFO - Using buildbot properties: 12:09:55 INFO - { 12:09:55 INFO - "project": "", 12:09:55 INFO - "product": "firefox", 12:09:55 INFO - "script_repo_revision": "production", 12:09:55 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs", 12:09:55 INFO - "repository": "", 12:09:55 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs", 12:09:55 INFO - "buildid": "20170608104024", 12:09:55 INFO - "pgo_build": "False", 12:09:55 INFO - "basedir": "/builds/slave/test", 12:09:55 INFO - "buildnumber": 11, 12:09:55 INFO - "slavename": "t-yosemite-r7-0095", 12:09:55 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:09:55 INFO - "platform": "yosemite_r7_devedition", 12:09:55 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 12:09:55 INFO - "branch": "mozilla-beta", 12:09:55 INFO - "repo_path": "releases/mozilla-beta", 12:09:55 INFO - "revision": "d8424bcbf72f7a451e89f8f625a9ebafecded872", 12:09:55 INFO - "stage_platform": "macosx64-devedition", 12:09:55 INFO - "builduid": "6c076a982fa74dafa72304d8c38ff2af", 12:09:55 INFO - "slavebuilddir": "test" 12:09:55 INFO - } 12:09:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 12:09:55 INFO - Running post-action listener: _resource_record_post_action 12:09:55 INFO - [mozharness: 2017-06-08 19:09:55.780561Z] Finished read-buildbot-config step (success) 12:09:55 INFO - [mozharness: 2017-06-08 19:09:55.780666Z] Running download-and-extract step. 12:09:55 INFO - Running pre-action listener: _resource_record_pre_action 12:09:55 INFO - Running main action method: download_and_extract 12:09:55 INFO - mkdir: /builds/slave/test/build/tests 12:09:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:55 INFO - trying https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 12:09:55 INFO - Downloading https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/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:09:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/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:09:57 INFO - Downloaded 1287 bytes. 12:09:57 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:09:57 INFO - Using the following test package requirements: 12:09:57 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:09:57 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 12:09:57 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:09:57 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:09:57 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 12:09:57 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:09:57 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 12:09:57 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:09:57 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:09:57 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 12:09:57 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:09:57 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:09:57 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 12:09:57 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:09:57 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 12:09:57 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:09:57 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 12:09:57 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:09:57 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 12:09:57 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 12:09:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:09:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:09:57 INFO - Fetch https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:09:58 INFO - Expected file size: 51964029 12:09:58 INFO - Obtained file size: 51964029 12:10:15 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:10:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:10:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:10:15 INFO - Fetch https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:10:17 INFO - Expected file size: 51964029 12:10:17 INFO - Obtained file size: 51964029 12:10:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 12:10:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 12:10:32 INFO - Fetch https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 12:10:33 INFO - Expected file size: 14404461 12:10:33 INFO - Obtained file size: 14404461 12:10:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:34 INFO - trying https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:10:34 INFO - Downloading https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 12:10:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/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:10:36 INFO - Downloaded 74890013 bytes. 12:10:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:10:36 INFO - mkdir: /builds/slave/test/properties 12:10:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:10:36 INFO - Writing to file /builds/slave/test/properties/build_url 12:10:36 INFO - Contents: 12:10:36 INFO - build_url:https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:10:36 INFO - Running post-action listener: _resource_record_post_action 12:10:36 INFO - Running post-action listener: set_extra_try_arguments 12:10:36 INFO - [mozharness: 2017-06-08 19:10:36.978037Z] Finished download-and-extract step (success) 12:10:36 INFO - [mozharness: 2017-06-08 19:10:36.978185Z] Running populate-webroot step. 12:10:36 INFO - Running pre-action listener: _resource_record_pre_action 12:10:36 INFO - Running main action method: populate_webroot 12:10:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:10:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:10:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:10:36 INFO - '--tppagecycles', 12:10:36 INFO - '7'], 12:10:36 INFO - 'tests': ['tsvgm']}}, 12:10:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:10:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:10:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:10:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:10:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:10:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:10:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:10:36 INFO - 'talos_options': ['--disable-e10s'], 12:10:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:10:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:10:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:10:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:10:36 INFO - 'talos_options': ['--disable-e10s'], 12:10:36 INFO - 'tests': ['damp', 'tps']}, 12:10:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:10:36 INFO - 'tests': ['damp', 'tps']}, 12:10:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:10:36 INFO - 'tests': ['dromaeo_dom']}, 12:10:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:10:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:10:36 INFO - 'tests': ['basic_compositor_video']}, 12:10:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:10:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:10:36 INFO - 'tests': ['a11yr', 12:10:36 INFO - 'ts_paint', 12:10:36 INFO - 'tpaint', 12:10:36 INFO - 'sessionrestore', 12:10:36 INFO - 'sessionrestore_no_auto_restore', 12:10:36 INFO - 'tabpaint']}, 12:10:36 INFO - 'other-e10s': {'tests': ['a11yr', 12:10:36 INFO - 'ts_paint', 12:10:36 INFO - 'tpaint', 12:10:36 INFO - 'sessionrestore', 12:10:36 INFO - 'sessionrestore_no_auto_restore', 12:10:36 INFO - 'tabpaint']}, 12:10:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:10:36 INFO - 'ts_paint', 12:10:36 INFO - 'tpaint', 12:10:36 INFO - 'sessionrestore', 12:10:36 INFO - 'sessionrestore_no_auto_restore', 12:10:36 INFO - 'tabpaint']}, 12:10:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:10:36 INFO - 'ts_paint', 12:10:36 INFO - 'tpaint', 12:10:36 INFO - 'sessionrestore', 12:10:36 INFO - 'sessionrestore_no_auto_restore', 12:10:36 INFO - 'tabpaint']}, 12:10:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:10:36 INFO - 'tests': ['a11yr', 12:10:36 INFO - 'ts_paint', 12:10:36 INFO - 'tpaint', 12:10:36 INFO - 'sessionrestore', 12:10:36 INFO - 'sessionrestore_no_auto_restore', 12:10:36 INFO - 'tabpaint']}, 12:10:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:10:36 INFO - 'tests': ['a11yr', 12:10:36 INFO - 'ts_paint', 12:10:36 INFO - 'tpaint', 12:10:36 INFO - 'sessionrestore', 12:10:36 INFO - 'sessionrestore_no_auto_restore', 12:10:36 INFO - 'tabpaint']}, 12:10:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:10:36 INFO - 'tests': ['tsvgx', 12:10:36 INFO - 'tsvgr_opacity', 12:10:36 INFO - 'tart', 12:10:36 INFO - 'tscrollx', 12:10:36 INFO - 'cart', 12:10:36 INFO - 'tsvg_static']}, 12:10:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:10:36 INFO - 'tsvgr_opacity', 12:10:36 INFO - 'tart', 12:10:36 INFO - 'tscrollx', 12:10:36 INFO - 'cart', 12:10:36 INFO - 'tsvg_static']}, 12:10:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:10:36 INFO - 'talos_options': ['--disable-e10s'], 12:10:36 INFO - 'tests': ['tp5o']}, 12:10:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:10:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:10:36 INFO - 'talos_options': ['--disable-e10s', 12:10:36 INFO - '--xperf_path', 12:10:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:10:36 INFO - 'tests': ['tp5n']}, 12:10:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:10:36 INFO - 'talos_options': ['--xperf_path', 12:10:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:10:36 INFO - 'tests': ['tp5n']}}, 12:10:36 INFO - 'talos.zip': {'path': '', 12:10:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:10:36 INFO - Running post-action listener: _resource_record_post_action 12:10:36 INFO - [mozharness: 2017-06-08 19:10:36.996598Z] Finished populate-webroot step (success) 12:10:36 INFO - [mozharness: 2017-06-08 19:10:36.996735Z] Running create-virtualenv step. 12:10:36 INFO - Running pre-action listener: _resource_record_pre_action 12:10:36 INFO - Running main action method: create_virtualenv 12:10:36 INFO - Creating virtualenv /builds/slave/test/build/venv 12:10:36 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:10:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:10:36 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:10:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:10:37 INFO - Using real prefix '/tools/python27' 12:10:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:10:37 INFO - Installing distribute.............................................................................................................................................................................................done. 12:10:40 INFO - Installing pip.................done. 12:10:40 INFO - Return code: 0 12:10:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:10:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:10:40 INFO - Reading from file tmpfile_stdout 12:10:40 INFO - Output received: 12:10:40 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:10:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:10:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:10:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:40 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 0x10f217b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f21a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9729552060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eff5d30>, '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 0x10ef1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efeabe8>, '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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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:10:40 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:10:40 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:10:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cp5UUhvQ8o/Render', 12:10:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:40 INFO - 'HOME': '/Users/cltbld', 12:10:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:40 INFO - 'LOGNAME': 'cltbld', 12:10:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:40 INFO - 'MOZ_NO_REMOTE': '1', 12:10:40 INFO - 'NO_EM_RESTART': '1', 12:10:40 INFO - 'PAGER': '/bin/cat', 12:10:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:40 INFO - 'PWD': '/builds/slave/test', 12:10:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:40 INFO - 'SHELL': '/bin/bash', 12:10:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KK6KxwstTr/Listeners', 12:10:40 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:10:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:40 INFO - 'USER': 'cltbld', 12:10:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:40 INFO - 'XPC_FLAGS': '0x0', 12:10:40 INFO - 'XPC_SERVICE_NAME': '0', 12:10:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:41 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:10:41 INFO - Cleaning up... 12:10:41 INFO - Return code: 0 12:10:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:10: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:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10: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:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10: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', '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 0x10f217b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f21a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9729552060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eff5d30>, '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 0x10ef1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efeabe8>, '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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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:10: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', 'psutil>=3.1.1'] in /builds/slave/test/build 12:10: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 psutil>=3.1.1 12:10:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cp5UUhvQ8o/Render', 12:10:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:41 INFO - 'HOME': '/Users/cltbld', 12:10:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:41 INFO - 'LOGNAME': 'cltbld', 12:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:41 INFO - 'MOZ_NO_REMOTE': '1', 12:10:41 INFO - 'NO_EM_RESTART': '1', 12:10:41 INFO - 'PAGER': '/bin/cat', 12:10:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:41 INFO - 'PWD': '/builds/slave/test', 12:10:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:41 INFO - 'SHELL': '/bin/bash', 12:10:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KK6KxwstTr/Listeners', 12:10:41 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:10:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:41 INFO - 'USER': 'cltbld', 12:10:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:41 INFO - 'XPC_FLAGS': '0x0', 12:10:41 INFO - 'XPC_SERVICE_NAME': '0', 12:10:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:41 INFO - Downloading/unpacking psutil>=3.1.1 12:10: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:10: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:10: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:10: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:10:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:10:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:10:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:10:43 INFO - Installing collected packages: psutil 12:10:43 INFO - Running setup.py install for psutil 12:10:43 INFO - building 'psutil._psutil_osx' extension 12:10:43 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:10:44 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:10:44 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:10:44 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:10:44 INFO - building 'psutil._psutil_posix' extension 12:10:44 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:10:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:10:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:10:44 INFO - ^ 12:10:44 INFO - 1 warning generated. 12:10:44 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:10:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:10:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:10:44 INFO - Successfully installed psutil 12:10:44 INFO - Cleaning up... 12:10:44 INFO - Return code: 0 12:10:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:10: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:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:45 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:10:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:45 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 0x10f217b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f21a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9729552060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eff5d30>, '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 0x10ef1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efeabe8>, '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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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:10:45 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:10:45 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:10:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cp5UUhvQ8o/Render', 12:10:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:45 INFO - 'HOME': '/Users/cltbld', 12:10:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:45 INFO - 'LOGNAME': 'cltbld', 12:10:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:45 INFO - 'MOZ_NO_REMOTE': '1', 12:10:45 INFO - 'NO_EM_RESTART': '1', 12:10:45 INFO - 'PAGER': '/bin/cat', 12:10:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:45 INFO - 'PWD': '/builds/slave/test', 12:10:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:45 INFO - 'SHELL': '/bin/bash', 12:10:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KK6KxwstTr/Listeners', 12:10:45 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:10:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:45 INFO - 'USER': 'cltbld', 12:10:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:45 INFO - 'XPC_FLAGS': '0x0', 12:10:45 INFO - 'XPC_SERVICE_NAME': '0', 12:10:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:10:45 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:10:45 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:10:45 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:10:45 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:10:47 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:10:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:10:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:10:47 INFO - Installing collected packages: mozsystemmonitor 12:10:47 INFO - Running setup.py install for mozsystemmonitor 12:10:47 INFO - Successfully installed mozsystemmonitor 12:10:47 INFO - Cleaning up... 12:10:47 INFO - Return code: 0 12:10:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:10:47 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:10:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:47 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:10:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:47 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 0x10f217b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f21a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9729552060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eff5d30>, '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 0x10ef1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efeabe8>, '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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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:10:47 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:10:47 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:10:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cp5UUhvQ8o/Render', 12:10:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:47 INFO - 'HOME': '/Users/cltbld', 12:10:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:47 INFO - 'LOGNAME': 'cltbld', 12:10:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:47 INFO - 'MOZ_NO_REMOTE': '1', 12:10:47 INFO - 'NO_EM_RESTART': '1', 12:10:47 INFO - 'PAGER': '/bin/cat', 12:10:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:47 INFO - 'PWD': '/builds/slave/test', 12:10:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:47 INFO - 'SHELL': '/bin/bash', 12:10:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KK6KxwstTr/Listeners', 12:10:47 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:10:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:47 INFO - 'USER': 'cltbld', 12:10:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:47 INFO - 'XPC_FLAGS': '0x0', 12:10:47 INFO - 'XPC_SERVICE_NAME': '0', 12:10:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:48 INFO - Downloading/unpacking jsonschema==2.5.1 12:10:48 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:10:48 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:10:48 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:10:48 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:10:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:10:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:10:49 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:10:49 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:10:49 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:10:49 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:10:49 INFO - Downloading functools32-3.2.3-2.tar.gz 12:10:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:10:49 INFO - warning: no files found matching '*.txt' 12:10:49 INFO - no previously-included directories found matching 'build' 12:10:49 INFO - no previously-included directories found matching 'dist' 12:10:49 INFO - no previously-included directories found matching '.git*' 12:10:49 INFO - Installing collected packages: jsonschema, functools32 12:10:49 INFO - Running setup.py install for functools32 12:10:49 INFO - warning: no files found matching '*.txt' 12:10:49 INFO - no previously-included directories found matching 'build' 12:10:49 INFO - no previously-included directories found matching 'dist' 12:10:49 INFO - no previously-included directories found matching '.git*' 12:10:49 INFO - Successfully installed jsonschema functools32 12:10:49 INFO - Cleaning up... 12:10:50 INFO - Return code: 0 12:10:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:10:50 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:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:50 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:10:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:50 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 0x10f217b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f21a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9729552060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eff5d30>, '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 0x10ef1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efeabe8>, '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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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:10:50 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:10:50 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:10:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cp5UUhvQ8o/Render', 12:10:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:50 INFO - 'HOME': '/Users/cltbld', 12:10:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:50 INFO - 'LOGNAME': 'cltbld', 12:10:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:50 INFO - 'MOZ_NO_REMOTE': '1', 12:10:50 INFO - 'NO_EM_RESTART': '1', 12:10:50 INFO - 'PAGER': '/bin/cat', 12:10:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:50 INFO - 'PWD': '/builds/slave/test', 12:10:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:50 INFO - 'SHELL': '/bin/bash', 12:10:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KK6KxwstTr/Listeners', 12:10:50 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:10:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:50 INFO - 'USER': 'cltbld', 12:10:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:50 INFO - 'XPC_FLAGS': '0x0', 12:10:50 INFO - 'XPC_SERVICE_NAME': '0', 12:10:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:10:50 INFO - Cleaning up... 12:10:50 INFO - Return code: 0 12:10:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:10:50 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:10:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:50 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:10:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:50 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 0x10f217b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f21a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9729552060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eff5d30>, '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 0x10ef1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efeabe8>, '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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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:10:50 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:10:50 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:10:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cp5UUhvQ8o/Render', 12:10:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:50 INFO - 'HOME': '/Users/cltbld', 12:10:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:50 INFO - 'LOGNAME': 'cltbld', 12:10:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:50 INFO - 'MOZ_NO_REMOTE': '1', 12:10:50 INFO - 'NO_EM_RESTART': '1', 12:10:50 INFO - 'PAGER': '/bin/cat', 12:10:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:50 INFO - 'PWD': '/builds/slave/test', 12:10:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:50 INFO - 'SHELL': '/bin/bash', 12:10:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KK6KxwstTr/Listeners', 12:10:50 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:10:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:50 INFO - 'USER': 'cltbld', 12:10:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:50 INFO - 'XPC_FLAGS': '0x0', 12:10:50 INFO - 'XPC_SERVICE_NAME': '0', 12:10:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:50 INFO - Downloading/unpacking blobuploader==1.2.4 12:10:50 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:10:50 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:10:50 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:10:50 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:10:51 INFO - Downloading blobuploader-1.2.4.tar.gz 12:10:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:10:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:10: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:10: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:10: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:10: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:10:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:10:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:10:52 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:10:52 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:10:52 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:10:52 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:10:52 INFO - Downloading docopt-0.6.1.tar.gz 12:10:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:10:52 INFO - Installing collected packages: blobuploader, requests, docopt 12:10:52 INFO - Running setup.py install for blobuploader 12:10:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:10:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:10:53 INFO - Running setup.py install for requests 12:10:53 INFO - Running setup.py install for docopt 12:10:53 INFO - Successfully installed blobuploader requests docopt 12:10:53 INFO - Cleaning up... 12:10:53 INFO - Return code: 0 12:10:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:10: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:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10: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:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:53 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 0x10f217b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f21a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9729552060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eff5d30>, '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 0x10ef1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efeabe8>, '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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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:10:53 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:10:53 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:10:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cp5UUhvQ8o/Render', 12:10:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:53 INFO - 'HOME': '/Users/cltbld', 12:10:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:53 INFO - 'LOGNAME': 'cltbld', 12:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:53 INFO - 'MOZ_NO_REMOTE': '1', 12:10:53 INFO - 'NO_EM_RESTART': '1', 12:10:53 INFO - 'PAGER': '/bin/cat', 12:10:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:53 INFO - 'PWD': '/builds/slave/test', 12:10:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:53 INFO - 'SHELL': '/bin/bash', 12:10:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KK6KxwstTr/Listeners', 12:10:53 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:10:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:53 INFO - 'USER': 'cltbld', 12:10:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:53 INFO - 'XPC_FLAGS': '0x0', 12:10:53 INFO - 'XPC_SERVICE_NAME': '0', 12:10:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:10:53 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-uDNeUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:10:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-75rcyF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:10:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-FXuzQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:10:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-U4A9Rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:10:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-P5j5kW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:10:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-0IzBts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:10:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-5EDRaj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:10:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-5H0Rmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:10:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-3DojHR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:10:54 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-Tia8zq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:10:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-nPqTlk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:10:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-hazXf_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:10:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-23znEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:10:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-2GxWC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:10:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-MCI9vc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:10:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-4UWZH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:10:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:10:55 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-WnNrW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:10:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:10:55 INFO - Running setup.py install for manifestparser 12:10:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:10:55 INFO - Running setup.py install for mozcrash 12:10:56 INFO - Running setup.py install for mozdebug 12:10:56 INFO - Running setup.py install for mozdevice 12:10:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:10:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:10:56 INFO - Running setup.py install for mozfile 12:10:56 INFO - Running setup.py install for mozhttpd 12:10:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:10:56 INFO - Running setup.py install for mozinfo 12:10:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:10:56 INFO - Running setup.py install for mozInstall 12:10:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:10:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:10:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:10:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:10:56 INFO - Running setup.py install for mozleak 12:10:57 INFO - Running setup.py install for mozlog 12:10:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:10:57 INFO - Running setup.py install for moznetwork 12:10:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:10:57 INFO - Running setup.py install for mozprocess 12:10:57 INFO - Running setup.py install for mozprofile 12:10:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:10:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:10:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:10:57 INFO - Running setup.py install for mozrunner 12:10:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:10:57 INFO - Running setup.py install for mozscreenshot 12:10:58 INFO - Running setup.py install for moztest 12:10:58 INFO - Running setup.py install for mozversion 12:10:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:10:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:10:58 INFO - Cleaning up... 12:10:58 INFO - Return code: 0 12:10:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:10:58 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:10:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:58 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:10:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:58 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 0x10f217b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f21a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9729552060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eff5d30>, '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 0x10ef1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efeabe8>, '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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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:10:58 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:10:58 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:10:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cp5UUhvQ8o/Render', 12:10:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:58 INFO - 'HOME': '/Users/cltbld', 12:10:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:58 INFO - 'LOGNAME': 'cltbld', 12:10:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:58 INFO - 'MOZ_NO_REMOTE': '1', 12:10:58 INFO - 'NO_EM_RESTART': '1', 12:10:58 INFO - 'PAGER': '/bin/cat', 12:10:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:58 INFO - 'PWD': '/builds/slave/test', 12:10:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:58 INFO - 'SHELL': '/bin/bash', 12:10:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KK6KxwstTr/Listeners', 12:10:58 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:10:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:58 INFO - 'USER': 'cltbld', 12:10:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:58 INFO - 'XPC_FLAGS': '0x0', 12:10:58 INFO - 'XPC_SERVICE_NAME': '0', 12:10:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:10:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-qCeo5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:10:58 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:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:10:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-HuQJP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:10:58 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:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:10:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-f3Kk0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:10:58 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:10:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:10:58 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-BqozZV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:10:59 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:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:10:59 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-PLM2cI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:10:59 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:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:10:59 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-dgZS34-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:10:59 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:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:10:59 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-hKxh9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:10:59 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:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:10:59 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-QvMB3S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:10:59 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:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:10:59 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-NvJTa1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:10:59 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:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:10:59 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-DczSar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:10:59 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:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:10:59 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-j_cPc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:10:59 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:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:10:59 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-rVNFHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:10:59 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:10:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:10:59 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-chDjbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:11:00 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:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:11:00 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-omkrOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:11:00 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:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:00 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-obKCj7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:11:00 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:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:11:00 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-dDHBJj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:11:00 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:11:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:11:00 INFO - Running setup.py (path:/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/pip-mk9Heb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:11:00 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:11:00 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:11:00 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:11:00 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:11:00 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:11:00 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:11:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:11:00 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:11:00 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:11:00 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:11:00 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:11:01 INFO - Downloading blessings-1.6.tar.gz 12:11:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:11:01 INFO - Installing collected packages: blessings 12:11:01 INFO - Running setup.py install for blessings 12:11:02 INFO - Successfully installed blessings 12:11:02 INFO - Cleaning up... 12:11:02 INFO - Return code: 0 12:11:02 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:11:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:02 INFO - Reading from file tmpfile_stdout 12:11:02 INFO - Current package versions: 12:11:02 INFO - blessings == 1.6 12:11:02 INFO - blobuploader == 1.2.4 12:11:02 INFO - docopt == 0.6.1 12:11:02 INFO - functools32 == 3.2.3-2 12:11:02 INFO - jsonschema == 2.5.1 12:11:02 INFO - manifestparser == 1.1 12:11:02 INFO - mozInstall == 1.12 12:11:02 INFO - mozcrash == 1.0 12:11:02 INFO - mozdebug == 0.1 12:11:02 INFO - mozdevice == 0.49 12:11:02 INFO - mozfile == 1.2 12:11:02 INFO - mozhttpd == 0.7 12:11:02 INFO - mozinfo == 0.9 12:11:02 INFO - mozleak == 0.1 12:11:02 INFO - mozlog == 3.4 12:11:02 INFO - moznetwork == 0.27 12:11:02 INFO - mozprocess == 0.25 12:11:02 INFO - mozprofile == 0.28 12:11:02 INFO - mozrunner == 6.13 12:11:02 INFO - mozscreenshot == 0.1 12:11:02 INFO - mozsystemmonitor == 0.3 12:11:02 INFO - moztest == 0.8 12:11:02 INFO - mozversion == 1.4 12:11:02 INFO - psutil == 3.1.1 12:11:02 INFO - requests == 1.2.3 12:11:02 INFO - wsgiref == 0.1.2 12:11:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:02 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 0x10f217b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f21a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9729552060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eff5d30>, '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 0x10ef1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efeabe8>, '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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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:11:02 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:11:02 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:11:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cp5UUhvQ8o/Render', 12:11:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:02 INFO - 'HOME': '/Users/cltbld', 12:11:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:02 INFO - 'LOGNAME': 'cltbld', 12:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:02 INFO - 'MOZ_NO_REMOTE': '1', 12:11:02 INFO - 'NO_EM_RESTART': '1', 12:11:02 INFO - 'PAGER': '/bin/cat', 12:11:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:02 INFO - 'PWD': '/builds/slave/test', 12:11:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:02 INFO - 'SHELL': '/bin/bash', 12:11:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KK6KxwstTr/Listeners', 12:11:02 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:11:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:02 INFO - 'USER': 'cltbld', 12:11:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:02 INFO - 'XPC_FLAGS': '0x0', 12:11:02 INFO - 'XPC_SERVICE_NAME': '0', 12:11:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:02 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:11:02 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:11:02 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:11:02 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:11:02 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:11:02 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:11:02 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:11:02 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:11:02 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:11:02 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:11:02 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:11:02 INFO - Cleaning up... 12:11:02 INFO - Return code: 0 12:11:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:11:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:11:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f217b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f21a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9729552060>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eff5d30>, '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 0x10ef1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efeabe8>, '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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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:11:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:11:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cp5UUhvQ8o/Render', 12:11:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:02 INFO - 'HOME': '/Users/cltbld', 12:11:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:02 INFO - 'LOGNAME': 'cltbld', 12:11:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:02 INFO - 'MOZ_NO_REMOTE': '1', 12:11:02 INFO - 'NO_EM_RESTART': '1', 12:11:02 INFO - 'PAGER': '/bin/cat', 12:11:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:02 INFO - 'PWD': '/builds/slave/test', 12:11:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:02 INFO - 'SHELL': '/bin/bash', 12:11:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KK6KxwstTr/Listeners', 12:11:02 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:11:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:02 INFO - 'USER': 'cltbld', 12:11:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:02 INFO - 'XPC_FLAGS': '0x0', 12:11:02 INFO - 'XPC_SERVICE_NAME': '0', 12:11:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:11:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:11:02 INFO - Cleaning up... 12:11:02 INFO - Return code: 0 12:11:02 INFO - Running post-action listener: _resource_record_post_action 12:11:02 INFO - Running post-action listener: _start_resource_monitoring 12:11:02 INFO - Starting resource monitoring. 12:11:02 INFO - [mozharness: 2017-06-08 19:11:02.963349Z] Finished create-virtualenv step (success) 12:11:02 INFO - [mozharness: 2017-06-08 19:11:02.964324Z] Running install step. 12:11:02 INFO - Running pre-action listener: _resource_record_pre_action 12:11:02 INFO - Running main action method: install 12:11:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:11:03 INFO - Reading from file tmpfile_stdout 12:11:03 INFO - Detecting whether we're running mozinstall >=1.0... 12:11:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:11:03 INFO - Reading from file tmpfile_stdout 12:11:03 INFO - Output received: 12:11:03 INFO - Usage: mozinstall [options] installer 12:11:03 INFO - Options: 12:11:03 INFO - -h, --help show this help message and exit 12:11:03 INFO - -d DEST, --destination=DEST 12:11:03 INFO - Directory to install application into. [default: 12:11:03 INFO - "/builds/slave/test"] 12:11:03 INFO - --app=APP Application being installed. [default: firefox] 12:11:03 INFO - mkdir: /builds/slave/test/build/application 12:11:03 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:11:03 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:11:23 INFO - Reading from file tmpfile_stdout 12:11:23 INFO - Output received: 12:11:23 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:11:23 INFO - Running post-action listener: _resource_record_post_action 12:11:23 INFO - [mozharness: 2017-06-08 19:11:23.425211Z] Finished install step (success) 12:11:23 INFO - [mozharness: 2017-06-08 19:11:23.425391Z] Running run-tests step. 12:11:23 INFO - Running pre-action listener: _resource_record_pre_action 12:11:23 INFO - Running main action method: run_tests 12:11:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:11:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:11:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:11:23 INFO - 2017-06-08 12:11:23.440 screenresolution[1625:7326] starting screenresolution argv=screenresolution get 12:11:23 INFO - 2017-06-08 12:11:23.443 screenresolution[1625:7326] Display 0: 1600x1200x32@60 12:11:23 INFO - 2017-06-08 12:11:23.449 screenresolution[1626:7329] starting screenresolution argv=screenresolution list 12:11:23 INFO - Available Modes on Display 0 12:11:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:11:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:11:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:11:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:11:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:11:23 INFO - Intel Iris: 12:11:23 INFO - Chipset Model: Intel Iris 12:11:23 INFO - Type: GPU 12:11:23 INFO - Bus: Built-In 12:11:23 INFO - VRAM (Dynamic, Max): 1536 MB 12:11:23 INFO - Vendor: Intel (0x8086) 12:11:23 INFO - Device ID: 0x0a2e 12:11:23 INFO - Revision ID: 0x0009 12:11:23 INFO - Displays: 12:11:23 INFO - Display: 12:11:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:11:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:11:23 INFO - Display Serial Number: 1600x1200 60 12:11:23 INFO - Main Display: Yes 12:11:23 INFO - Mirror: Off 12:11:23 INFO - Online: Yes 12:11:23 INFO - Rotation: Supported 12:11:23 INFO - Return code: 0 12:11:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:11:23 INFO - Python 2.7.3 12:11:23 INFO - Return code: 0 12:11:23 INFO - grabbing minidump binary from tooltool 12:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:11:23 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 0x10eff5d30>, '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 0x10ef1cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10efeabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:11:23 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:11:23 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:11:23 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:11:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:11:23 INFO - Return code: 0 12:11:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:11:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:11:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:11:23 INFO - ENV: RUST_BACKTRACE is now 1 12:11:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:11:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:11:23 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--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:11:23 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0095 --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:11:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cp5UUhvQ8o/Render', 12:11:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:11:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:11:23 INFO - 'HOME': '/Users/cltbld', 12:11:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:11:23 INFO - 'LOGNAME': 'cltbld', 12:11:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:11:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:11:23 INFO - 'MOZ_NO_REMOTE': '1', 12:11:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:11:23 INFO - 'NO_EM_RESTART': '1', 12:11:23 INFO - 'PAGER': '/bin/cat', 12:11:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:11:23 INFO - 'PWD': '/builds/slave/test', 12:11:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:11:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:11:23 INFO - 'RUST_BACKTRACE': '1', 12:11:23 INFO - 'SHELL': '/bin/bash', 12:11:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KK6KxwstTr/Listeners', 12:11:23 INFO - 'TMPDIR': '/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/', 12:11:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:11:23 INFO - 'USER': 'cltbld', 12:11:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:11:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:11:23 INFO - 'XPC_FLAGS': '0x0', 12:11:23 INFO - 'XPC_SERVICE_NAME': '0', 12:11:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:11:23 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0095', '--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:11:24 INFO - mozversion application_buildid: 20170608104024 12:11:24 INFO - mozversion application_changeset: d8424bcbf72f7a451e89f8f625a9ebafecded872 12:11:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:11:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:11:24 INFO - mozversion application_name: Firefox 12:11:24 INFO - mozversion application_remotingname: firefox-dev 12:11:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:11:24 INFO - mozversion application_vendor: Mozilla 12:11:24 INFO - mozversion application_version: 54.0 12:11:24 INFO - mozversion platform_buildid: 20170608104024 12:11:24 INFO - mozversion platform_changeset: d8424bcbf72f7a451e89f8f625a9ebafecded872 12:11:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:11:24 INFO - mozversion platform_version: 54.0 12:11:24 INFO - using testdate: 1496949084 12:11:24 INFO - actual date: 1496949084 12:11:24 INFO - starting webserver on 'localhost:49226' 12:11:24 INFO - SUITE-START | Running 2 tests 12:11:24 INFO - TEST-START | dromaeo_css 12:11:24 INFO - operating with platform_type : mac_ 12:11:24 INFO - Initialising browser for dromaeo_css test... 12:11:24 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpFPabbp/profile http://localhost:49226/getInfo.html) 12:11:26 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 12:11:26 INFO - PROCESS | 1636 | colorDepth:24 12:11:26 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 12:11:26 INFO - PROCESS | 1636 | __metrics 12:11:26 INFO - PROCESS | 1636 | 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:11:26 INFO - PROCESS | 1636 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:11:27 INFO - TEST-INFO | 1636: exit 0 12:11:27 INFO - Browser initialized. 12:11:27 INFO - Running cycle 1/1 for dromaeo_css test... 12:11:27 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpFPabbp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:11:39 INFO - PROCESS | 1638 | RSS: Main: 193667072 12:11:39 INFO - PROCESS | 1638 | 12:14:43 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 12:14:45 INFO - PROCESS | 1638 | RSS: Main: 381976576 12:14:45 INFO - PROCESS | 1638 | 12:17:48 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 12:17:48 INFO - PROCESS | 1638 | RSS: Main: 328052736 12:17:48 INFO - PROCESS | 1638 | 12:20:51 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 12:20:51 INFO - PROCESS | 1638 | RSS: Main: 307916800 12:20:51 INFO - PROCESS | 1638 | 12:23:53 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 12:23:54 INFO - PROCESS | 1638 | RSS: Main: 317308928 12:23:54 INFO - PROCESS | 1638 | 12:26:58 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 12:26:58 INFO - PROCESS | 1638 | RSS: Main: 289820672 12:26:58 INFO - PROCESS | 1638 | 12:30:01 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 12:30:02 INFO - PROCESS | 1638 | RSS: Main: 351137792 12:30:02 INFO - PROCESS | 1638 | 12:30:02 INFO - PROCESS | 1638 | __start_tp_report 12:30:02 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 12:30:02 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 12:30:02 INFO - PROCESS | 1638 | |i|pagename|runs| 12:30:02 INFO - PROCESS | 1638 | |0;dojo.html;3410.369081719882;3417.29945361201;3450.999999999999;3465.6187819339852;3483.216276337823;5920.348761636222;5949.553783466246;5961.493273071783;5973.40265973403;6036;5910.438508341701;5935.258092738408;5961.105834124884;5982.700864956746;5986.461218490335;5342.519173274414;5369.9463005370035;5385.111456609655;5424.620276580641;5463.972680136613;5301.5228629423345;5303.893922121555;5315.53822698759;5461;5475;5395.406505284415;5398.784048638069;5404.243405923174;5409.756560954746;5424.403315635277;5419.783208671648;5425.9728701356435;5435.9943410468795;5445.156000819879;5462.235287059815;5214.270002199695;5296.443873393316;5364.812231571916;5374.435684253175;5427.945720542783;5745.080787074048;5770.451807078362;5776.740046697909;5778.3066032076;5783.681897495636;5282.418933917266;6292.244930608356;6367.936320636781;6370.777022804226;6371.553991220617;5249.737513124367;6220.440160385542;6353.396427339441;6367.07677386781;6369.585976911486;6072.635641861459;6118.741008805849;6119.045604113885;6140.1799587324185;6144.569880108439;6442;6442.420182183626;6443.871122577616;6445.581037232565;6484.643344615997;6159.045347971071;6214.347493513206;6397.200349956255;6407.871842563122;6437.356264373525;5635.492805647429;6311.147995020613;6311.431971122529;6344.873102537924;6350.555461117678;6313;6322.209723784527;6327.968360158262;6337.081123237105;6338.683065846689;6267.310595834455;6277.780277690259;6313.210848643919;6319.526035547352;6346.30190679025;4740.881477963078;4874.025194961022;4882.829100981448;4890.584300334512;4907.895723462192;4409.691321607456;4424.690271680952;4429.269170586882;4435.689501734848;4443.200223959719;5609.719514024282;5658.236138121383;5692.459216374438;5819.709014549256;5836.4747172753805;6289.339619339995;6315.400036996478;6346.619202847844;6370.426661600478;6375.171227740457;5735.225744524436;5767.971160144172;5779.162021506942;5782.768689252467;5809.564282678816;5482.424345443751;5509.063459211943;5517.475839795213;5541.612087153861;5544.002079625706;6252.718627661766;6292.370762923671;6323.462505687068;6334.334894835976;6338.176037115146;6313.684315784284;6333.20834895638;6355.428011478989;6360.681965901594;6374.521910856612;6423;6488.091667166506;6493.253275873146;6493.740250389931;6499.1876015498065;6355.396237357444;6356.2054743157105;6367.777127800505;6369.936300636934;6396.904046439214;6280;6302.432781049728;6325.335839736761;6350.68246587678;6354.015127655221;5421.810236641856;6285.308616052322;6348.269948955929;6392.488600912009;6399.64801935898;6263.467605253604;6367.235931688227;6376.553641245254;6393.2647745508;6393.648349340645;6007.579469437097;6036.758529658764;6192.845178870564;6273.843153921189;6274.309825919054;6303.36966303366;6389.39300766445;6391.392817682313;6397.680115994089;6417.326180750954;4307.461567304087;6019.51843852482;6048.032314829607;6087.634741915499;6179 12:30:02 INFO - PROCESS | 1638 | |1;ext.html;803.2650125135501;823.9217274358936;858.8153546987396;872.149654087265;872.2411501993266;40608.59391406085;41252.38121428177;41318;41508.79245603775;43312;42645.573544264626;42821;42843.78578107105;42897.78551107248;42943;7488.925110748904;7629.732959346424;9528.523573821298;9686.66096686616;11520.308781473139;11621;11678.883211167908;11695.824562631567;11703;11709.648710538655;11682.474288656987;11685.70785730355;11693.123015773852;11707.473163707653;11709.707257318592;11649.767004659901;11684.298942063504;11697.883021169764;11708.238964467282;11739.06087512997;11455.942720286428;11526.077913766962;11532.538698452052;11549.422528873523;11557.479913403833;40521.18957620831;40837.979050523674;40849.59150408488;40872.773816785244;40975.36098556054;3557.5019497270405;3740.4389341598708;3847.3844184930276;3886.7667939923695;3945.250402423559;28679;29148;30069;30115.849420752977;30404.391912161853;2580.600006998918;3121.7658675599196;3198.3512836010286;3232.773705840592;3307.6361600224195;341467.5853241461;343348;344192.2790386032;345116;346491;43970.34044489335;44363;46617.533824661994;53535.732321338146;53824;46514.30228546575;46539;46621.533784662395;60205;61253.01855379386;15227.543173704807;15409.9229503854;15411.383544658089;15412.922935385475;15427.922860385626;12824.615261542169;12895.935520322526;12930.159539629894;12932.806007909889;12948.417321220568;12763.91506721939;13643;13663.043586948817;13755.449782008607;13864.098833575785;5162.380514338303;5571.693556854331;6081.930885787912;6143.293521245025;6149.292831324366;1090.7654854206226;1156.6067537037447;1167.2529581068122;1177.9941100294616;1233.4202924625413;22153.44616384571;23529.7647023531;27238.45523089527;27490.725092748817;28164.87340506357;1141.4749215361028;1215.9452824622913;1230.1266101068343;1237.8762123787549;1242.7203879127123;13348.532801351907;13917.914359744995;14314.355853986595;14421.134731916118;14515.491957781427;496942;500634;501175;501822;503154.48422758386;59968;60016.69991650014;60105;60127;60157.69921150366;1442.6393401649589;1471.065873170523;1472.749632562467;1515.9242037898061;1529.3118096856458;27108.864455677594;27719;28116;28256.29359266035;28263.576046359733;38388;38522.422163668125;38547.80726096351;38609;38678.419823702105;463131;466142;466626;466738;468162.31837681186;24657.383565411008;24824.503509930062;25210;25218.117365892107;30114.945976890704;32073.839630801696;36958.8152059238;37451.43822842605;39387.40918886162;39442.60557394504;26209.73790262073;27402.040928567403;27795.58306625443;27925.16224513268;28841.42317153687;23955.401114972265;28711.569326459703;28751.28121796888;29106.7089329104;29244.56133158047;31949.041528754173;32589.348213035133;32971.17572060718;33199.66800331966;33386.833065834515;28127.87488500437;28290.86836526516;28321.57517637196;28353.574696379983;29279.560806588343;22142;22720.636761794347;22913.65629515592;23086.653700194176;23300.43695193561 12:30:02 INFO - PROCESS | 1638 | |2;jquery.html;377.98299076541554;412.45555866256535;420.12194513466886;425.160308390928;457.0014518277564;19701.21195152194;20655.483612909684;21733.565328693418;21902.90485475728;21932.890335548338;14140.363683634236;15423;21440.785592144075;21661.45846353842;23295.534089318204;6759.222689390709;6869.416099631525;7050.224475307712;7078.752243671473;7093.71625134994;6769.695363708645;6920.238773734885;6937.9306206938045;7015.82460438488;7016.8772996320595;6703.597784132943;6883.208431030421;6968.756093536727;7028.226895041567;7039.401650859683;6724.361185687352;6881.277465866087;6933.72265109395;6938.895916561256;6938.930610693879;6781.593104413701;6849.965750171267;6853.588784672936;6866.588004719732;6868.381845633917;26913;27137;27218;27240.72759272402;27476.313092172855;3069.309405383793;3074.13924101252;3075.4310452566124;3080.3993221321853;3086.4598695228246;16124.355025798954;17326.74009889853;17348.392806251846;17466.912665436717;17995.550111247194;1871.1018711018787;2026.6149431607948;2031.6139933412753;2053.917843286267;2059.06312627754;128513.0536857902;129576.3521182388;136127.31936340255;138627;139681.3015934924;17861.553461163574;20714.792852071587;21051.78948210498;21706.13175472995;21966.011529481213;30163.190208587115;36419.725309614034;36781.26437471236;36815.4477682835;36819.263614727555;9059.954700226457;9638.759031024281;9704.980976997454;10188.184945204513;11116.722081947853;8774.298056155492;9063.456192628331;9080.182783549511;9247.491387973592;9276.907230927603;9172.587233574504;10737.463126843782;10892.37732989701;10945;10956.506957186946;3304.067120734397;4068.2880495913096;4072.8167232474016;4168.499780026416;4183.790810459464;942.1614762861059;947.545178314413;950.1828427552299;988.5600907596195;989.1641562879355;21976.89011554932;23423.7657623425;23453.88273058624;23507.412314691926;23565.88217058938;831.6340810043562;967.4969016111583;967.9177269931994;987.4667679453015;1008.1682611845257;11783.057355411547;12311.876881231252;12460;12586.937065314614;12640.05199610033;742446;756036;766903;777477;783938;114075.85924140818;122630.77369226373;124711;125253.37373313075;130600;1270.8919741821865;1271.3007845684836;1304.2370213425252;1304.8890844278153;1312.8424589049375;19717;19729.013549322764;20290.695639565714;20336.898315508326;20346.89826550917;54589.36231913049;55549.44450555443;55922.72038639781;56313;57285.42714572801;76248;84459.5777021111;87569;92929.5353523255;96381.51809240908;10891.292066016;11377.658670239905;11387.829182562102;11466.31202127875;11815.232009919328;12621.373763797383;12684.365781711062;12957;13062.934685326893;13098.607041788762;11025.503852326505;11054.834177487182;11059.72350691239;11299.09607231403;11423.714407139889;9992.200623950212;10165.491725413553;10230.897691022994;10232.488375581042;10547.736306592396;11153.275037122561;11333.376664283544;11366.204365694322;11496.82754758696;11632.069434445393;11439.942800285946;11460.19778615489;11585.942070289595;11682.94158529236;11811.232269902428;10887.727806804893;11322.773544529227;11335.206535542762;11342.376169310766;11438.82841757391 12:30:02 INFO - PROCESS | 1638 | |3;mootools.html;1447.7466443372407;1614.321984766398;1627.8860479766413;1650.0347296831349;1654.5946243170424;10044.547995340208;11275.661730148098;11284.153688473376;11298.943505282483;11324.980751732342;10872.565097396115;10980.670579882597;11186.161037922147;11314.208005439601;11413.42932853356;2312.5143719818857;2346.85918844869;2348.3542025942834;2350.811935045192;2355.458244603742;2248.5615305015526;2329.452578644017;2341.086976079322;2343.60158773009;2355.340504658698;1971.3987233893656;2286.039863257436;2326.348622385735;2341.484873327865;2343.9179628713;2253.3352660965056;2311.06401907227;2315.3169814904645;2315.9305220843403;2326.5579539887453;2311.751654106797;2316.745158032615;2318.1538738360546;2324.5815753164593;2326.8603883767028;11145.996860282614;11223.38271395073;11226.66320010401;11229.3262404256;11245.269057511236;5379.193121031837;5527.170924361338;5540.833774986757;5556.555475561945;5572.86067848303;3186.8406579671155;3275.9344813103844;3457.6023757267985;3460.74044446665;3467;1151.9020883224937;1173.501261963662;1742.9651406971916;1799.478151336111;1831.9542011449687;13816.654583635389;14124.646883827883;14744.483943061941;15230.847691523053;15649.765253521206;4315.438992930962;4438.4451943507065;4477.619402350837;4481.014176881081;4487.820487180476;11878.227915185658;11884.762304753856;11886.40567971598;11910.880891191151;11915.98714109293;3960.683145348365;4811;4839.128956787742;4855.490173531799;5509.669419834822;4305.311150216013;4985.975070124626;5040.873978150502;5078.08594453002;5124.103281925648;2876.6548014238424;3100;3634.3639863023864;3651.726120540917;3693.9445908311395;927.6614035876856;928.9024652411534;935.4948327902993;941.4774799986074;942.9151376376159;943.1747221181466;968.3463662028103;977.9706608801747;991.8660980767648;995.5320999130397;6547.607143571401;6645.900311495331;6676.132102826601;6765.458763298924;6804.829879253059;975.6536429567543;999.9550020249106;1016.1870503597092;1033.235405799703;1040.8178568750438;4127.628513433805;4254.149719687816;4292.18448494785;4362.454693163355;4449.354843547732;12165.513379464723;12795.808062879065;12972.870271297355;13546.796798048037;13560.796588051187;13471.730565388638;13478.595642130751;13481.595552133256;13500.054996150371;13521.850642695284;8912.509811960404;10151.847722284172;10239.180865530889;10246.924072972379;10273.434961077062;6906.447484201352;6924.515283930076;6984.511084224056;7070.540414873017;7163.391111755559;12278;12326.383680816103;12357.505699771908;12469.438875250455;12488.188267762567;10171.796564068825;10804.24370294072;12352.75294494123;12587.74824503523;12631.11582189238;9170.220531254652;9372.109649583279;9429.669961551586;9431.339806213773;9501.477418741759;10224.795504090025;10348;10411.739706507398;10413.062824345845;10415.114715248985;7403.407727381689;8947.507887066275;8987.325950553604;9016.639334426549;9060.456372617664;8744.562771861509;8783.429077109968;8807.691730789391;8816.603252853509;8851.60167792438;7940.6029698513685;7968.561729104955;8065.112837587978;8079.959600201962;8122.268995790408;4898.387701537308;5535.861603459945;5621.55027597783;5726.341470730753;5766.308043034863;5915.290165180034;5952.255968003999;6014.458698717136;6031;6157.291911430243 12:30:02 INFO - PROCESS | 1638 | |4;prototype.html;367.71685801932506;373.7402505258846;383.79083399547244;386.74308497383487;389.101176282787;4352.956470435299;4370.956290437095;4564.726116433017;5092.541671249587;5094.235864620309;4101.733387329821;4595.5634214749625;4805.927911081337;4892.727890748404;4909.410870695513;3478.669526394988;3700.463432802242;3741.981290093546;3959.46547216125;4246.554111818261;4241.915161696764;4259.78701064947;4276.8024953013055;4334.588214119669;4338.414314067608;3640.7451478396524;3924.607539246081;3951.46655201547;4146.688998325122;4271.359296105594;3799.25914446683;3887.7839631410106;4122.835086596518;4176.749395036308;4181.91636167278;3109.486934655779;3511.6806328216435;3835.7314987950854;3849.2301539692176;3946.782926939017;4103.876883693494;4718.200555963138;4935.827246046407;4936.679115857458;5005.3721796057;4308.9963167127125;4553.4535855697195;4853.247746599282;4900;4908.582770464514;4473.642108631302;4539.455265368144;4541.318802179667;4916.950830491685;5309.33633295838;3935.622532113766;3991.962089856629;4086.509618845728;4412.294032954712;4516.7578915798085;4856.4172299323955;5204.9739751301;5488.121900495941;5596.664200148004;5732.799352022701;4342.934855977162;4369.737815731066;4484.798184081724;4488.730676159375;4497.370368148462;4521.61566266871;4552;4568.383268258809;4571.611413029929;4578.427696537933;4134.421181034598;4414.536473670219;4695.600873925686;4761.547652972962;4817;4845.53967373099;5414.88994756074;5425.755840987165;5440.755166017539;5461.481159289861;1384.6746014686541;1519.3011214841076;1553.1923399832024;1564.428983421043;1564.968700625981;1058.5342449322275;1094.5621751299384;1153.794284972206;1154.7286387698884;1176.758764453285;4584.312353147055;4615.261558150747;4879.316895634614;4894.461609222983;4905.705657660552;4969.627277954168;5282.418933917266;5586.804461843816;5632.774689012475;5634.703698336519;4302.9887976325535;4310.504292006397;4393.538678438718;4556.223073001532;4907.877303067452;2602.518534071203;2662.8269162504375;2908.8836446542323;2912.189736903898;2916.110586271165;5191.8182863599595;5713.74288157034;5791.623544469596;5834.649921004753;5836.854078648068;4679.571696831659;4775.785089670973;4919.77860996256;5367.810879552094;5579.804706835242;5541.226807421667;5558.722063896789;5560.471755183251;5565.30433695788;5569.805056822992;5002.149634562061;5181.341970569381;5236;5313.707746073848;5486.835394938158;5011.09356573194;5070.391553013662;5126.872088140603;5194.584433245256;5448.972755136199;5712.285964254468;5732.856678583069;5780.508656764054;5841.211436456024;5860.2967643883;3858.9614103858603;3949.111449923744;4147.854825081107;4342.674299427493;4411.750007496136;5050.84847454577;5149.433562308218;5430.596556426223;5510.925369552899;5521.702399935932;4903.8528844134735;4943.085529177114;5028.21498368074;5064.46823083571;5435.412627665722;4843.806247750051;4923.384576927884;5108.565771909281;5384.273123128328;5467.617266791286;4575.444348921383;4827.606438112357;5124.692518448905;5202.76281199088;5223.268742375994;4333.674974376871;4801.4958429364415;5301.0579128584;5305.946940530545;5343.946560534345;4743.600637811923;4979.925301120558;5055.014272216882;5222.033923724123;5297.390800058042 12:30:02 INFO - PROCESS | 1638 | |5;yui.html;755.165542076006;762.6415584675201;764.7170546897648;768.6848392159214;789.218673513222;15423.768643470357;15922;16323.999999999985;16328;16564;13132.73734525309;14424.134551926893;15090.924545377258;15224.314905829237;15234.009789363712;965.3146266151041;998.1814911772314;998.9350692204983;999.8800143982733;1000.2198285337448;953.6233187890779;978.8335982882927;982.2976571751196;983.990160098397;985.2561316206254;1034.632705389587;1037.035556932053;1037.3361048928691;1037.7872536130112;1037.8806437259698;1031.0617338222257;1031.8813336466328;1033.5710680067764;1033.607229252883;1033.6485594897772;773.9342155916697;774.5585016540574;776.0377132355894;776.6543887969856;777.5529070784265;15947.44183953556;16231.91884040584;16242.862999590034;16669.583260418465;16891.577710557212;14155.43378264868;14259.500917467838;14377.496787612488;14387;14409.279536023158;11323;11669.124815638776;11712;11751.353675547842;12363.505459781598;3711.9058307147598;3741.158239396141;3819.255245227154;3868.5551161616213;3907.1404291055924;107277.46361268144;108314;111227.88772112256;112801.87198128078;114121.4293928525;10289.228307876789;10748.032677058945;10932.234743567873;10947.83578246327;10977.945110274397;22653.320400388016;22818.771812281997;22847.543049139258;22886.313410597708;22924.88537557335;7844.921550784533;7846.293833555007;7988.600569971478;7993.72021979228;8013.959930200428;4313.913721725611;4331.371951067078;4399.538048504861;4562.794674239667;4672.883177920579;2870.799044066937;3352.8826491072696;3440.913977150541;3452.223249768782;3454.7927124372113;607.1530215349562;615.2616859768295;615.4522474997256;617.0589850477967;617.9134921111048;562.2016736234557;565.7934853778423;577.8353169346716;624.5097598385337;624.8531595075153;9675.371100878421;9957.900420995842;10021.94951611291;10061;10857.408434656158;560.3080195957989;579.8405438504361;608.6409018678912;654.2410803467955;751.7256201486418;4960.280759289883;5044.935353968855;5097.8215762448135;5098.1588037973315;5102.872428189325;71568.64215678992;79909.80135297975;91949.5402522983;99098;101249.49375253076;55065;56475;59699.701501492214;87993;89685;60644.39355606476;68203;74521;74792.14470900211;77889.61055194914;9546.52267386614;10631.096356809758;11072.944635276772;11192.048675862643;11337.376444295644;48507;50353.244701328775;50636.734081648254;51547.742261288455;52378.47621523736;59099.38555837188;59256;60269.698651506464;60406.697966509884;60631.69684151551;13591.45634174622;13667.38496767628;13827;13828.585142445743;13914.930425347808;12084.274943503418;14518.782218266946;14795.482158124414;15088;15491.380344786083;13407.396667149807;13834.23911684867;13892.791608126088;14184;14348.49780257686;13112.729228715676;13795.931020344835;13797.51708690191;13816;13840.439518689074;13522.25627590492;13602.319884005958;13699.178049317074;13863;14144.646383840487;9138.268938484773;9478.620855165991;9701.369410988264;9771.853422198816;10010.249231307827;11177.944110279723;11330.773384532098;11384.772304553697;11475.655730328104;11589.594364197214 12:30:02 INFO - PROCESS | 1638 | __end_tp_report 12:30:02 INFO - PROCESS | 1638 | __start_cc_report 12:30:02 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,2083 12:30:02 INFO - PROCESS | 1638 | __end_cc_report 12:30:02 INFO - PROCESS | 1638 | __startTimestamp1496950202132__endTimestamp 12:30:02 INFO - PROCESS | 1638 | 12:30:02 INFO - PROCESS | 1638 | ------- Summary: start ------- 12:30:02 INFO - PROCESS | 1638 | Number of tests: 6 12:30:02 INFO - PROCESS | 1638 | 12:30:02 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5858.16 Median:6119.05 stddev:673.36 (11.0%) stddev-sans-first:647.46 12:30:02 INFO - PROCESS | 1638 | Values: 3410.4 3417.3 3451.0 3465.6 3483.2 5920.3 5949.6 5961.5 5973.4 6036.0 5910.4 5935.3 5961.1 5982.7 5986.5 5342.5 5369.9 5385.1 5424.6 5464.0 5301.5 5303.9 5315.5 5461.0 5475.0 5395.4 5398.8 5404.2 5409.8 5424.4 5419.8 5426.0 5436.0 5445.2 5462.2 5214.3 5296.4 5364.8 5374.4 5427.9 5745.1 5770.5 5776.7 5778.3 5783.7 5282.4 6292.2 6367.9 6370.8 6371.6 5249.7 6220.4 6353.4 6367.1 6369.6 6072.6 6118.7 6119.0 6140.2 6144.6 6442.0 6442.4 6443.9 6445.6 6484.6 6159.0 6214.3 6397.2 6407.9 6437.4 5635.5 6311.1 6311.4 6344.9 6350.6 6313.0 6322.2 6328.0 6337.1 6338.7 6267.3 6277.8 6313.2 6319.5 6346.3 4740.9 4874.0 4882.8 4890.6 4907.9 4409.7 4424.7 4429.3 4435.7 4443.2 5609.7 5658.2 5692.5 5819.7 5836.5 6289.3 6315.4 6346.6 6370.4 6375.2 5735.2 5768.0 5779.2 5782.8 5809.6 5482.4 5509.1 5517.5 5541.6 5544.0 6252.7 6292.4 6323.5 6334.3 6338.2 6313.7 6333.2 6355.4 6360.7 6374.5 6423.0 6488.1 6493.3 6493.7 6499.2 6355.4 6356.2 6367.8 6369.9 6396.9 6280.0 6302.4 6325.3 6350.7 6354.0 5421.8 6285.3 6348.3 6392.5 6399.6 6263.5 6367.2 6376.6 6393.3 6393.6 6007.6 6036.8 6192.8 6273.8 6274.3 6303.4 6389.4 6391.4 6397.7 6417.3 4307.5 6019.5 6048.0 6087.6 6179.0 12:30:02 INFO - PROCESS | 1638 | 12:30:02 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:56965.77 Median:29196.28 stddev:117582.07 (402.7%) stddev-sans-first:117836.31 12:30:02 INFO - PROCESS | 1638 | Values: 803.3 823.9 858.8 872.1 872.2 40608.6 41252.4 41318.0 41508.8 43312.0 42645.6 42821.0 42843.8 42897.8 42943.0 7488.9 7629.7 9528.5 9686.7 11520.3 11621.0 11678.9 11695.8 11703.0 11709.6 11682.5 11685.7 11693.1 11707.5 11709.7 11649.8 11684.3 11697.9 11708.2 11739.1 11455.9 11526.1 11532.5 11549.4 11557.5 40521.2 40838.0 40849.6 40872.8 40975.4 3557.5 3740.4 3847.4 3886.8 3945.3 28679.0 29148.0 30069.0 30115.8 30404.4 2580.6 3121.8 3198.4 3232.8 3307.6 341467.6 343348.0 344192.3 345116.0 346491.0 43970.3 44363.0 46617.5 53535.7 53824.0 46514.3 46539.0 46621.5 60205.0 61253.0 15227.5 15409.9 15411.4 15412.9 15427.9 12824.6 12895.9 12930.2 12932.8 12948.4 12763.9 13643.0 13663.0 13755.4 13864.1 5162.4 5571.7 6081.9 6143.3 6149.3 1090.8 1156.6 1167.3 1178.0 1233.4 22153.4 23529.8 27238.5 27490.7 28164.9 1141.5 1215.9 1230.1 1237.9 1242.7 13348.5 13917.9 14314.4 14421.1 14515.5 496942.0 500634.0 501175.0 501822.0 503154.5 59968.0 60016.7 60105.0 60127.0 60157.7 1442.6 1471.1 1472.7 1515.9 1529.3 27108.9 27719.0 28116.0 28256.3 28263.6 38388.0 38522.4 38547.8 38609.0 38678.4 463131.0 466142.0 466626.0 466738.0 468162.3 24657.4 24824.5 25210.0 25218.1 30114.9 32073.8 36958.8 37451.4 39387.4 39442.6 26209.7 27402.0 27795.6 27925.2 28841.4 23955.4 28711.6 28751.3 29106.7 29244.6 31949.0 32589.3 32971.2 33199.7 33386.8 28127.9 28290.9 28321.6 28353.6 29279.6 22142.0 22720.6 22913.7 23086.7 23300.4 12:30:02 INFO - PROCESS | 1638 | 12:30:02 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:42222.67 Median:22636.21 stddev:126421.82 (558.5%) stddev-sans-first:126737.42 12:30:02 INFO - PROCESS | 1638 | Values: 378.0 412.5 420.1 425.2 457.0 19701.2 20655.5 21733.6 21902.9 21932.9 14140.4 15423.0 21440.8 21661.5 23295.5 6759.2 6869.4 7050.2 7078.8 7093.7 6769.7 6920.2 6937.9 7015.8 7016.9 6703.6 6883.2 6968.8 7028.2 7039.4 6724.4 6881.3 6933.7 6938.9 6938.9 6781.6 6850.0 6853.6 6866.6 6868.4 26913.0 27137.0 27218.0 27240.7 27476.3 3069.3 3074.1 3075.4 3080.4 3086.5 16124.4 17326.7 17348.4 17466.9 17995.6 1871.1 2026.6 2031.6 2053.9 2059.1 128513.1 129576.4 136127.3 138627.0 139681.3 17861.6 20714.8 21051.8 21706.1 21966.0 30163.2 36419.7 36781.3 36815.4 36819.3 9060.0 9638.8 9705.0 10188.2 11116.7 8774.3 9063.5 9080.2 9247.5 9276.9 9172.6 10737.5 10892.4 10945.0 10956.5 3304.1 4068.3 4072.8 4168.5 4183.8 942.2 947.5 950.2 988.6 989.2 21976.9 23423.8 23453.9 23507.4 23565.9 831.6 967.5 967.9 987.5 1008.2 11783.1 12311.9 12460.0 12586.9 12640.1 742446.0 756036.0 766903.0 777477.0 783938.0 114075.9 122630.8 124711.0 125253.4 130600.0 1270.9 1271.3 1304.2 1304.9 1312.8 19717.0 19729.0 20290.7 20336.9 20346.9 54589.4 55549.4 55922.7 56313.0 57285.4 76248.0 84459.6 87569.0 92929.5 96381.5 10891.3 11377.7 11387.8 11466.3 11815.2 12621.4 12684.4 12957.0 13062.9 13098.6 11025.5 11054.8 11059.7 11299.1 11423.7 9992.2 10165.5 10230.9 10232.5 10547.7 11153.3 11333.4 11366.2 11496.8 11632.1 11439.9 11460.2 11585.9 11682.9 11811.2 10887.7 11322.8 11335.2 11342.4 11438.8 12:30:02 INFO - PROCESS | 1638 | 12:30:02 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6649.61 Median:2355.40 stddev:4191.58 (178.0%) stddev-sans-first:4185.12 12:30:02 INFO - PROCESS | 1638 | Values: 1447.7 1614.3 1627.9 1650.0 1654.6 10044.5 11275.7 11284.2 11298.9 11325.0 10872.6 10980.7 11186.2 11314.2 11413.4 2312.5 2346.9 2348.4 2350.8 2355.5 2248.6 2329.5 2341.1 2343.6 2355.3 1971.4 2286.0 2326.3 2341.5 2343.9 2253.3 2311.1 2315.3 2315.9 2326.6 2311.8 2316.7 2318.2 2324.6 2326.9 11146.0 11223.4 11226.7 11229.3 11245.3 5379.2 5527.2 5540.8 5556.6 5572.9 3186.8 3275.9 3457.6 3460.7 3467.0 1151.9 1173.5 1743.0 1799.5 1832.0 13816.7 14124.6 14744.5 15230.8 15649.8 4315.4 4438.4 4477.6 4481.0 4487.8 11878.2 11884.8 11886.4 11910.9 11916.0 3960.7 4811.0 4839.1 4855.5 5509.7 4305.3 4986.0 5040.9 5078.1 5124.1 2876.7 3100.0 3634.4 3651.7 3693.9 927.7 928.9 935.5 941.5 942.9 943.2 968.3 978.0 991.9 995.5 6547.6 6645.9 6676.1 6765.5 6804.8 975.7 1000.0 1016.2 1033.2 1040.8 4127.6 4254.1 4292.2 4362.5 4449.4 12165.5 12795.8 12972.9 13546.8 13560.8 13471.7 13478.6 13481.6 13500.1 13521.9 8912.5 10151.8 10239.2 10246.9 10273.4 6906.4 6924.5 6984.5 7070.5 7163.4 12278.0 12326.4 12357.5 12469.4 12488.2 10171.8 10804.2 12352.8 12587.7 12631.1 9170.2 9372.1 9429.7 9431.3 9501.5 10224.8 10348.0 10411.7 10413.1 10415.1 7403.4 8947.5 8987.3 9016.6 9060.5 8744.6 8783.4 8807.7 8816.6 8851.6 7940.6 7968.6 8065.1 8080.0 8122.3 4898.4 5535.9 5621.6 5726.3 5766.3 5915.3 5952.3 6014.5 6031.0 6157.3 12:30:02 INFO - PROCESS | 1638 | 12:30:02 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4432.31 Median:4768.67 stddev:1228.72 (25.8%) stddev-sans-first:1193.69 12:30:02 INFO - PROCESS | 1638 | Values: 367.7 373.7 383.8 386.7 389.1 4353.0 4371.0 4564.7 5092.5 5094.2 4101.7 4595.6 4805.9 4892.7 4909.4 3478.7 3700.5 3742.0 3959.5 4246.6 4241.9 4259.8 4276.8 4334.6 4338.4 3640.7 3924.6 3951.5 4146.7 4271.4 3799.3 3887.8 4122.8 4176.7 4181.9 3109.5 3511.7 3835.7 3849.2 3946.8 4103.9 4718.2 4935.8 4936.7 5005.4 4309.0 4553.5 4853.2 4900.0 4908.6 4473.6 4539.5 4541.3 4917.0 5309.3 3935.6 3992.0 4086.5 4412.3 4516.8 4856.4 5205.0 5488.1 5596.7 5732.8 4342.9 4369.7 4484.8 4488.7 4497.4 4521.6 4552.0 4568.4 4571.6 4578.4 4134.4 4414.5 4695.6 4761.5 4817.0 4845.5 5414.9 5425.8 5440.8 5461.5 1384.7 1519.3 1553.2 1564.4 1565.0 1058.5 1094.6 1153.8 1154.7 1176.8 4584.3 4615.3 4879.3 4894.5 4905.7 4969.6 5282.4 5586.8 5632.8 5634.7 4303.0 4310.5 4393.5 4556.2 4907.9 2602.5 2662.8 2908.9 2912.2 2916.1 5191.8 5713.7 5791.6 5834.6 5836.9 4679.6 4775.8 4919.8 5367.8 5579.8 5541.2 5558.7 5560.5 5565.3 5569.8 5002.1 5181.3 5236.0 5313.7 5486.8 5011.1 5070.4 5126.9 5194.6 5449.0 5712.3 5732.9 5780.5 5841.2 5860.3 3859.0 3949.1 4147.9 4342.7 4411.8 5050.8 5149.4 5430.6 5510.9 5521.7 4903.9 4943.1 5028.2 5064.5 5435.4 4843.8 4923.4 5108.6 5384.3 5467.6 4575.4 4827.6 5124.7 5202.8 5223.3 4333.7 4801.5 5301.1 5305.9 5343.9 4743.6 4979.9 5055.0 5222.0 5297.4 12:30:02 INFO - PROCESS | 1638 | 12:30:02 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:19481.82 Median:3111.84 stddev:27091.70 (870.6%) stddev-sans-first:27131.21 12:30:02 INFO - PROCESS | 1638 | Values: 755.2 762.6 764.7 768.7 789.2 15423.8 15922.0 16324.0 16328.0 16564.0 13132.7 14424.1 15090.9 15224.3 15234.0 965.3 998.2 998.9 999.9 1000.2 953.6 978.8 982.3 984.0 985.3 1034.6 1037.0 1037.3 1037.8 1037.9 1031.1 1031.9 1033.6 1033.6 1033.6 773.9 774.6 776.0 776.7 777.6 15947.4 16231.9 16242.9 16669.6 16891.6 14155.4 14259.5 14377.5 14387.0 14409.3 11323.0 11669.1 11712.0 11751.4 12363.5 3711.9 3741.2 3819.3 3868.6 3907.1 107277.5 108314.0 111227.9 112801.9 114121.4 10289.2 10748.0 10932.2 10947.8 10977.9 22653.3 22818.8 22847.5 22886.3 22924.9 7844.9 7846.3 7988.6 7993.7 8014.0 4313.9 4331.4 4399.5 4562.8 4672.9 2870.8 3352.9 3440.9 3452.2 3454.8 607.2 615.3 615.5 617.1 617.9 562.2 565.8 577.8 624.5 624.9 9675.4 9957.9 10021.9 10061.0 10857.4 560.3 579.8 608.6 654.2 751.7 4960.3 5044.9 5097.8 5098.2 5102.9 71568.6 79909.8 91949.5 99098.0 101249.5 55065.0 56475.0 59699.7 87993.0 89685.0 60644.4 68203.0 74521.0 74792.1 77889.6 9546.5 10631.1 11072.9 11192.0 11337.4 48507.0 50353.2 50636.7 51547.7 52378.5 59099.4 59256.0 60269.7 60406.7 60631.7 13591.5 13667.4 13827.0 13828.6 13914.9 12084.3 14518.8 14795.5 15088.0 15491.4 13407.4 13834.2 13892.8 14184.0 14348.5 13112.7 13795.9 13797.5 13816.0 13840.4 13522.3 13602.3 13699.2 13863.0 14144.6 9138.3 9478.6 9701.4 9771.9 10010.2 11177.9 11330.8 11384.8 11475.7 11589.6 12:30:02 INFO - PROCESS | 1638 | -------- Summary: end -------- 12:30:02 INFO - PROCESS | 1638 | 12:30:02 INFO - TEST-INFO | 1638: exit 0 12:30:02 INFO - TEST-OK | dromaeo_css | took 1118628ms 12:30:02 INFO - TEST-START | kraken 12:30:02 INFO - operating with platform_type : mac_ 12:30:02 INFO - Initialising browser for kraken test... 12:30:02 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpF0yF9F/profile http://localhost:49226/getInfo.html) 12:30:04 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 12:30:04 INFO - PROCESS | 1649 | colorDepth:24 12:30:04 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 12:30:04 INFO - PROCESS | 1649 | __metrics 12:30:05 INFO - PROCESS | 1649 | 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:30:05 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:30:05 INFO - TEST-INFO | 1649: exit 0 12:30:05 INFO - Browser initialized. 12:30:05 INFO - Running cycle 1/1 for kraken test... 12:30:05 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/T/tmpF0yF9F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:30:17 INFO - PROCESS | 1651 | RSS: Main: 193810432 12:30:17 INFO - PROCESS | 1651 | 12:30:20 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 12:30:21 INFO - PROCESS | 1651 | RSS: Main: 244277248 12:30:21 INFO - PROCESS | 1651 | 12:30:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 12:30:24 INFO - PROCESS | 1651 | RSS: Main: 259170304 12:30:24 INFO - PROCESS | 1651 | 12:30:27 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 12:30:28 INFO - PROCESS | 1651 | RSS: Main: 354656256 12:30:28 INFO - PROCESS | 1651 | 12:30:30 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 12:30:31 INFO - PROCESS | 1651 | RSS: Main: 258105344 12:30:31 INFO - PROCESS | 1651 | 12:30:33 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:30:33 INFO - PROCESS | 1651 | RSS: Main: 259420160 12:30:33 INFO - PROCESS | 1651 | 12:30:36 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 12:30:37 INFO - PROCESS | 1651 | RSS: Main: 300658688 12:30:37 INFO - PROCESS | 1651 | 12:30:40 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 12:30:40 INFO - PROCESS | 1651 | RSS: Main: 300449792 12:30:40 INFO - PROCESS | 1651 | 12:30:43 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 12:30:44 INFO - PROCESS | 1651 | RSS: Main: 297000960 12:30:44 INFO - PROCESS | 1651 | 12:30:46 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:30:46 INFO - PROCESS | 1651 | RSS: Main: 277200896 12:30:46 INFO - PROCESS | 1651 | 12:30:48 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:30:49 INFO - PROCESS | 1651 | RSS: Main: 280494080 12:30:49 INFO - PROCESS | 1651 | 12:30:51 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:30:51 INFO - PROCESS | 1651 | RSS: Main: 288452608 12:30:51 INFO - PROCESS | 1651 | 12:30:54 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:30:55 INFO - PROCESS | 1651 | RSS: Main: 299675648 12:30:55 INFO - PROCESS | 1651 | 12:30:58 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:30:59 INFO - PROCESS | 1651 | RSS: Main: 293670912 12:30:59 INFO - PROCESS | 1651 | 12:31:00 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 12:31:01 INFO - PROCESS | 1651 | RSS: Main: 338759680 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | __start_tp_report 12:31:01 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 12:31:01 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 12:31:01 INFO - PROCESS | 1651 | |i|pagename|runs| 12:31:01 INFO - PROCESS | 1651 | |0;ai-astar;105;113;115;118;162;149;114;114;113;114 12:31:01 INFO - PROCESS | 1651 | |1;audio-beat-detection;145;107;111;144;110;111;112;113;155;102 12:31:01 INFO - PROCESS | 1651 | |2;audio-dft;185;182;181;184;184;184;184;183;185;181 12:31:01 INFO - PROCESS | 1651 | |3;audio-fft;81;64;82;116;87;58;58;60;75;60 12:31:01 INFO - PROCESS | 1651 | |4;audio-oscillator;91;69;86;61;54;91;114;98;55;88 12:31:01 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;87;86;87;87;87;87;86;87;87;86 12:31:01 INFO - PROCESS | 1651 | |6;imaging-darkroom;93;95;96;94;94;95;94;94;93;94 12:31:01 INFO - PROCESS | 1651 | |7;imaging-desaturate;86;86;83;84;84;86;85;83;84;92 12:31:01 INFO - PROCESS | 1651 | |8;json-parse-financial;58;60;61;61;63;63;64;65;63;63 12:31:01 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;58;64;67;61;61;58;58;59;58;59 12:31:01 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;86;86;83;85;85;85;84;94;98;89 12:31:01 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;138;135;136;134;136;134;135;141;177;136 12:31:01 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;164;162;160;163;165;161;219;173;162;163 12:31:01 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;66;67;63;64;65;64;64;64;65;61 12:31:01 INFO - PROCESS | 1651 | __end_tp_report 12:31:01 INFO - PROCESS | 1651 | __start_cc_report 12:31:01 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,2144 12:31:01 INFO - PROCESS | 1651 | __end_cc_report 12:31:01 INFO - PROCESS | 1651 | __startTimestamp1496950261055__endTimestamp 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | ------- Summary: start ------- 12:31:01 INFO - PROCESS | 1651 | Number of tests: 14 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:121.70 Median:114.50 stddev:18.37 (16.0%) stddev-sans-first:18.46 12:31:01 INFO - PROCESS | 1651 | Values: 105.0 113.0 115.0 118.0 162.0 149.0 114.0 114.0 113.0 114.0 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:121.00 Median:112.50 stddev:19.10 (17.0%) stddev-sans-first:18.18 12:31:01 INFO - PROCESS | 1651 | Values: 145.0 107.0 111.0 144.0 110.0 111.0 112.0 113.0 155.0 102.0 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:1.49 (0.8%) stddev-sans-first:1.45 12:31:01 INFO - PROCESS | 1651 | Values: 185.0 182.0 181.0 184.0 184.0 184.0 184.0 183.0 185.0 181.0 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:74.10 Median:69.50 stddev:18.41 (26.5%) stddev-sans-first:19.36 12:31:01 INFO - PROCESS | 1651 | Values: 81.0 64.0 82.0 116.0 87.0 58.0 58.0 60.0 75.0 60.0 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:80.70 Median:87.00 stddev:20.00 (23.0%) stddev-sans-first:20.86 12:31:01 INFO - PROCESS | 1651 | Values: 91.0 69.0 86.0 61.0 54.0 91.0 114.0 98.0 55.0 88.0 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.00 stddev:0.48 (0.6%) stddev-sans-first:0.50 12:31:01 INFO - PROCESS | 1651 | Values: 87.0 86.0 87.0 87.0 87.0 87.0 86.0 87.0 87.0 86.0 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:0.92 (1.0%) stddev-sans-first:0.87 12:31:01 INFO - PROCESS | 1651 | Values: 93.0 95.0 96.0 94.0 94.0 95.0 94.0 94.0 93.0 94.0 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.50 stddev:2.63 (3.1%) stddev-sans-first:2.77 12:31:01 INFO - PROCESS | 1651 | Values: 86.0 86.0 83.0 84.0 84.0 86.0 85.0 83.0 84.0 92.0 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:62.10 Median:63.00 stddev:2.08 (3.3%) stddev-sans-first:1.59 12:31:01 INFO - PROCESS | 1651 | Values: 58.0 60.0 61.0 61.0 63.0 63.0 64.0 65.0 63.0 63.0 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.00 stddev:3.06 (5.1%) stddev-sans-first:3.13 12:31:01 INFO - PROCESS | 1651 | Values: 58.0 64.0 67.0 61.0 61.0 58.0 58.0 59.0 58.0 59.0 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:86.00 stddev:4.84 (5.6%) stddev-sans-first:5.10 12:31:01 INFO - PROCESS | 1651 | Values: 86.0 86.0 83.0 85.0 85.0 85.0 84.0 94.0 98.0 89.0 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:140.20 Median:136.00 stddev:13.10 (9.6%) stddev-sans-first:13.87 12:31:01 INFO - PROCESS | 1651 | Values: 138.0 135.0 136.0 134.0 136.0 134.0 135.0 141.0 177.0 136.0 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.20 Median:163.50 stddev:17.86 (10.9%) stddev-sans-first:18.85 12:31:01 INFO - PROCESS | 1651 | Values: 164.0 162.0 160.0 163.0 165.0 161.0 219.0 173.0 162.0 163.0 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.30 Median:64.50 stddev:1.64 (2.5%) stddev-sans-first:1.62 12:31:01 INFO - PROCESS | 1651 | Values: 66.0 67.0 63.0 64.0 65.0 64.0 64.0 64.0 65.0 61.0 12:31:01 INFO - PROCESS | 1651 | -------- Summary: end -------- 12:31:01 INFO - PROCESS | 1651 | 12:31:01 INFO - TEST-INFO | 1651: exit 0 12:31:01 INFO - TEST-OK | kraken | took 59103ms 12:31:01 INFO - SUITE-END | took 1177s 12:31:02 INFO - Completed test suite (00:19:38) 12:31:02 INFO - javascript benchmark 12:31:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7651.136808220298, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3410.369081719882, 3417.29945361201, 3450.999999999999, 3465.6187819339852, 3483.216276337823, 5920.348761636222, 5949.553783466246, 5961.493273071783, 5973.40265973403, 6036.0, 5910.438508341701, 5935.258092738408, 5961.105834124884, 5982.700864956746, 5986.461218490335, 5342.519173274414, 5369.9463005370035, 5385.111456609655, 5424.620276580641, 5463.972680136613, 5301.5228629423345, 5303.893922121555, 5315.53822698759, 5461.0, 5475.0, 5395.406505284415, 5398.784048638069, 5404.243405923174, 5409.756560954746, 5424.403315635277, 5419.783208671648, 5425.9728701356435, 5435.9943410468795, 5445.156000819879, 5462.235287059815, 5214.270002199695, 5296.443873393316, 5364.812231571916, 5374.435684253175, 5427.945720542783, 5745.080787074048, 5770.451807078362, 5776.740046697909, 5778.3066032076, 5783.681897495636, 5282.418933917266, 6292.244930608356, 6367.936320636781, 6370.777022804226, 6371.553991220617, 5249.737513124367, 6220.440160385542, 6353.396427339441, 6367.07677386781, 6369.585976911486, 6072.635641861459, 6118.741008805849, 6119.045604113885, 6140.1799587324185, 6144.569880108439, 6442.0, 6442.420182183626, 6443.871122577616, 6445.581037232565, 6484.643344615997, 6159.045347971071, 6214.347493513206, 6397.200349956255, 6407.871842563122, 6437.356264373525, 5635.492805647429, 6311.147995020613, 6311.431971122529, 6344.873102537924, 6350.555461117678, 6313.0, 6322.209723784527, 6327.968360158262, 6337.081123237105, 6338.683065846689, 6267.310595834455, 6277.780277690259, 6313.210848643919, 6319.526035547352, 6346.30190679025, 4740.881477963078, 4874.025194961022, 4882.829100981448, 4890.584300334512, 4907.895723462192, 4409.691321607456, 4424.690271680952, 4429.269170586882, 4435.689501734848, 4443.200223959719, 5609.719514024282, 5658.236138121383, 5692.459216374438, 5819.709014549256, 5836.4747172753805, 6289.339619339995, 6315.400036996478, 6346.619202847844, 6370.426661600478, 6375.171227740457, 5735.225744524436, 5767.971160144172, 5779.162021506942, 5782.768689252467, 5809.564282678816, 5482.424345443751, 5509.063459211943, 5517.475839795213, 5541.612087153861, 5544.002079625706, 6252.718627661766, 6292.370762923671, 6323.462505687068, 6334.334894835976, 6338.176037115146, 6313.684315784284, 6333.20834895638, 6355.428011478989, 6360.681965901594, 6374.521910856612, 6423.0, 6488.091667166506, 6493.253275873146, 6493.740250389931, 6499.1876015498065, 6355.396237357444, 6356.2054743157105, 6367.777127800505, 6369.936300636934, 6396.904046439214, 6280.0, 6302.432781049728, 6325.335839736761, 6350.68246587678, 6354.015127655221, 5421.810236641856, 6285.308616052322, 6348.269948955929, 6392.488600912009, 6399.64801935898, 6263.467605253604, 6367.235931688227, 6376.553641245254, 6393.2647745508, 6393.648349340645, 6007.579469437097, 6036.758529658764, 6192.845178870564, 6273.843153921189, 6274.309825919054, 6303.36966303366, 6389.39300766445, 6391.392817682313, 6397.680115994089, 6417.326180750954, 4307.461567304087, 6019.51843852482, 6048.032314829607, 6087.634741915499, 6179.0], "value": 5815.721087370727, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [803.2650125135501, 823.9217274358936, 858.8153546987396, 872.149654087265, 872.2411501993266, 40608.59391406085, 41252.38121428177, 41318.0, 41508.79245603775, 43312.0, 42645.573544264626, 42821.0, 42843.78578107105, 42897.78551107248, 42943.0, 7488.925110748904, 7629.732959346424, 9528.523573821298, 9686.66096686616, 11520.308781473139, 11621.0, 11678.883211167908, 11695.824562631567, 11703.0, 11709.648710538655, 11682.474288656987, 11685.70785730355, 11693.123015773852, 11707.473163707653, 11709.707257318592, 11649.767004659901, 11684.298942063504, 11697.883021169764, 11708.238964467282, 11739.06087512997, 11455.942720286428, 11526.077913766962, 11532.538698452052, 11549.422528873523, 11557.479913403833, 40521.18957620831, 40837.979050523674, 40849.59150408488, 40872.773816785244, 40975.36098556054, 3557.5019497270405, 3740.4389341598708, 3847.3844184930276, 3886.7667939923695, 3945.250402423559, 28679.0, 29148.0, 30069.0, 30115.849420752977, 30404.391912161853, 2580.600006998918, 3121.7658675599196, 3198.3512836010286, 3232.773705840592, 3307.6361600224195, 341467.5853241461, 343348.0, 344192.2790386032, 345116.0, 346491.0, 43970.34044489335, 44363.0, 46617.533824661994, 53535.732321338146, 53824.0, 46514.30228546575, 46539.0, 46621.533784662395, 60205.0, 61253.01855379386, 15227.543173704807, 15409.9229503854, 15411.383544658089, 15412.922935385475, 15427.922860385626, 12824.615261542169, 12895.935520322526, 12930.159539629894, 12932.806007909889, 12948.417321220568, 12763.91506721939, 13643.0, 13663.043586948817, 13755.449782008607, 13864.098833575785, 5162.380514338303, 5571.693556854331, 6081.930885787912, 6143.293521245025, 6149.292831324366, 1090.7654854206226, 1156.6067537037447, 1167.2529581068122, 1177.9941100294616, 1233.4202924625413, 22153.44616384571, 23529.7647023531, 27238.45523089527, 27490.725092748817, 28164.87340506357, 1141.4749215361028, 1215.9452824622913, 1230.1266101068343, 1237.8762123787549, 1242.7203879127123, 13348.532801351907, 13917.914359744995, 14314.355853986595, 14421.134731916118, 14515.491957781427, 496942.0, 500634.0, 501175.0, 501822.0, 503154.48422758386, 59968.0, 60016.69991650014, 60105.0, 60127.0, 60157.69921150366, 1442.6393401649589, 1471.065873170523, 1472.749632562467, 1515.9242037898061, 1529.3118096856458, 27108.864455677594, 27719.0, 28116.0, 28256.29359266035, 28263.576046359733, 38388.0, 38522.422163668125, 38547.80726096351, 38609.0, 38678.419823702105, 463131.0, 466142.0, 466626.0, 466738.0, 468162.31837681186, 24657.383565411008, 24824.503509930062, 25210.0, 25218.117365892107, 30114.945976890704, 32073.839630801696, 36958.8152059238, 37451.43822842605, 39387.40918886162, 39442.60557394504, 26209.73790262073, 27402.040928567403, 27795.58306625443, 27925.16224513268, 28841.42317153687, 23955.401114972265, 28711.569326459703, 28751.28121796888, 29106.7089329104, 29244.56133158047, 31949.041528754173, 32589.348213035133, 32971.17572060718, 33199.66800331966, 33386.833065834515, 28127.87488500437, 28290.86836526516, 28321.57517637196, 28353.574696379983, 29279.560806588343, 22142.0, 22720.636761794347, 22913.65629515592, 23086.653700194176, 23300.43695193561], "value": 18903.499154712434, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [377.98299076541554, 412.45555866256535, 420.12194513466886, 425.160308390928, 457.0014518277564, 19701.21195152194, 20655.483612909684, 21733.565328693418, 21902.90485475728, 21932.890335548338, 14140.363683634236, 15423.0, 21440.785592144075, 21661.45846353842, 23295.534089318204, 6759.222689390709, 6869.416099631525, 7050.224475307712, 7078.752243671473, 7093.71625134994, 6769.695363708645, 6920.238773734885, 6937.9306206938045, 7015.82460438488, 7016.8772996320595, 6703.597784132943, 6883.208431030421, 6968.756093536727, 7028.226895041567, 7039.401650859683, 6724.361185687352, 6881.277465866087, 6933.72265109395, 6938.895916561256, 6938.930610693879, 6781.593104413701, 6849.965750171267, 6853.588784672936, 6866.588004719732, 6868.381845633917, 26913.0, 27137.0, 27218.0, 27240.72759272402, 27476.313092172855, 3069.309405383793, 3074.13924101252, 3075.4310452566124, 3080.3993221321853, 3086.4598695228246, 16124.355025798954, 17326.74009889853, 17348.392806251846, 17466.912665436717, 17995.550111247194, 1871.1018711018787, 2026.6149431607948, 2031.6139933412753, 2053.917843286267, 2059.06312627754, 128513.0536857902, 129576.3521182388, 136127.31936340255, 138627.0, 139681.3015934924, 17861.553461163574, 20714.792852071587, 21051.78948210498, 21706.13175472995, 21966.011529481213, 30163.190208587115, 36419.725309614034, 36781.26437471236, 36815.4477682835, 36819.263614727555, 9059.954700226457, 9638.759031024281, 9704.980976997454, 10188.184945204513, 11116.722081947853, 8774.298056155492, 9063.456192628331, 9080.182783549511, 9247.491387973592, 9276.907230927603, 9172.587233574504, 10737.463126843782, 10892.37732989701, 10945.0, 10956.506957186946, 3304.067120734397, 4068.2880495913096, 4072.8167232474016, 4168.499780026416, 4183.790810459464, 942.1614762861059, 947.545178314413, 950.1828427552299, 988.5600907596195, 989.1641562879355, 21976.89011554932, 23423.7657623425, 23453.88273058624, 23507.412314691926, 23565.88217058938, 831.6340810043562, 967.4969016111583, 967.9177269931994, 987.4667679453015, 1008.1682611845257, 11783.057355411547, 12311.876881231252, 12460.0, 12586.937065314614, 12640.05199610033, 742446.0, 756036.0, 766903.0, 777477.0, 783938.0, 114075.85924140818, 122630.77369226373, 124711.0, 125253.37373313075, 130600.0, 1270.8919741821865, 1271.3007845684836, 1304.2370213425252, 1304.8890844278153, 1312.8424589049375, 19717.0, 19729.013549322764, 20290.695639565714, 20336.898315508326, 20346.89826550917, 54589.36231913049, 55549.44450555443, 55922.72038639781, 56313.0, 57285.42714572801, 76248.0, 84459.5777021111, 87569.0, 92929.5353523255, 96381.51809240908, 10891.292066016, 11377.658670239905, 11387.829182562102, 11466.31202127875, 11815.232009919328, 12621.373763797383, 12684.365781711062, 12957.0, 13062.934685326893, 13098.607041788762, 11025.503852326505, 11054.834177487182, 11059.72350691239, 11299.09607231403, 11423.714407139889, 9992.200623950212, 10165.491725413553, 10230.897691022994, 10232.488375581042, 10547.736306592396, 11153.275037122561, 11333.376664283544, 11366.204365694322, 11496.82754758696, 11632.069434445393, 11439.942800285946, 11460.19778615489, 11585.942070289595, 11682.94158529236, 11811.232269902428, 10887.727806804893, 11322.773544529227, 11335.206535542762, 11342.376169310766, 11438.82841757391], "value": 11628.961602643638, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1447.7466443372407, 1614.321984766398, 1627.8860479766413, 1650.0347296831349, 1654.5946243170424, 10044.547995340208, 11275.661730148098, 11284.153688473376, 11298.943505282483, 11324.980751732342, 10872.565097396115, 10980.670579882597, 11186.161037922147, 11314.208005439601, 11413.42932853356, 2312.5143719818857, 2346.85918844869, 2348.3542025942834, 2350.811935045192, 2355.458244603742, 2248.5615305015526, 2329.452578644017, 2341.086976079322, 2343.60158773009, 2355.340504658698, 1971.3987233893656, 2286.039863257436, 2326.348622385735, 2341.484873327865, 2343.9179628713, 2253.3352660965056, 2311.06401907227, 2315.3169814904645, 2315.9305220843403, 2326.5579539887453, 2311.751654106797, 2316.745158032615, 2318.1538738360546, 2324.5815753164593, 2326.8603883767028, 11145.996860282614, 11223.38271395073, 11226.66320010401, 11229.3262404256, 11245.269057511236, 5379.193121031837, 5527.170924361338, 5540.833774986757, 5556.555475561945, 5572.86067848303, 3186.8406579671155, 3275.9344813103844, 3457.6023757267985, 3460.74044446665, 3467.0, 1151.9020883224937, 1173.501261963662, 1742.9651406971916, 1799.478151336111, 1831.9542011449687, 13816.654583635389, 14124.646883827883, 14744.483943061941, 15230.847691523053, 15649.765253521206, 4315.438992930962, 4438.4451943507065, 4477.619402350837, 4481.014176881081, 4487.820487180476, 11878.227915185658, 11884.762304753856, 11886.40567971598, 11910.880891191151, 11915.98714109293, 3960.683145348365, 4811.0, 4839.128956787742, 4855.490173531799, 5509.669419834822, 4305.311150216013, 4985.975070124626, 5040.873978150502, 5078.08594453002, 5124.103281925648, 2876.6548014238424, 3100.0, 3634.3639863023864, 3651.726120540917, 3693.9445908311395, 927.6614035876856, 928.9024652411534, 935.4948327902993, 941.4774799986074, 942.9151376376159, 943.1747221181466, 968.3463662028103, 977.9706608801747, 991.8660980767648, 995.5320999130397, 6547.607143571401, 6645.900311495331, 6676.132102826601, 6765.458763298924, 6804.829879253059, 975.6536429567543, 999.9550020249106, 1016.1870503597092, 1033.235405799703, 1040.8178568750438, 4127.628513433805, 4254.149719687816, 4292.18448494785, 4362.454693163355, 4449.354843547732, 12165.513379464723, 12795.808062879065, 12972.870271297355, 13546.796798048037, 13560.796588051187, 13471.730565388638, 13478.595642130751, 13481.595552133256, 13500.054996150371, 13521.850642695284, 8912.509811960404, 10151.847722284172, 10239.180865530889, 10246.924072972379, 10273.434961077062, 6906.447484201352, 6924.515283930076, 6984.511084224056, 7070.540414873017, 7163.391111755559, 12278.0, 12326.383680816103, 12357.505699771908, 12469.438875250455, 12488.188267762567, 10171.796564068825, 10804.24370294072, 12352.75294494123, 12587.74824503523, 12631.11582189238, 9170.220531254652, 9372.109649583279, 9429.669961551586, 9431.339806213773, 9501.477418741759, 10224.795504090025, 10348.0, 10411.739706507398, 10413.062824345845, 10415.114715248985, 7403.407727381689, 8947.507887066275, 8987.325950553604, 9016.639334426549, 9060.456372617664, 8744.562771861509, 8783.429077109968, 8807.691730789391, 8816.603252853509, 8851.60167792438, 7940.6029698513685, 7968.561729104955, 8065.112837587978, 8079.959600201962, 8122.268995790408, 4898.387701537308, 5535.861603459945, 5621.55027597783, 5726.341470730753, 5766.308043034863, 5915.290165180034, 5952.255968003999, 6014.458698717136, 6031.0, 6157.291911430243], "value": 5070.482023140003, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.71685801932506, 373.7402505258846, 383.79083399547244, 386.74308497383487, 389.101176282787, 4352.956470435299, 4370.956290437095, 4564.726116433017, 5092.541671249587, 5094.235864620309, 4101.733387329821, 4595.5634214749625, 4805.927911081337, 4892.727890748404, 4909.410870695513, 3478.669526394988, 3700.463432802242, 3741.981290093546, 3959.46547216125, 4246.554111818261, 4241.915161696764, 4259.78701064947, 4276.8024953013055, 4334.588214119669, 4338.414314067608, 3640.7451478396524, 3924.607539246081, 3951.46655201547, 4146.688998325122, 4271.359296105594, 3799.25914446683, 3887.7839631410106, 4122.835086596518, 4176.749395036308, 4181.91636167278, 3109.486934655779, 3511.6806328216435, 3835.7314987950854, 3849.2301539692176, 3946.782926939017, 4103.876883693494, 4718.200555963138, 4935.827246046407, 4936.679115857458, 5005.3721796057, 4308.9963167127125, 4553.4535855697195, 4853.247746599282, 4900.0, 4908.582770464514, 4473.642108631302, 4539.455265368144, 4541.318802179667, 4916.950830491685, 5309.33633295838, 3935.622532113766, 3991.962089856629, 4086.509618845728, 4412.294032954712, 4516.7578915798085, 4856.4172299323955, 5204.9739751301, 5488.121900495941, 5596.664200148004, 5732.799352022701, 4342.934855977162, 4369.737815731066, 4484.798184081724, 4488.730676159375, 4497.370368148462, 4521.61566266871, 4552.0, 4568.383268258809, 4571.611413029929, 4578.427696537933, 4134.421181034598, 4414.536473670219, 4695.600873925686, 4761.547652972962, 4817.0, 4845.53967373099, 5414.88994756074, 5425.755840987165, 5440.755166017539, 5461.481159289861, 1384.6746014686541, 1519.3011214841076, 1553.1923399832024, 1564.428983421043, 1564.968700625981, 1058.5342449322275, 1094.5621751299384, 1153.794284972206, 1154.7286387698884, 1176.758764453285, 4584.312353147055, 4615.261558150747, 4879.316895634614, 4894.461609222983, 4905.705657660552, 4969.627277954168, 5282.418933917266, 5586.804461843816, 5632.774689012475, 5634.703698336519, 4302.9887976325535, 4310.504292006397, 4393.538678438718, 4556.223073001532, 4907.877303067452, 2602.518534071203, 2662.8269162504375, 2908.8836446542323, 2912.189736903898, 2916.110586271165, 5191.8182863599595, 5713.74288157034, 5791.623544469596, 5834.649921004753, 5836.854078648068, 4679.571696831659, 4775.785089670973, 4919.77860996256, 5367.810879552094, 5579.804706835242, 5541.226807421667, 5558.722063896789, 5560.471755183251, 5565.30433695788, 5569.805056822992, 5002.149634562061, 5181.341970569381, 5236.0, 5313.707746073848, 5486.835394938158, 5011.09356573194, 5070.391553013662, 5126.872088140603, 5194.584433245256, 5448.972755136199, 5712.285964254468, 5732.856678583069, 5780.508656764054, 5841.211436456024, 5860.2967643883, 3858.9614103858603, 3949.111449923744, 4147.854825081107, 4342.674299427493, 4411.750007496136, 5050.84847454577, 5149.433562308218, 5430.596556426223, 5510.925369552899, 5521.702399935932, 4903.8528844134735, 4943.085529177114, 5028.21498368074, 5064.46823083571, 5435.412627665722, 4843.806247750051, 4923.384576927884, 5108.565771909281, 5384.273123128328, 5467.617266791286, 4575.444348921383, 4827.606438112357, 5124.692518448905, 5202.76281199088, 5223.268742375994, 4333.674974376871, 4801.4958429364415, 5301.0579128584, 5305.946940530545, 5343.946560534345, 4743.600637811923, 4979.925301120558, 5055.014272216882, 5222.033923724123, 5297.390800058042], "value": 4079.8559420373253, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.165542076006, 762.6415584675201, 764.7170546897648, 768.6848392159214, 789.218673513222, 15423.768643470357, 15922.0, 16323.999999999985, 16328.0, 16564.0, 13132.73734525309, 14424.134551926893, 15090.924545377258, 15224.314905829237, 15234.009789363712, 965.3146266151041, 998.1814911772314, 998.9350692204983, 999.8800143982733, 1000.2198285337448, 953.6233187890779, 978.8335982882927, 982.2976571751196, 983.990160098397, 985.2561316206254, 1034.632705389587, 1037.035556932053, 1037.3361048928691, 1037.7872536130112, 1037.8806437259698, 1031.0617338222257, 1031.8813336466328, 1033.5710680067764, 1033.607229252883, 1033.6485594897772, 773.9342155916697, 774.5585016540574, 776.0377132355894, 776.6543887969856, 777.5529070784265, 15947.44183953556, 16231.91884040584, 16242.862999590034, 16669.583260418465, 16891.577710557212, 14155.43378264868, 14259.500917467838, 14377.496787612488, 14387.0, 14409.279536023158, 11323.0, 11669.124815638776, 11712.0, 11751.353675547842, 12363.505459781598, 3711.9058307147598, 3741.158239396141, 3819.255245227154, 3868.5551161616213, 3907.1404291055924, 107277.46361268144, 108314.0, 111227.88772112256, 112801.87198128078, 114121.4293928525, 10289.228307876789, 10748.032677058945, 10932.234743567873, 10947.83578246327, 10977.945110274397, 22653.320400388016, 22818.771812281997, 22847.543049139258, 22886.313410597708, 22924.88537557335, 7844.921550784533, 7846.293833555007, 7988.600569971478, 7993.72021979228, 8013.959930200428, 4313.913721725611, 4331.371951067078, 4399.538048504861, 4562.794674239667, 4672.883177920579, 2870.799044066937, 3352.8826491072696, 3440.913977150541, 3452.223249768782, 3454.7927124372113, 607.1530215349562, 615.2616859768295, 615.4522474997256, 617.0589850477967, 617.9134921111048, 562.2016736234557, 565.7934853778423, 577.8353169346716, 624.5097598385337, 624.8531595075153, 9675.371100878421, 9957.900420995842, 10021.94951611291, 10061.0, 10857.408434656158, 560.3080195957989, 579.8405438504361, 608.6409018678912, 654.2410803467955, 751.7256201486418, 4960.280759289883, 5044.935353968855, 5097.8215762448135, 5098.1588037973315, 5102.872428189325, 71568.64215678992, 79909.80135297975, 91949.5402522983, 99098.0, 101249.49375253076, 55065.0, 56475.0, 59699.701501492214, 87993.0, 89685.0, 60644.39355606476, 68203.0, 74521.0, 74792.14470900211, 77889.61055194914, 9546.52267386614, 10631.096356809758, 11072.944635276772, 11192.048675862643, 11337.376444295644, 48507.0, 50353.244701328775, 50636.734081648254, 51547.742261288455, 52378.47621523736, 59099.38555837188, 59256.0, 60269.698651506464, 60406.697966509884, 60631.69684151551, 13591.45634174622, 13667.38496767628, 13827.0, 13828.585142445743, 13914.930425347808, 12084.274943503418, 14518.782218266946, 14795.482158124414, 15088.0, 15491.380344786083, 13407.396667149807, 13834.23911684867, 13892.791608126088, 14184.0, 14348.49780257686, 13112.729228715676, 13795.931020344835, 13797.51708690191, 13816.0, 13840.439518689074, 13522.25627590492, 13602.319884005958, 13699.178049317074, 13863.0, 14144.646383840487, 9138.268938484773, 9478.620855165991, 9701.369410988264, 9771.853422198816, 10010.249231307827, 11177.944110279723, 11330.773384532098, 11384.772304553697, 11475.655730328104, 11589.594364197214], "value": 7584.574963480726, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1430.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 115.0, 118.0, 162.0, 149.0, 114.0, 114.0, 113.0, 114.0], "value": 121.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 107.0, 111.0, 144.0, 110.0, 111.0, 112.0, 113.0, 155.0, 102.0], "value": 121.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 182.0, 181.0, 184.0, 184.0, 184.0, 184.0, 183.0, 185.0, 181.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 64.0, 82.0, 116.0, 87.0, 58.0, 58.0, 60.0, 75.0, 60.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 69.0, 86.0, 61.0, 54.0, 91.0, 114.0, 98.0, 55.0, 88.0], "value": 80.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 87.0, 87.0, 87.0, 87.0, 86.0, 87.0, 87.0, 86.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 96.0, 94.0, 94.0, 95.0, 94.0, 94.0, 93.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 83.0, 84.0, 84.0, 86.0, 85.0, 83.0, 84.0, 92.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 61.0, 61.0, 63.0, 63.0, 64.0, 65.0, 63.0, 63.0], "value": 62.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 64.0, 67.0, 61.0, 61.0, 58.0, 58.0, 59.0, 58.0, 59.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 83.0, 85.0, 85.0, 85.0, 84.0, 94.0, 98.0, 89.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 135.0, 136.0, 134.0, 136.0, 134.0, 135.0, 141.0, 177.0, 136.0], "value": 140.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 160.0, 163.0, 165.0, 161.0, 219.0, 173.0, 162.0, 163.0], "value": 169.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 63.0, 64.0, 65.0, 64.0, 64.0, 64.0, 65.0, 61.0], "value": 64.3, "unit": "score"}]}]} 12:31:02 INFO - Return code: 0 12:31:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:31:02 INFO - # TBPL SUCCESS # 12:31:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:31:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:31:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:31:02 INFO - 2017-06-08 12:31:02.395 screenresolution[1655:9697] starting screenresolution argv=screenresolution get 12:31:02 INFO - 2017-06-08 12:31:02.398 screenresolution[1655:9697] Display 0: 1600x1200x32@60 12:31:02 INFO - 2017-06-08 12:31:02.404 screenresolution[1656:9700] starting screenresolution argv=screenresolution list 12:31:02 INFO - Available Modes on Display 0 12:31:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:31:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:31:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:31:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:31:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:31:02 INFO - Intel Iris: 12:31:02 INFO - Chipset Model: Intel Iris 12:31:02 INFO - Type: GPU 12:31:02 INFO - Bus: Built-In 12:31:02 INFO - VRAM (Dynamic, Max): 1536 MB 12:31:02 INFO - Vendor: Intel (0x8086) 12:31:02 INFO - Device ID: 0x0a2e 12:31:02 INFO - Revision ID: 0x0009 12:31:02 INFO - Displays: 12:31:02 INFO - Display: 12:31:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:31:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:31:02 INFO - Display Serial Number: 1600x1200 60 12:31:02 INFO - Main Display: Yes 12:31:02 INFO - Mirror: Off 12:31:02 INFO - Online: Yes 12:31:02 INFO - Rotation: Supported 12:31:02 INFO - Return code: 0 12:31:02 INFO - Running post-action listener: _resource_record_post_action 12:31:02 INFO - [mozharness: 2017-06-08 19:31:02.638968Z] Finished run-tests step (success) 12:31:02 INFO - Running post-run listener: _resource_record_post_run 12:31:02 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 37868544; Write bytes: 510090240; Read time: 395; Write time: 1703 12:31:02 INFO - TinderboxPrint: CPU usage
53.8% 12:31:02 INFO - TinderboxPrint: I/O read bytes / time
37,868,544 / 395 12:31:02 INFO - TinderboxPrint: I/O write bytes / time
510,090,240 / 1,703 12:31:02 INFO - TinderboxPrint: CPU idle
2,214.9 (46.2%) 12:31:02 INFO - TinderboxPrint: CPU system
952.9 (19.9%) 12:31:02 INFO - TinderboxPrint: CPU user
1,629.9 (34.0%) 12:31:02 INFO - TinderboxPrint: Swap in / out
245,411,840 / 0 12:31:02 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 204327424; Write bytes: 203724800; Read time: 15617; Write time: 443 12:31:02 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35808256; Write bytes: 295506944; Read time: 371; Write time: 1223 12:31:03 INFO - Running post-run listener: _upload_blobber_files 12:31:03 INFO - Blob upload gear active. 12:31:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:31:03 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:31:03 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:31:03 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:31:03 INFO - (blobuploader) - INFO - Open directory for files ... 12:31:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:31:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:31:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:04 INFO - (blobuploader) - INFO - Done attempting. 12:31:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:31:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:31:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:05 INFO - (blobuploader) - INFO - Done attempting. 12:31:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:31:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:06 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:31:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:06 INFO - (blobuploader) - INFO - Done attempting. 12:31:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:31:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:31:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:08 INFO - (blobuploader) - INFO - Done attempting. 12:31:08 INFO - (blobuploader) - INFO - Iteration through files over. 12:31:08 INFO - Return code: 0 12:31:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:31:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:31:08 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2c1180cbd1dd1ea4dfc4e268194e5ed9f15b6f5289ce023c4ef6f1ea33de608bdc7fb25d97831f2174707673210d8471e9b808921c08fbfdb358677a1c4fb8e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/311ef2b68bf812989e339a8ad80526155c71378bce11c11c0297f4b115f8b99ca133e8cfce441503a16f9b748286d0da15355627cdaae35dfe4e4edc01df54fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88effcca4bf8ca63f9e1266e2912473f93a0930aafd83d5243f50630b8038e2813b71367611c08072b4defb82b6e2e8044f8b11080001dcd6d948ee33221c6b4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8e4bdeef0a22480c8e90376744509e2c2bed2cf9faf0cccbffb96d435691de337b4c442b5ce16570f0e6ff1f714d52c0ed4f29775889c230c524fa0f99b78010"} 12:31:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:31:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:31:08 INFO - Contents: 12:31:08 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/2c1180cbd1dd1ea4dfc4e268194e5ed9f15b6f5289ce023c4ef6f1ea33de608bdc7fb25d97831f2174707673210d8471e9b808921c08fbfdb358677a1c4fb8e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/311ef2b68bf812989e339a8ad80526155c71378bce11c11c0297f4b115f8b99ca133e8cfce441503a16f9b748286d0da15355627cdaae35dfe4e4edc01df54fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88effcca4bf8ca63f9e1266e2912473f93a0930aafd83d5243f50630b8038e2813b71367611c08072b4defb82b6e2e8044f8b11080001dcd6d948ee33221c6b4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8e4bdeef0a22480c8e90376744509e2c2bed2cf9faf0cccbffb96d435691de337b4c442b5ce16570f0e6ff1f714d52c0ed4f29775889c230c524fa0f99b78010"} 12:31:08 INFO - Running post-run listener: copy_logs_to_upload_dir 12:31:08 INFO - Copying logs to upload dir... 12:31:08 INFO - mkdir: /builds/slave/test/build/upload/logs 12:31:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.698829 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-06-08 12:31:08.699607) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 12:31:08.703514) ========= 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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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/2c1180cbd1dd1ea4dfc4e268194e5ed9f15b6f5289ce023c4ef6f1ea33de608bdc7fb25d97831f2174707673210d8471e9b808921c08fbfdb358677a1c4fb8e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/311ef2b68bf812989e339a8ad80526155c71378bce11c11c0297f4b115f8b99ca133e8cfce441503a16f9b748286d0da15355627cdaae35dfe4e4edc01df54fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88effcca4bf8ca63f9e1266e2912473f93a0930aafd83d5243f50630b8038e2813b71367611c08072b4defb82b6e2e8044f8b11080001dcd6d948ee33221c6b4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8e4bdeef0a22480c8e90376744509e2c2bed2cf9faf0cccbffb96d435691de337b4c442b5ce16570f0e6ff1f714d52c0ed4f29775889c230c524fa0f99b78010"} build_url:https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010636 build_url: 'https://queue.taskcluster.net/v1/task/QaUTSejQRqSbHHc0i5a2Pw/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/2c1180cbd1dd1ea4dfc4e268194e5ed9f15b6f5289ce023c4ef6f1ea33de608bdc7fb25d97831f2174707673210d8471e9b808921c08fbfdb358677a1c4fb8e1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/311ef2b68bf812989e339a8ad80526155c71378bce11c11c0297f4b115f8b99ca133e8cfce441503a16f9b748286d0da15355627cdaae35dfe4e4edc01df54fe", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/88effcca4bf8ca63f9e1266e2912473f93a0930aafd83d5243f50630b8038e2813b71367611c08072b4defb82b6e2e8044f8b11080001dcd6d948ee33221c6b4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8e4bdeef0a22480c8e90376744509e2c2bed2cf9faf0cccbffb96d435691de337b4c442b5ce16570f0e6ff1f714d52c0ed4f29775889c230c524fa0f99b78010"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-08 12:31:08.736487) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:31:08.736812) ========= 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.Cp5UUhvQ8o/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.KK6KxwstTr/Listeners TMPDIR=/var/folders/7n/1rclx_kx36b2p57w818tgqh000000w/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.005001 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-08 12:31:08.793012) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 12:31:08.793388) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-08 12:31:08.793839) ========= ========= Total master_lag: 2.98 =========