builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0101 starttime: 1496631303.3 results: success (0) buildid: 20170604183818 builduid: 22326c3f1cee41f490d7388aa11ebf83 revision: 09f9827c36d63365507fa3999e66c298eed81808 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-04 19:55:03.296461) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-04 19:55:03.297019) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-04 19:55:03.297339) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-04 19:55:03.532526) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:55:03.532848) ========= 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.xUAri7zf6H/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.iTKhxA83Nd/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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-04 19:55:03-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 153M=0s 2017-06-04 19:55:04 (153 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.517556 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:55:04.074388) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:55:04.074750) ========= 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.xUAri7zf6H/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.iTKhxA83Nd/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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.029763 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 19:55:04.126510) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-04 19:55:04.126904) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 09f9827c36d63365507fa3999e66c298eed81808 --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 09f9827c36d63365507fa3999e66c298eed81808 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xUAri7zf6H/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.iTKhxA83Nd/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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-04 19:55:04,232 truncating revision to first 12 chars 2017-06-04 19:55:04,232 Setting DEBUG logging. 2017-06-04 19:55:04,232 attempt 1/10 2017-06-04 19:55:04,232 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/09f9827c36d6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-04 19:55:05,343 unpacking tar archive at: mozilla-beta-09f9827c36d6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.398570 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-04 19:55:05.548347) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-04 19:55:05.548694) ========= script_repo_revision: 09f9827c36d63365507fa3999e66c298eed81808 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-04 19:55:05.549169) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-04 19:55:05.549432) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-04 19:55:05.568247) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-06-04 19:55:05.568532) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.xUAri7zf6H/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.iTKhxA83Nd/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 19:55:05 INFO - MultiFileLogger online at 20170604 19:55:05 in /builds/slave/test 19:55:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 19:55:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:55:05 INFO - {'append_to_log': False, 19:55:05 INFO - 'base_work_dir': '/builds/slave/test', 19:55:05 INFO - 'blob_upload_branch': 'Mozilla-Beta', 19:55:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:55:05 INFO - 'branch': 'Mozilla-Beta', 19:55:05 INFO - 'buildbot_json_path': 'buildprops.json', 19:55:05 INFO - 'config_files': ('talos/mac_config.py',), 19:55:05 INFO - 'default_actions': ('clobber', 19:55:05 INFO - 'read-buildbot-config', 19:55:05 INFO - 'download-and-extract', 19:55:05 INFO - 'populate-webroot', 19:55:05 INFO - 'create-virtualenv', 19:55:05 INFO - 'install', 19:55:05 INFO - 'run-tests'), 19:55:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:55:05 INFO - 'download_minidump_stackwalk': True, 19:55:05 INFO - 'download_symbols': 'ondemand', 19:55:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:55:05 INFO - 'tooltool.py': '/tools/tooltool.py', 19:55:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:55:05 INFO - '/tools/misc-python/virtualenv.py')}, 19:55:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:55:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:55:05 INFO - 'gecko_profile': False, 19:55:05 INFO - 'gecko_profile_interval': 0, 19:55:05 INFO - 'installer_path': 'installer.exe', 19:55:05 INFO - 'log_level': 'info', 19:55:05 INFO - 'log_name': 'talos', 19:55:05 INFO - 'log_to_console': True, 19:55:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:55:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:55:05 INFO - 'opt_config_files': (), 19:55:05 INFO - 'pip_index': False, 19:55:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:55:05 INFO - 'cmd': ('bash', 19:55:05 INFO - '-c', 19:55:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:55:05 INFO - 'enabled': True, 19:55:05 INFO - 'halt_on_failure': False, 19:55:05 INFO - 'name': 'check_screen_resolution'},), 19:55:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:55:05 INFO - 'cmd': ('bash', 19:55:05 INFO - '-c', 19:55:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:55:05 INFO - 'enabled': True, 19:55:05 INFO - 'halt_on_failure': False, 19:55:05 INFO - 'name': 'check_screen_resolution'},), 19:55:05 INFO - 'run_cmd_checks_enabled': True, 19:55:05 INFO - 'suite': 'dromaeojs-e10s', 19:55:05 INFO - 'system_bits': '32', 19:55:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:55:05 INFO - 'title': 't-yosemite-r7-0101', 19:55:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:55:05 INFO - 'use_talos_json': True, 19:55:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:55:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:55:05 INFO - 'work_dir': 'build'} 19:55:05 INFO - [mozharness: 2017-06-05 02:55:05.780996Z] Running clobber step. 19:55:05 INFO - Running pre-action listener: _resource_record_pre_action 19:55:05 INFO - Running main action method: clobber 19:55:05 INFO - rmtree: /builds/slave/test/build 19:55:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:55:08 INFO - Running post-action listener: _resource_record_post_action 19:55:08 INFO - [mozharness: 2017-06-05 02:55:08.300780Z] Finished clobber step (success) 19:55:08 INFO - [mozharness: 2017-06-05 02:55:08.300916Z] Running read-buildbot-config step. 19:55:08 INFO - Running pre-action listener: _resource_record_pre_action 19:55:08 INFO - Running main action method: read_buildbot_config 19:55:08 INFO - Using buildbot properties: 19:55:08 INFO - { 19:55:08 INFO - "project": "", 19:55:08 INFO - "product": "firefox", 19:55:08 INFO - "script_repo_revision": "production", 19:55:08 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 19:55:08 INFO - "repository": "", 19:55:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 19:55:08 INFO - "buildid": "20170604183818", 19:55:08 INFO - "pgo_build": "False", 19:55:08 INFO - "basedir": "/builds/slave/test", 19:55:08 INFO - "buildnumber": 6, 19:55:08 INFO - "slavename": "t-yosemite-r7-0101", 19:55:08 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 19:55:08 INFO - "platform": "yosemite_r7", 19:55:08 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 19:55:08 INFO - "branch": "mozilla-beta", 19:55:08 INFO - "repo_path": "releases/mozilla-beta", 19:55:08 INFO - "revision": "09f9827c36d63365507fa3999e66c298eed81808", 19:55:08 INFO - "stage_platform": "macosx64", 19:55:08 INFO - "builduid": "22326c3f1cee41f490d7388aa11ebf83", 19:55:08 INFO - "slavebuilddir": "test" 19:55:08 INFO - } 19:55:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 19:55:08 INFO - Running post-action listener: _resource_record_post_action 19:55:08 INFO - [mozharness: 2017-06-05 02:55:08.305887Z] Finished read-buildbot-config step (success) 19:55:08 INFO - [mozharness: 2017-06-05 02:55:08.306089Z] Running download-and-extract step. 19:55:08 INFO - Running pre-action listener: _resource_record_pre_action 19:55:08 INFO - Running main action method: download_and_extract 19:55:08 INFO - mkdir: /builds/slave/test/build/tests 19:55:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:55:08 INFO - trying https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 19:55:08 INFO - Downloading https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/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 19:55:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/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 19:55:08 INFO - Downloaded 1287 bytes. 19:55:08 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 19:55:08 INFO - Using the following test package requirements: 19:55:08 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:55:08 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 19:55:08 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 19:55:08 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:55:08 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 19:55:08 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:55:08 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 19:55:08 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 19:55:08 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:55:08 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 19:55:08 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 19:55:08 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:55:08 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 19:55:08 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:55:08 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 19:55:08 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:55:08 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 19:55:08 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 19:55:08 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 19:55:08 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 19:55:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 19:55:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 19:55:08 INFO - Fetch https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 19:55:10 INFO - Expected file size: 51393774 19:55:10 INFO - Obtained file size: 51393774 19:55:26 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 19:55:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 19:55:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 19:55:26 INFO - Fetch https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 19:55:28 INFO - Expected file size: 51393774 19:55:28 INFO - Obtained file size: 51393774 19:55:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 19:55:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 19:55:43 INFO - Fetch https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 19:55:45 INFO - Expected file size: 14404461 19:55:45 INFO - Obtained file size: 14404461 19:55: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')]} 19:55:45 INFO - trying https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:55:45 INFO - Downloading https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 19:55:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/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 19:55:47 INFO - Downloaded 59647984 bytes. 19:55:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:55:47 INFO - mkdir: /builds/slave/test/properties 19:55:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:55:47 INFO - Writing to file /builds/slave/test/properties/build_url 19:55:47 INFO - Contents: 19:55:47 INFO - build_url:https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.dmg 19:55:47 INFO - Running post-action listener: _resource_record_post_action 19:55:47 INFO - Running post-action listener: set_extra_try_arguments 19:55:47 INFO - [mozharness: 2017-06-05 02:55:47.778002Z] Finished download-and-extract step (success) 19:55:47 INFO - [mozharness: 2017-06-05 02:55:47.778171Z] Running populate-webroot step. 19:55:47 INFO - Running pre-action listener: _resource_record_pre_action 19:55:47 INFO - Running main action method: populate_webroot 19:55:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:55:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:55:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:55:47 INFO - '--tppagecycles', 19:55:47 INFO - '7'], 19:55:47 INFO - 'tests': ['tsvgm']}}, 19:55:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:55:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:55:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:55:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:55:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:55:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:55:47 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:55:47 INFO - 'talos_options': ['--disable-e10s'], 19:55:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:55:47 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:55:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:55:47 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:55:47 INFO - 'talos_options': ['--disable-e10s'], 19:55:47 INFO - 'tests': ['damp', 'tps']}, 19:55:47 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:55:47 INFO - 'tests': ['damp', 'tps']}, 19:55:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:55:47 INFO - 'tests': ['dromaeo_dom']}, 19:55:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:55:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:55:47 INFO - 'tests': ['basic_compositor_video']}, 19:55:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:55:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:55:47 INFO - 'tests': ['a11yr', 19:55:47 INFO - 'ts_paint', 19:55:47 INFO - 'tpaint', 19:55:47 INFO - 'sessionrestore', 19:55:47 INFO - 'sessionrestore_no_auto_restore', 19:55:47 INFO - 'tabpaint']}, 19:55:47 INFO - 'other-e10s': {'tests': ['a11yr', 19:55:47 INFO - 'ts_paint', 19:55:47 INFO - 'tpaint', 19:55:47 INFO - 'sessionrestore', 19:55:47 INFO - 'sessionrestore_no_auto_restore', 19:55:47 INFO - 'tabpaint']}, 19:55:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:55:47 INFO - 'ts_paint', 19:55:47 INFO - 'tpaint', 19:55:47 INFO - 'sessionrestore', 19:55:47 INFO - 'sessionrestore_no_auto_restore', 19:55:47 INFO - 'tabpaint']}, 19:55:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:55:47 INFO - 'ts_paint', 19:55:47 INFO - 'tpaint', 19:55:47 INFO - 'sessionrestore', 19:55:47 INFO - 'sessionrestore_no_auto_restore', 19:55:47 INFO - 'tabpaint']}, 19:55:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:55:47 INFO - 'tests': ['a11yr', 19:55:47 INFO - 'ts_paint', 19:55:47 INFO - 'tpaint', 19:55:47 INFO - 'sessionrestore', 19:55:47 INFO - 'sessionrestore_no_auto_restore', 19:55:47 INFO - 'tabpaint']}, 19:55:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:55:47 INFO - 'tests': ['a11yr', 19:55:47 INFO - 'ts_paint', 19:55:47 INFO - 'tpaint', 19:55:47 INFO - 'sessionrestore', 19:55:47 INFO - 'sessionrestore_no_auto_restore', 19:55:47 INFO - 'tabpaint']}, 19:55:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:55:47 INFO - 'tests': ['tsvgx', 19:55:47 INFO - 'tsvgr_opacity', 19:55:47 INFO - 'tart', 19:55:47 INFO - 'tscrollx', 19:55:47 INFO - 'cart', 19:55:47 INFO - 'tsvg_static']}, 19:55:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:55:47 INFO - 'tsvgr_opacity', 19:55:47 INFO - 'tart', 19:55:47 INFO - 'tscrollx', 19:55:47 INFO - 'cart', 19:55:47 INFO - 'tsvg_static']}, 19:55:47 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:55:47 INFO - 'talos_options': ['--disable-e10s'], 19:55:47 INFO - 'tests': ['tp5o']}, 19:55:47 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:55:47 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:55:47 INFO - 'talos_options': ['--disable-e10s', 19:55:47 INFO - '--xperf_path', 19:55:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:55:47 INFO - 'tests': ['tp5n']}, 19:55:47 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:55:47 INFO - 'talos_options': ['--xperf_path', 19:55:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:55:47 INFO - 'tests': ['tp5n']}}, 19:55:47 INFO - 'talos.zip': {'path': '', 19:55:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:55:47 INFO - Running post-action listener: _resource_record_post_action 19:55:47 INFO - [mozharness: 2017-06-05 02:55:47.798455Z] Finished populate-webroot step (success) 19:55:47 INFO - [mozharness: 2017-06-05 02:55:47.798574Z] Running create-virtualenv step. 19:55:47 INFO - Running pre-action listener: _resource_record_pre_action 19:55:47 INFO - Running main action method: create_virtualenv 19:55:47 INFO - Creating virtualenv /builds/slave/test/build/venv 19:55:47 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 19:55:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:55:47 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 19:55:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:55:47 INFO - Using real prefix '/tools/python27' 19:55:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:55:48 INFO - Installing distribute.............................................................................................................................................................................................done. 19:55:51 INFO - Installing pip.................done. 19:55:51 INFO - Return code: 0 19:55:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:55:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:55:51 INFO - Reading from file tmpfile_stdout 19:55:51 INFO - Output received: 19:55:51 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) 19:55:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:55:51 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')]} 19:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:51 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')]} 19:55:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:51 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 0x10b807b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b80a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd88cd6ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e5d30>, '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 0x10b50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dabe8>, '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.xUAri7zf6H/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.iTKhxA83Nd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 19:55:51 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 19:55:51 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 19:55:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xUAri7zf6H/Render', 19:55:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:51 INFO - 'HOME': '/Users/cltbld', 19:55:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:51 INFO - 'LOGNAME': 'cltbld', 19:55:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:51 INFO - 'MOZ_NO_REMOTE': '1', 19:55:51 INFO - 'NO_EM_RESTART': '1', 19:55:51 INFO - 'PAGER': '/bin/cat', 19:55:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:51 INFO - 'PWD': '/builds/slave/test', 19:55:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:51 INFO - 'SHELL': '/bin/bash', 19:55:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTKhxA83Nd/Listeners', 19:55:51 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 19:55:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:51 INFO - 'USER': 'cltbld', 19:55:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:51 INFO - 'XPC_FLAGS': '0x0', 19:55:51 INFO - 'XPC_SERVICE_NAME': '0', 19:55:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:51 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 19:55:51 INFO - Cleaning up... 19:55:52 INFO - Return code: 0 19:55:52 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:55:52 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')]} 19:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:52 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')]} 19:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:52 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 0x10b807b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b80a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd88cd6ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e5d30>, '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 0x10b50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dabe8>, '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.xUAri7zf6H/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.iTKhxA83Nd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 19:55:52 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 19:55:52 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 19:55:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xUAri7zf6H/Render', 19:55:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:52 INFO - 'HOME': '/Users/cltbld', 19:55:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:52 INFO - 'LOGNAME': 'cltbld', 19:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:52 INFO - 'MOZ_NO_REMOTE': '1', 19:55:52 INFO - 'NO_EM_RESTART': '1', 19:55:52 INFO - 'PAGER': '/bin/cat', 19:55:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:52 INFO - 'PWD': '/builds/slave/test', 19:55:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:52 INFO - 'SHELL': '/bin/bash', 19:55:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTKhxA83Nd/Listeners', 19:55:52 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 19:55:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:52 INFO - 'USER': 'cltbld', 19:55:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:52 INFO - 'XPC_FLAGS': '0x0', 19:55:52 INFO - 'XPC_SERVICE_NAME': '0', 19:55:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:52 INFO - Downloading/unpacking psutil>=3.1.1 19:55: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 19:55: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 19:55: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 19:55: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 19:55:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:55:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:55:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:55:54 INFO - Installing collected packages: psutil 19:55:54 INFO - Running setup.py install for psutil 19:55:54 INFO - building 'psutil._psutil_osx' extension 19:55:54 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 19:55:55 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 19:55:55 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 19:55:55 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 19:55:55 INFO - building 'psutil._psutil_posix' extension 19:55:55 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 19:55:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:55:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:55:55 INFO - ^ 19:55:55 INFO - 1 warning generated. 19:55:55 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 19:55:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:55:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:55:55 INFO - Successfully installed psutil 19:55:55 INFO - Cleaning up... 19:55:55 INFO - Return code: 0 19:55:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:55: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')]} 19:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55: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')]} 19:55:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:55 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 0x10b807b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b80a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd88cd6ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e5d30>, '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 0x10b50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dabe8>, '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.xUAri7zf6H/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.iTKhxA83Nd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 19:55:55 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 19:55:55 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 19:55:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xUAri7zf6H/Render', 19:55:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:55 INFO - 'HOME': '/Users/cltbld', 19:55:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:55 INFO - 'LOGNAME': 'cltbld', 19:55:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:55 INFO - 'MOZ_NO_REMOTE': '1', 19:55:55 INFO - 'NO_EM_RESTART': '1', 19:55:55 INFO - 'PAGER': '/bin/cat', 19:55:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:55 INFO - 'PWD': '/builds/slave/test', 19:55:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:55 INFO - 'SHELL': '/bin/bash', 19:55:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTKhxA83Nd/Listeners', 19:55:55 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 19:55:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:55 INFO - 'USER': 'cltbld', 19:55:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:55 INFO - 'XPC_FLAGS': '0x0', 19:55:55 INFO - 'XPC_SERVICE_NAME': '0', 19:55:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:55:56 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 19:55:56 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 19:55:56 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 19:55:56 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 19:55:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:55:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:55:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:55:57 INFO - Installing collected packages: mozsystemmonitor 19:55:57 INFO - Running setup.py install for mozsystemmonitor 19:55:57 INFO - Successfully installed mozsystemmonitor 19:55:57 INFO - Cleaning up... 19:55:57 INFO - Return code: 0 19:55:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:55:57 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')]} 19:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:57 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')]} 19:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:57 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 0x10b807b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b80a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd88cd6ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e5d30>, '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 0x10b50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dabe8>, '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.xUAri7zf6H/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.iTKhxA83Nd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 19:55:57 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 19:55:57 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 19:55:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xUAri7zf6H/Render', 19:55:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:57 INFO - 'HOME': '/Users/cltbld', 19:55:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:57 INFO - 'LOGNAME': 'cltbld', 19:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:57 INFO - 'MOZ_NO_REMOTE': '1', 19:55:57 INFO - 'NO_EM_RESTART': '1', 19:55:57 INFO - 'PAGER': '/bin/cat', 19:55:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:57 INFO - 'PWD': '/builds/slave/test', 19:55:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:57 INFO - 'SHELL': '/bin/bash', 19:55:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTKhxA83Nd/Listeners', 19:55:57 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 19:55:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:57 INFO - 'USER': 'cltbld', 19:55:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:57 INFO - 'XPC_FLAGS': '0x0', 19:55:57 INFO - 'XPC_SERVICE_NAME': '0', 19:55:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:55:57 INFO - Downloading/unpacking jsonschema==2.5.1 19:55:57 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 19:55:57 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 19:55:57 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 19:55:57 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 19:55:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:55:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:55:59 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 19:55:59 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 19:55:59 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 19:55:59 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 19:55:59 INFO - Downloading functools32-3.2.3-2.tar.gz 19:55:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:55:59 INFO - warning: no files found matching '*.txt' 19:55:59 INFO - no previously-included directories found matching 'build' 19:55:59 INFO - no previously-included directories found matching 'dist' 19:55:59 INFO - no previously-included directories found matching '.git*' 19:55:59 INFO - Installing collected packages: jsonschema, functools32 19:55:59 INFO - Running setup.py install for functools32 19:55:59 INFO - warning: no files found matching '*.txt' 19:55:59 INFO - no previously-included directories found matching 'build' 19:55:59 INFO - no previously-included directories found matching 'dist' 19:55:59 INFO - no previously-included directories found matching '.git*' 19:55:59 INFO - Successfully installed jsonschema functools32 19:55:59 INFO - Cleaning up... 19:55:59 INFO - Return code: 0 19:55:59 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:55:59 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')]} 19:55:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:55:59 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')]} 19:55:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:55:59 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 0x10b807b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b80a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd88cd6ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e5d30>, '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 0x10b50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dabe8>, '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.xUAri7zf6H/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.iTKhxA83Nd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 19:55:59 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 19:55:59 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 19:55:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xUAri7zf6H/Render', 19:55:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:55:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:55:59 INFO - 'HOME': '/Users/cltbld', 19:55:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:55:59 INFO - 'LOGNAME': 'cltbld', 19:55:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:55:59 INFO - 'MOZ_NO_REMOTE': '1', 19:55:59 INFO - 'NO_EM_RESTART': '1', 19:55:59 INFO - 'PAGER': '/bin/cat', 19:55:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:55:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:55:59 INFO - 'PWD': '/builds/slave/test', 19:55:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:55:59 INFO - 'SHELL': '/bin/bash', 19:55:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTKhxA83Nd/Listeners', 19:55:59 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 19:55:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:55:59 INFO - 'USER': 'cltbld', 19:55:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:55:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:55:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:55:59 INFO - 'XPC_FLAGS': '0x0', 19:55:59 INFO - 'XPC_SERVICE_NAME': '0', 19:55:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:56:00 INFO - Cleaning up... 19:56:00 INFO - Return code: 0 19:56:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:56:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:56:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:00 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 0x10b807b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b80a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd88cd6ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e5d30>, '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 0x10b50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dabe8>, '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.xUAri7zf6H/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.iTKhxA83Nd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 19:56:00 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 19:56:00 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 19:56:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xUAri7zf6H/Render', 19:56:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:00 INFO - 'HOME': '/Users/cltbld', 19:56:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:00 INFO - 'LOGNAME': 'cltbld', 19:56:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:00 INFO - 'MOZ_NO_REMOTE': '1', 19:56:00 INFO - 'NO_EM_RESTART': '1', 19:56:00 INFO - 'PAGER': '/bin/cat', 19:56:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:00 INFO - 'PWD': '/builds/slave/test', 19:56:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:00 INFO - 'SHELL': '/bin/bash', 19:56:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTKhxA83Nd/Listeners', 19:56:00 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 19:56:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:00 INFO - 'USER': 'cltbld', 19:56:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:00 INFO - 'XPC_FLAGS': '0x0', 19:56:00 INFO - 'XPC_SERVICE_NAME': '0', 19:56:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:00 INFO - Downloading/unpacking blobuploader==1.2.4 19:56: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 19:56: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 19:56: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 19:56: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 19:56:01 INFO - Downloading blobuploader-1.2.4.tar.gz 19:56:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:56:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:56:01 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 19:56:01 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 19:56:01 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 19:56:01 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 19:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:56:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:56:02 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 19:56:02 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 19:56:02 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 19:56:02 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 19:56:02 INFO - Downloading docopt-0.6.1.tar.gz 19:56:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:56:02 INFO - Installing collected packages: blobuploader, requests, docopt 19:56:02 INFO - Running setup.py install for blobuploader 19:56:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:56:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:56:02 INFO - Running setup.py install for requests 19:56:03 INFO - Running setup.py install for docopt 19:56:03 INFO - Successfully installed blobuploader requests docopt 19:56:03 INFO - Cleaning up... 19:56:03 INFO - Return code: 0 19:56:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:56:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:56:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:56:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:03 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 0x10b807b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b80a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd88cd6ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e5d30>, '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 0x10b50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dabe8>, '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.xUAri7zf6H/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.iTKhxA83Nd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 19:56:03 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 19:56:03 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 19:56:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xUAri7zf6H/Render', 19:56:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:03 INFO - 'HOME': '/Users/cltbld', 19:56:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:03 INFO - 'LOGNAME': 'cltbld', 19:56:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:03 INFO - 'MOZ_NO_REMOTE': '1', 19:56:03 INFO - 'NO_EM_RESTART': '1', 19:56:03 INFO - 'PAGER': '/bin/cat', 19:56:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:03 INFO - 'PWD': '/builds/slave/test', 19:56:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:03 INFO - 'SHELL': '/bin/bash', 19:56:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTKhxA83Nd/Listeners', 19:56:03 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 19:56:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:03 INFO - 'USER': 'cltbld', 19:56:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:03 INFO - 'XPC_FLAGS': '0x0', 19:56:03 INFO - 'XPC_SERVICE_NAME': '0', 19:56:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:56:03 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-xua97l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:56:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-PEjxAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:56:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-X0f0ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:56:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-F0Sb6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:56:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-VYmDYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:56:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-gHLf1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:56:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-CqeEyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:56:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-ilxo_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:56:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-XdZ57U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:56:04 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-tMxhEs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:56:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-iGVhe4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:56:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-POwsG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:56:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-jA53ea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:56:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-o8swWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-dR26yK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:56:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-eD3zJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:56:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:56:05 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-2E5yL0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:56:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:56:05 INFO - Running setup.py install for manifestparser 19:56:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:56:05 INFO - Running setup.py install for mozcrash 19:56:06 INFO - Running setup.py install for mozdebug 19:56:06 INFO - Running setup.py install for mozdevice 19:56:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:56:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:56:06 INFO - Running setup.py install for mozfile 19:56:06 INFO - Running setup.py install for mozhttpd 19:56:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:56:06 INFO - Running setup.py install for mozinfo 19:56:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:56:06 INFO - Running setup.py install for mozInstall 19:56:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:56:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:56:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:56:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:56:06 INFO - Running setup.py install for mozleak 19:56:07 INFO - Running setup.py install for mozlog 19:56:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:56:07 INFO - Running setup.py install for moznetwork 19:56:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:56:07 INFO - Running setup.py install for mozprocess 19:56:07 INFO - Running setup.py install for mozprofile 19:56:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:56:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:56:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:56:07 INFO - Running setup.py install for mozrunner 19:56:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:56:07 INFO - Running setup.py install for mozscreenshot 19:56:08 INFO - Running setup.py install for moztest 19:56:08 INFO - Running setup.py install for mozversion 19:56:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:56:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:56:08 INFO - Cleaning up... 19:56:08 INFO - Return code: 0 19:56:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:56:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:56:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:08 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 0x10b807b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b80a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd88cd6ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e5d30>, '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 0x10b50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dabe8>, '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.xUAri7zf6H/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.iTKhxA83Nd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 19:56:08 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 19:56:08 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 19:56:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xUAri7zf6H/Render', 19:56:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:08 INFO - 'HOME': '/Users/cltbld', 19:56:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:08 INFO - 'LOGNAME': 'cltbld', 19:56:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:08 INFO - 'MOZ_NO_REMOTE': '1', 19:56:08 INFO - 'NO_EM_RESTART': '1', 19:56:08 INFO - 'PAGER': '/bin/cat', 19:56:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:08 INFO - 'PWD': '/builds/slave/test', 19:56:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:08 INFO - 'SHELL': '/bin/bash', 19:56:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTKhxA83Nd/Listeners', 19:56:08 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 19:56:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:08 INFO - 'USER': 'cltbld', 19:56:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:08 INFO - 'XPC_FLAGS': '0x0', 19:56:08 INFO - 'XPC_SERVICE_NAME': '0', 19:56:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:56:08 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-zhxpqr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:56:08 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)) 19:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:56:08 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-oG_8hx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:56:08 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)) 19:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:56:08 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-D5YybY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:56:08 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)) 19:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:56:08 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-ZDqrm7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:56:09 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)) 19:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:56:09 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-oY5EQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:56:09 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)) 19:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:56:09 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-wG8LjE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:56:09 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)) 19:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:56:09 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-oFOTpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:56:09 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)) 19:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:56:09 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-DMeEhW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:56:09 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)) 19:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:56:09 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-LNEcnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:56:09 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)) 19:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:56:09 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-gEFVvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:56:09 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)) 19:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:56:09 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-B__cLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:56:09 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)) 19:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:56:09 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-FvvDDx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:56:09 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)) 19:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:56:09 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-OrG_IJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:56:09 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)) 19:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:56:09 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-nKGyi1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:56:10 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)) 19:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:10 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-uEbWo7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:56:10 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)) 19:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:56:10 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-0L3WQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:56:10 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)) 19:56:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:56:10 INFO - Running setup.py (path:/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/pip-s47w3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:56:10 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)) 19:56:10 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)) 19:56:10 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)) 19:56:10 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)) 19:56:10 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)) 19:56:10 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)) 19:56:10 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:56:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:56:11 INFO - Downloading blessings-1.6.tar.gz 19:56:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:56:11 INFO - Installing collected packages: blessings 19:56:11 INFO - Running setup.py install for blessings 19:56:12 INFO - Successfully installed blessings 19:56:12 INFO - Cleaning up... 19:56:12 INFO - Return code: 0 19:56:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:56:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:56:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:56:12 INFO - Reading from file tmpfile_stdout 19:56:12 INFO - Current package versions: 19:56:12 INFO - blessings == 1.6 19:56:12 INFO - blobuploader == 1.2.4 19:56:12 INFO - docopt == 0.6.1 19:56:12 INFO - functools32 == 3.2.3-2 19:56:12 INFO - jsonschema == 2.5.1 19:56:12 INFO - manifestparser == 1.1 19:56:12 INFO - mozInstall == 1.12 19:56:12 INFO - mozcrash == 1.0 19:56:12 INFO - mozdebug == 0.1 19:56:12 INFO - mozdevice == 0.49 19:56:12 INFO - mozfile == 1.2 19:56:12 INFO - mozhttpd == 0.7 19:56:12 INFO - mozinfo == 0.9 19:56:12 INFO - mozleak == 0.1 19:56:12 INFO - mozlog == 3.4 19:56:12 INFO - moznetwork == 0.27 19:56:12 INFO - mozprocess == 0.25 19:56:12 INFO - mozprofile == 0.28 19:56:12 INFO - mozrunner == 6.13 19:56:12 INFO - mozscreenshot == 0.1 19:56:12 INFO - mozsystemmonitor == 0.3 19:56:12 INFO - moztest == 0.8 19:56:12 INFO - mozversion == 1.4 19:56:12 INFO - psutil == 3.1.1 19:56:12 INFO - requests == 1.2.3 19:56:12 INFO - wsgiref == 0.1.2 19:56:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:56:12 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')]} 19:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:12 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')]} 19:56:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:12 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 0x10b807b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b80a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd88cd6ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e5d30>, '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 0x10b50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dabe8>, '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.xUAri7zf6H/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.iTKhxA83Nd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 19:56:12 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 19:56:12 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 19:56:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xUAri7zf6H/Render', 19:56:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:12 INFO - 'HOME': '/Users/cltbld', 19:56:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:12 INFO - 'LOGNAME': 'cltbld', 19:56:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:12 INFO - 'MOZ_NO_REMOTE': '1', 19:56:12 INFO - 'NO_EM_RESTART': '1', 19:56:12 INFO - 'PAGER': '/bin/cat', 19:56:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:12 INFO - 'PWD': '/builds/slave/test', 19:56:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:12 INFO - 'SHELL': '/bin/bash', 19:56:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTKhxA83Nd/Listeners', 19:56:12 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 19:56:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:12 INFO - 'USER': 'cltbld', 19:56:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:12 INFO - 'XPC_FLAGS': '0x0', 19:56:12 INFO - 'XPC_SERVICE_NAME': '0', 19:56:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:12 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)) 19:56:12 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)) 19:56:12 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)) 19:56:12 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)) 19:56:12 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)) 19:56:12 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)) 19:56:12 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)) 19:56:12 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)) 19:56:12 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)) 19:56:12 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)) 19:56:12 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)) 19:56:12 INFO - Cleaning up... 19:56:12 INFO - Return code: 0 19:56:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:56:12 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')]} 19:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:56:12 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')]} 19:56:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:56:12 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 0x10b807b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b80a768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd88cd6ad0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b5e5d30>, '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 0x10b50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dabe8>, '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.xUAri7zf6H/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.iTKhxA83Nd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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 19:56:12 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 19:56:12 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 19:56:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xUAri7zf6H/Render', 19:56:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:12 INFO - 'HOME': '/Users/cltbld', 19:56:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:12 INFO - 'LOGNAME': 'cltbld', 19:56:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:12 INFO - 'MOZ_NO_REMOTE': '1', 19:56:12 INFO - 'NO_EM_RESTART': '1', 19:56:12 INFO - 'PAGER': '/bin/cat', 19:56:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:12 INFO - 'PWD': '/builds/slave/test', 19:56:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:12 INFO - 'SHELL': '/bin/bash', 19:56:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTKhxA83Nd/Listeners', 19:56:12 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 19:56:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:12 INFO - 'USER': 'cltbld', 19:56:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:12 INFO - 'XPC_FLAGS': '0x0', 19:56:12 INFO - 'XPC_SERVICE_NAME': '0', 19:56:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:56:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:56:12 INFO - Cleaning up... 19:56:12 INFO - Return code: 0 19:56:12 INFO - Running post-action listener: _resource_record_post_action 19:56:12 INFO - Running post-action listener: _start_resource_monitoring 19:56:12 INFO - Starting resource monitoring. 19:56:12 INFO - [mozharness: 2017-06-05 02:56:12.907266Z] Finished create-virtualenv step (success) 19:56:12 INFO - [mozharness: 2017-06-05 02:56:12.908222Z] Running install step. 19:56:12 INFO - Running pre-action listener: _resource_record_pre_action 19:56:12 INFO - Running main action method: install 19:56:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:56:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:56:13 INFO - Reading from file tmpfile_stdout 19:56:13 INFO - Detecting whether we're running mozinstall >=1.0... 19:56:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:56:13 INFO - Reading from file tmpfile_stdout 19:56:13 INFO - Output received: 19:56:13 INFO - Usage: mozinstall [options] installer 19:56:13 INFO - Options: 19:56:13 INFO - -h, --help show this help message and exit 19:56:13 INFO - -d DEST, --destination=DEST 19:56:13 INFO - Directory to install application into. [default: 19:56:13 INFO - "/builds/slave/test"] 19:56:13 INFO - --app=APP Application being installed. [default: firefox] 19:56:13 INFO - mkdir: /builds/slave/test/build/application 19:56:13 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'] 19:56:13 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 19:56:28 INFO - Reading from file tmpfile_stdout 19:56:28 INFO - Output received: 19:56:28 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 19:56:28 INFO - Running post-action listener: _resource_record_post_action 19:56:28 INFO - [mozharness: 2017-06-05 02:56:28.197571Z] Finished install step (success) 19:56:28 INFO - [mozharness: 2017-06-05 02:56:28.197732Z] Running run-tests step. 19:56:28 INFO - Running pre-action listener: _resource_record_pre_action 19:56:28 INFO - Running main action method: run_tests 19:56:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:56:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:56:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:56:28 INFO - 2017-06-04 19:56:28.212 screenresolution[1626:7892] starting screenresolution argv=screenresolution get 19:56:28 INFO - 2017-06-04 19:56:28.214 screenresolution[1626:7892] Display 0: 1600x1200x32@60 19:56:28 INFO - 2017-06-04 19:56:28.220 screenresolution[1627:7894] starting screenresolution argv=screenresolution list 19:56:28 INFO - Available Modes on Display 0 19:56:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:56:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:56:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:56:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:56:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:56:28 INFO - Intel Iris: 19:56:28 INFO - Chipset Model: Intel Iris 19:56:28 INFO - Type: GPU 19:56:28 INFO - Bus: Built-In 19:56:28 INFO - VRAM (Dynamic, Max): 1536 MB 19:56:28 INFO - Vendor: Intel (0x8086) 19:56:28 INFO - Device ID: 0x0a2e 19:56:28 INFO - Revision ID: 0x0009 19:56:28 INFO - Displays: 19:56:28 INFO - Display: 19:56:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:56:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:56:28 INFO - Display Serial Number: 1600x1200 60 19:56:28 INFO - Main Display: Yes 19:56:28 INFO - Mirror: Off 19:56:28 INFO - Online: Yes 19:56:28 INFO - Rotation: Supported 19:56:28 INFO - Return code: 0 19:56:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:56:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:56:28 INFO - Python 2.7.3 19:56:28 INFO - Return code: 0 19:56:28 INFO - grabbing minidump binary from tooltool 19:56:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:56:28 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 0x10b5e5d30>, '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 0x10b50cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b5dabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:56:28 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 19:56:28 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 19:56:28 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 19:56:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:56:28 INFO - Return code: 0 19:56:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:56:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:56:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:56:28 INFO - ENV: RUST_BACKTRACE is now 1 19:56:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:56:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:56:28 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-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0101', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:56:28 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-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0101 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:56:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xUAri7zf6H/Render', 19:56:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:56:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:56:28 INFO - 'HOME': '/Users/cltbld', 19:56:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:56:28 INFO - 'LOGNAME': 'cltbld', 19:56:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:56:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:56:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:56:28 INFO - 'MOZ_NO_REMOTE': '1', 19:56:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:56:28 INFO - 'NO_EM_RESTART': '1', 19:56:28 INFO - 'PAGER': '/bin/cat', 19:56:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:56:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:56:28 INFO - 'PWD': '/builds/slave/test', 19:56:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:56:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:56:28 INFO - 'RUST_BACKTRACE': '1', 19:56:28 INFO - 'SHELL': '/bin/bash', 19:56:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTKhxA83Nd/Listeners', 19:56:28 INFO - 'TMPDIR': '/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/', 19:56:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:56:28 INFO - 'USER': 'cltbld', 19:56:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:56:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:56:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:56:28 INFO - 'XPC_FLAGS': '0x0', 19:56:28 INFO - 'XPC_SERVICE_NAME': '0', 19:56:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:56:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0101', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:56:28 INFO - mozversion application_buildid: 20170604183818 19:56:28 INFO - mozversion application_changeset: 09f9827c36d63365507fa3999e66c298eed81808 19:56:28 INFO - mozversion application_display_name: Firefox 19:56:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:56:28 INFO - mozversion application_name: Firefox 19:56:28 INFO - mozversion application_remotingname: firefox 19:56:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 19:56:28 INFO - mozversion application_vendor: Mozilla 19:56:28 INFO - mozversion application_version: 54.0 19:56:28 INFO - mozversion platform_buildid: 20170604183818 19:56:28 INFO - mozversion platform_changeset: 09f9827c36d63365507fa3999e66c298eed81808 19:56:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 19:56:28 INFO - mozversion platform_version: 54.0 19:56:28 INFO - using testdate: 1496631388 19:56:28 INFO - actual date: 1496631388 19:56:28 INFO - starting webserver on 'localhost:49226' 19:56:28 INFO - SUITE-START | Running 2 tests 19:56:28 INFO - TEST-START | dromaeo_css 19:56:28 INFO - operating with platform_type : mac_ 19:56:28 INFO - Initialising browser for dromaeo_css test... 19:56:28 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmpsT94MI/profile http://localhost:49226/getInfo.html) 19:56:31 INFO - PROCESS | 1637 | 2017-06-04 19:56:31.568 plugin-container[1640:8068] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c43, name = 'com.apple.tsm.portname' 19:56:31 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:56:31 INFO - PROCESS | 1637 | 2017-06-04 19:56:31.569 plugin-container[1640:8068] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d07, name = 'com.apple.CFPasteboardClient' 19:56:31 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:56:31 INFO - PROCESS | 1637 | 2017-06-04 19:56:31.569 plugin-container[1640:8068] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:56:31 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 19:56:31 INFO - PROCESS | 1637 | colorDepth:24 19:56:31 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 19:56:31 INFO - PROCESS | 1637 | __metrics 19:56:31 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 19:56:32 INFO - TEST-INFO | 1637: exit 0 19:56:32 INFO - Browser initialized. 19:56:32 INFO - Running cycle 1/1 for dromaeo_css test... 19:56:32 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmpsT94MI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:56:34 INFO - PROCESS | 1641 | 2017-06-04 19:56:34.625 plugin-container[1642:8204] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 19:56:34 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:56:34 INFO - PROCESS | 1641 | 2017-06-04 19:56:34.626 plugin-container[1642:8204] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 19:56:34 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:56:34 INFO - PROCESS | 1641 | 2017-06-04 19:56:34.626 plugin-container[1642:8204] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:56:44 INFO - PROCESS | 1641 | RSS: Main: 174190592 19:56:44 INFO - PROCESS | 1641 | 19:59:47 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 19:59:48 INFO - PROCESS | 1641 | RSS: Main: 190025728 19:59:48 INFO - PROCESS | 1641 | 20:02:51 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 20:02:51 INFO - PROCESS | 1641 | RSS: Main: 200630272 20:02:51 INFO - PROCESS | 1641 | 20:05:53 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 20:05:54 INFO - PROCESS | 1641 | RSS: Main: 205770752 20:05:54 INFO - PROCESS | 1641 | 20:08:56 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 20:08:56 INFO - PROCESS | 1641 | RSS: Main: 208891904 20:08:56 INFO - PROCESS | 1641 | 20:11:59 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 20:12:00 INFO - PROCESS | 1641 | RSS: Main: 211324928 20:12:00 INFO - PROCESS | 1641 | 20:15:03 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 20:15:03 INFO - PROCESS | 1641 | RSS: Main: 209301504 20:15:03 INFO - PROCESS | 1641 | 20:15:03 INFO - PROCESS | 1641 | __start_tp_report 20:15:03 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 20:15:03 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 20:15:03 INFO - PROCESS | 1641 | |i|pagename|runs| 20:15:03 INFO - PROCESS | 1641 | |0;dojo.html;3389.796612203268;3651.6896063834556;3682.3924052531333;3690.0959264980074;3697.7596456230326;4838;6145.938540614593;6168.660723660202;6192.009278515438;6228.688565571721;5079.644424890259;6220.191375121228;6258.405451482102;6285.434310912017;6323.77866774663;4101;4330.458692663417;4938.580220681247;5224.85053288276;5484.985277723624;4653.511381304965;5631;5632.971835140818;5639.2668953036255;5724.370319264877;5463.262459567947;5474.315710536183;5613.21415001898;5622.775088996435;5629.268195134648;4800.759962001921;5538.446155384469;5552.944470555303;5553.111502159675;5555.388907220203;5076.568491678175;5095.566876815476;5587.161925711135;5620.353659329147;5624.493795558419;6291.5281353898645;6302.432781049728;6322.74709011639;6336.271328797156;6336.936630633681;6114.541409394314;6301.842453938688;6546.1490006299355;6594.835129121762;6599.6370199639005;5368;5693.686847223401;6434.7104380303;6438.45273151778;6526.869462610769;6312.242530896276;6352.777652782131;6364.140840986453;6378.681065946684;6381.585196962183;6751.864962700718;6803.93196068033;6837.076994605764;6853.657317134123;6868.141482314711;6297.433231009231;6362.045693146065;6374.107624932513;6468.482521398277;6504.317046710122;6490.0589414534625;6540.215174179129;6568.6058836469965;6577.210734711865;6592.8351791205605;6503.447206987365;6530.510211734139;6532.542722009509;6542;6559.934400656028;5893.673923367208;6259.4679452246155;6308.936910630835;6525.575837570543;6550.181227346582;5249.448807875195;5258.8948221036135;5280.947190528045;5295.232191332312;5302.390225124083;4708.081924024782;4709.576138147515;4731.858044258678;4767.856964291077;4776.665633405698;6288.779892703734;6345.650989195547;6356.745730170834;6368.490520758328;6379.043143528415;5262.500062494057;6386.489080873612;6490.935090649127;6498.350164983558;6520.054592084121;6296.370362963758;6329.936700633027;6335.841603959938;6336.968315158395;6350.206224221973;5812.302523697099;5823.068309070529;5843.035899076604;5849.8797081050025;5883.7058147092475;5706.28671416073;6562.967185164043;6574.868502630016;6775.695093720893;6828.180618325832;6634.104395906684;6634.668266586747;6665.200175978914;6671.699773510107;6676.632785196666;6827.658617069027;6904.344087311697;6909.239983601699;6919.619420931897;6923.584584924719;6341.080543321101;6372.808815735535;6432.6554964451225;6437.742490300336;6524.119243902012;6594.340565943367;6650.501212408992;6651.800445986628;6652.966735166293;6658.5671931324305;6334.144890439732;6448;6477.77327793525;6481.8703625928165;6485.44873685742;6282.905756413566;6324.0513922910095;6340.524460665376;6346.079818426395;6349.269833969152;5436.320459942507;6372.58578192416;6374.808755737336;6379.489640828815;6395.232572091379;6367;6384.201974753156;6385.3295403983775;6391.648459334595;6399.8080057598345;5392.784288628411;5445.40100588927;5809.8547536311935;5896.584819643169;6271.122042914087 20:15:03 INFO - PROCESS | 1641 | |1;ext.html;795.614127148333;837.5770236030803;861.7027125641654;987.2250283527433;992.6476100984155;39689.4046589301;42440.15119697612;42502.362464563055;42670.35994460086;42696.57303426973;40434.79782601083;40560.594394056054;40711.98220044493;40772.18455630886;41031.35874565014;8279.834403311945;8439.535825529623;11127.554897804119;11407.315561066322;11573.305601663928;11328.433578321092;11383.886161138365;11424.600138995136;11445.942770286138;11459.713507162303;11402.372869492174;11415.54337826486;11418.771624567504;11423.543058277659;11437.885621143807;11355.829662555027;11363.204575679705;11370.033547148503;11377.772444551063;11379.26034807739;11080.335179889233;11097.611583594617;11139.944300278528;11147.609833655782;11153.051990580729;34282.82858585716;34402.13994650129;34433.311333773185;34493;34562.96311110671;3882.2235552889533;3913.4325522799318;3924.3524818405212;3939.5666476687543;3940.9802950985063;26398.944042238287;26418.075367362242;26888;27215.727842721517;27438;3195.8561864716144;3233.611966564004;3260.8858689945737;3283.8522266498067;3285.5564498792596;363800;367754;386220;386337;396362.0181899101;43797.78101109538;44198.116037679065;44210.115797683866;44241.11517769691;44249;45821.77089114533;45877.08245835132;45896.770516147204;45999.31001034987;46008.07983840304;14093.224872632103;14113.85886141146;14146.85853141476;14192.929035354964;14240.359183836752;12124.636260912186;12198.268103913793;12424.192427492184;12432.564860229848;12438;11897.048236141089;11915;11936.224145430519;11996.58011969577;12061.035117190604;4918.754062296871;4961.950380496149;5868.911966320508;5910.970445147746;5917.556183286185;1158.5829101523445;1183.408295852074;1197.479096592985;1201.7596480703894;1212.3877441891789;28258.43483130326;28430;28480.572791408147;28487.57268640972;28502.857485712855;1149.747055647756;1221.883921027501;1266.3858028855998;1312.8490223624406;1352.317079874657;12578.427696537932;14362.353694083791;14724;14729.660311111282;14860.70278594422;189355;201445;201674;201866.96266074595;202303;57604;58036.419635803104;58062.4193758057;58112.70943645255;58165.418345816;1306.9346532673328;1524.1540944775534;1529.8852586056091;1544.4208421841809;1550.9534713958599;26097.478050439266;27392.45215095727;27807.02675406351;27835.860820695765;27912;47685;47731.76134119307;47830.760846196936;47836;47836.760816195696;309707.9029209679;309951;310972.3354149644;312299;312830;30221.395572088873;30784.53823192609;31734.52398214075;31822.52266216055;32311.35377292488;39980.000499987735;40133.79933100316;40689.59310406858;41087;41216.587834121274;28910.566341505313;29080.7091929078;29165.562516561844;29787.55318670265;29955.850220748758;28929.276768080126;29117.708822912347;29193.85403072971;29814.10557683273;29865.701342987162;32644.83677581597;34217;34249.82875085609;34357.828210859785;34490;28761.28096797513;29285.26786830261;29293.560596591495;29783.851080744458;30019.949301774333;23360.6495902565;23508.11935045158;23523.529529409658;23565;23974 20:15:03 INFO - PROCESS | 1641 | |2;jquery.html;400.15167844170344;409.6538425030849;424.8067129456098;437.90585024219797;444.93882091212464;20277.695834562455;21184;22339.553208935813;22525.211617593388;22581.209657661984;17941.551461213476;22923.656145157795;23232.070717171337;23904.641430378557;24215.636765448533;6751.493637977147;6834.316568343175;7108.324709152648;7110.60891650959;7131.8217044573785;6875.724971001154;7025.192102908154;7054.294570542956;7066.116735408074;7074.292570742936;6640.7343706251695;6930.099087118668;6987.441004719611;7025.824354391156;7045.330693584101;6651.268360480343;6923.757668481604;6957.339052790002;6979.092717946685;7003.7548685795755;6747.426468750162;6799.66001699918;6837.863242735118;6842.521023528355;6847.349501797321;22486.88756556207;26426.471470570672;27382.726172738217;27985.440291194267;28229.576556351672;3029.8939537116094;3031.2118849099174;3046.5430185472137;3046.8628911698806;3080.3839232153437;16267.755983660254;17019.74470382933;17310.307587696476;17316.826831731647;17339.04635245061;2120.4592828828704;2159.676048592708;2191.616467118248;2203.6694495825595;2426.6845310109575;127942.36028819889;166438;177999;179270;179597.1020144904;20842.68735968962;21064.473388165112;21112.67760546339;21171.576568468852;21272;31527.84236078805;31705.52441713376;31850;31923.042308730775;32065.679343206735;10782.514786834612;10850.565977360975;10866.61966831157;10904.727381815517;11173.664790056311;9175.577785443267;9175.678851240174;9183.494907780136;9220.769480763034;9338.439693618404;7851.450398472166;8110.432269741062;9525.952370238105;9634.470104144339;9752.512374381253;3633.4368172933237;4141.192467468875;4262.616364527145;4294.377315289265;4297.269464191095;824.2664029014182;892.6295587331315;906.2523418180028;945.4280160502797;951.4671783801093;16510.015523687474;16754.4135955241;18766.249350025846;22455.10179592798;22674.546509070056;941.7457286532598;942.5852624845046;958.8561715742555;966.183574879226;973.3478569358547;9736.464494452732;10512.842307365396;11844.466998985066;11857.881421185677;11872.34702091376;766848;767495;773563.1321843468;774925;792338;123501;129099;139244;139318.9102163448;167044;1110.2505808579176;1194.5580135349976;1353.4383230959238;1364.9590512284647;1367.6102310841363;17459.738103928707;20479.976001199582;21069.262575809775;21081.578368432853;21103.8944805275;63999.68000159969;65288.67355663191;65456.34543654693;65625.34374656192;66362.66818665876;93433.05254154778;94424.05575944428;96075;96390;98146;11672;11715.062794976551;11741.882581174421;12067.879321206676;12083.275003499819;12178.75642487163;13150.539731109366;13156.868431315565;13168.539101131415;13472.79790803119;9645;10300.278980471296;11518.251313664585;11532.25040372373;11664.708382290171;8734.737957861274;10195;10537.841932371173;10730.839037414602;10855.131589472665;11495.137864660017;11810.468528916392;11826.93557579822;11834.644960651194;11949.22330048544;9660.860220428249;11648;11664.708382290512;11699.824502632297;11908.642740717793;11372.829407558727;11401.713138700678;11412.543498259976;11662.70843228926;11716.297022178696 20:15:03 INFO - PROCESS | 1641 | |3;mootools.html;1586.5319730679448;1639.4753678822779;1642.162497126465;1649.5876030992251;1656.8757343199263;9892.911779704245;10152.593896244141;10853.67438976831;11441.485133169017;11467.19729618927;10992.670219893416;11343.319400835935;11348.829767553494;11351.262167959097;11384.544618215261;2316.999999999996;2358.7759162879584;2364.3498038039593;2371.0989823866994;2377.3224630980176;2007.2071531744953;2290.335802617239;2356.422676444265;2357.457784709518;2372.9288121356385;2081.740546969077;2245.9101635934544;2368.6802281691926;2375.9168429104984;2393.4255778613183;2330.7086614173227;2343.121329501437;2350.1421980976993;2354.117206047732;2355.5288942211455;2095.172406899266;2259.728832540089;2331.5220379822094;2336.3458231695154;2357.446000189954;9796.559154838049;11453.026492748126;11669.591564295293;11738.706532336675;11741.412929353499;5598.440155984369;5725.713714314267;5759.22250496186;5762.510186634141;5763.510101641324;3294.374068926896;3417.8638351030604;3498.877539286138;3527.4356103023392;3546.6098729139776;1015.7816069545083;1102.1513434655271;1156.5157981246223;1203.1818363512807;1209.3288225035103;16102.597435064217;16142.273597688014;16153.192340383051;16160.676786464204;16163.111028893407;4349.630281426114;4643.558861908114;4702.130105930345;4732.503087175797;4750.501197374295;13559.66100847467;13596.592102236751;13618.387172577257;13618.659533511742;13669.931650341883;5787.247657804459;5844.47399734026;5854.765809367577;5862.530997520188;5890.617109887844;4471.463424389094;4892.804287828447;4942.258661200849;5111.974440127776;5223.686578805284;3307.4873394623874;3879.631435013669;3910.3156947534067;3999.1801680655976;4059.736117152435;1034.7464871106627;1040.2250323509033;1042.775803202315;1047.5233768635253;1057.212376779289;841.7600983719733;968.4625033106553;987.2842189412762;990.0842466264015;1033.5245786937942;6662.766803161866;6705.530612857053;6710.396064354231;6961.82595435108;6982.93017069833;984.3404918704485;1038.6912490262173;1052.5684469367523;1084.224779282801;1087.749817541954;4200.663946884242;4220.198162349144;4320.524742278347;4322.481302243688;4385.210662080856;13807.792883106762;14310.85689143116;14324.641883952985;14345.282735863166;14366.209858457885;14104.506342277971;14172.645683857985;14279.85720142806;14317;14356.49752258666;10603.151747860153;10723.410212438235;10774.46127693628;10785.514651840685;10808.405537695502;6428.292887782247;6596.538242322991;6725.73097076131;6751.45988320943;6831.59010459374;12185.51257949672;12502.937485312515;12735;12861.742765144832;12865.649106844021;12899.613011609667;12937.482500700244;12956.222626642431;13078.28069456189;13112.672183195114;8769.473831570127;8838.027816940003;8917.42036767608;8968.327375446997;8986.281097512052;10440.26918115755;10689.144868410353;10774.46127693628;10785.298955567863;10793.838092428778;9198.494082825242;9294.767630809283;9295.907040929504;9376.109269619375;9458.385204961656;8903.955480222558;9027.684031058881;9078.228350590274;9089.909100908906;9118.954405228196;7974.322182614311;8083.7170699025255;8129.878051829345;8195.795105122419;8205.753827385188;5047.470015648304;6065.848353791191;6090.177825993434;6199.225096862892;6222.533310001678;6002.279726432856;6055.273367195965;6157.84605384869;6168.9691551541955;6300.369963003663 20:15:03 INFO - PROCESS | 1641 | |4;prototype.html;383.89059118151334;396.7936672930076;399.4008986520218;402.29598203144496;406.69904270839567;4925.458199598051;5472.5621950243985;5519.902437073912;5570.359408668;5705.343885453174;4371.781410929457;4999.500049994998;5221.895562088756;5345.385280692712;5508.421615730351;4241.618254357115;4509.286471140962;4511.458624965009;4518.007601600234;4556;4017.5982401759884;4183.728057676241;4190.371444283352;4470.217711900421;4531.456225252974;4415.542870852627;4441.333799930004;4468.575485328888;4482.708623939449;4517.232070548015;3686.6313368663054;3909.3354129798004;4461.754603496806;4545.886352841172;4567.451905771329;3607.8196090195383;3648;4151.571330771502;4155.480564929384;4188.937165942513;4912.828051018198;4916.50834916509;4927.50724927508;5036.84889453317;5227.163653815409;4451.5103338632725;4732.491779356947;5054.82308119214;5085.847424577269;5217.799906021636;4827.513830377358;4983.287450849104;5563.833085007456;5689.857753556153;5714.571407144481;3852.5569559500454;3920.471016303644;3944.7435916665327;4594.448666160049;4628.467726211461;5272.710000949946;5356.3572371315695;5388.43421440747;5826.126081087828;5880.882382352372;4218.261804184255;4473.696046536036;4656.115338085753;4722.008378240539;4741.83403580873;4129.2773764591075;4546.886012926854;4763.809447622057;4774.665773395829;4801.303810947463;4280.72175308604;4684.601808846286;4751.928721069186;4892.186992479338;5142.742862856842;5350.545203657733;5746.798862039808;5778.306603207642;5817.360090390053;5829.7376618052285;1421.8933579981542;1507.8663690877286;1595.4045954045955;1658.7428948513;1674.5264167532494;1118.7358285137905;1124.0333313350511;1206.897413719826;1227.5028613411582;1254.943527541263;5348.88718956018;5459.344878614512;5483.451654834565;5503.816679413945;5567.47109024642;4988.326575912278;5192.182736042355;5211.218317252366;5518.420565840609;5632.295963004624;4577.61090307321;5119.259451786336;5365.409804921455;5374.951884382586;5490.6431081979545;2570.506608589665;2875.079974408169;3093.808883579838;3124.375124975014;3151.8896838611;5889.381614930457;5936.387989284701;6008.519318454513;6173.135760993465;6225.595336303126;5836.737346819404;6133.355997620275;6167.638636942787;6183.690815459209;6283.371662833772;5400.810971615974;5616.680080181154;6068.939310606926;6257.405546473078;6317.526185536011;5081.491850815037;5307.548858347083;5817.418258174149;5995.310539288038;6010.3088144863905;4826.638002149923;4943.703377797344;5626.915596265977;5786.392428794916;6097.5731698780655;4995.875103122451;5105.821296254613;5738.368779434176;6090.9949858272885;6192.411720886508;3871.3933717508044;4073.348264277701;4697.906041879075;4709.199436095941;4721.858344249678;5521.8310175817005;5796.449337312949;5845.503132233808;5956.515438468545;6131.294901086432;4818.8795280118275;5476.507114359727;5620.229627667271;5753.741081651252;5811.76752929895;5161.974190129025;5748.310202775527;5786.594938354275;5865.648061116347;5928.822135335947;5107.438181800073;5202.583793296603;5532.806351777668;5647.548196144381;5678;4719.787609557647;5370.704611246263;5722.599418040697;5776.277965254343;5781.421857814353;5036.924446133237;5055.1406260936465;5469.835904922858;5619.847931174181;5705.62913410627 20:15:03 INFO - PROCESS | 1641 | |5;yui.html;763.3282711214131;771.1748429180778;803.204827221051;851.5401683091131;897.125302829741;15766.975146615461;17511.912440437813;17750;17770.022648754315;17772.91113544432;15834.604134896634;16477;16615.919965202283;16626.919250248724;16693.415730449437;997.0727223681955;1041.2450973044558;1043.0091413157493;1045.9110975567087;1047.4605578127232;1000.7298029532059;1039.7036844499319;1057.9312344697607;1058.391424930664;1059.3749687684265;1082.3992684060383;1095.906847917928;1098.3849044535086;1100.8568886044802;1101.1136035491427;1094.0099210214769;1095.3920574081383;1096;1096.1723898456694;1098.5825386353215;811.703728139225;813.2030610002172;816.7182322098867;819.0703551469065;819.1194465949128;17148.657026859517;17476.475705728848;17633.823661763345;18253.54366140844;18426.170822313026;12312.569060082944;13226.933865330708;14244.359003844851;14701.705965880623;14746.557603271918;12104.878951210463;12229.93885030578;12235.510579576807;12286.324252166127;12581.308028058453;3780.9479248588787;3909.5894931032117;3928.253631809947;3932.390479475686;4064.5325787534516;97960.02039979651;108411.99999999921;110338.44830775875;115442;116321;11040.392778397105;11255.774884502263;11266.830997535044;11750.882491175149;11766.823497647541;21491.67762483564;22524.662130068064;22688.65967010496;22767.658485122738;22823.429414264778;7217.700813853452;8014.398920081017;8033.236842499953;8066.23370779775;8177.304929080976;4566.634669226454;4605.815767369335;4644.349791029259;4667.719936803803;4696.990147118386;3424.109731469786;3479.356319080978;3485.4946032825096;3499.4575840744724;3532.3994920863515;510.57877251267854;613.3192156706052;621.5493767018875;622.4957784194817;630.0140280461064;672.8015235505537;680.4318394140848;697.5884228305324;725.804032911111;730.8246020955039;10382.273240873066;10684.145268378512;10720.41037742916;10993.6152234673;11118.944405277922;568.1705306236022;580.5993864233666;586.9354371019184;603.3724926076831;605.4581149870841;5087.745612719349;5219.138842091088;5222.947770522323;5233.764480598382;5240.842774716688;109500;110838;112056;112838;116929;83191.16808831834;83748.58125709454;85652;93833;97151;71272.28727712863;71547.64226178835;72520;72621.27378726251;74458;10996.780064398827;11120.777584448428;11269.887301127212;11481.712957176138;11482.655520334069;51250.74374628103;51822.481775183274;52244.73877630587;52639.73680131575;52896.94206115933;53205.73397132989;54701.72649136729;65724.34275657182;65808.0128798078;65883.67058164679;11409.828852567383;12770.91447226959;13004.804927926278;13213.801792972921;13263.602091937257;12184.51261949512;14715.484958026418;15173.696526069638;15519.84480155184;15852.920735396248;10386.480675966322;13209.603711888658;13919.9458878764;14215.431382744575;14233.715325693222;11286.943565282121;13032.609021729364;13600.795988060385;13620;13632.113912595649;13397;13575.79636305476;13855.334880323511;14290.28548572588;14385.496507622183;9872.259580531345;9969.30214884951;10155.238357123097;10352;10926.508307126358;9733.464659443798;10390.844137338097;11242.7751444969;11490.080793536665;11573.942130289295 20:15:03 INFO - PROCESS | 1641 | __end_tp_report 20:15:03 INFO - PROCESS | 1641 | __start_cc_report 20:15:03 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,393 20:15:03 INFO - PROCESS | 1641 | __end_cc_report 20:15:03 INFO - PROCESS | 1641 | __startTimestamp1496632503468__endTimestamp 20:15:03 INFO - PROCESS | 1641 | 20:15:03 INFO - PROCESS | 1641 | ------- Summary: start ------- 20:15:03 INFO - PROCESS | 1641 | Number of tests: 6 20:15:03 INFO - PROCESS | 1641 | 20:15:03 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:6030.66 Median:6336.27 stddev:727.08 (11.5%) stddev-sans-first:699.18 20:15:03 INFO - PROCESS | 1641 | Values: 3389.8 3651.7 3682.4 3690.1 3697.8 4838.0 6145.9 6168.7 6192.0 6228.7 5079.6 6220.2 6258.4 6285.4 6323.8 4101.0 4330.5 4938.6 5224.9 5485.0 4653.5 5631.0 5633.0 5639.3 5724.4 5463.3 5474.3 5613.2 5622.8 5629.3 4800.8 5538.4 5552.9 5553.1 5555.4 5076.6 5095.6 5587.2 5620.4 5624.5 6291.5 6302.4 6322.7 6336.3 6336.9 6114.5 6301.8 6546.1 6594.8 6599.6 5368.0 5693.7 6434.7 6438.5 6526.9 6312.2 6352.8 6364.1 6378.7 6381.6 6751.9 6803.9 6837.1 6853.7 6868.1 6297.4 6362.0 6374.1 6468.5 6504.3 6490.1 6540.2 6568.6 6577.2 6592.8 6503.4 6530.5 6532.5 6542.0 6559.9 5893.7 6259.5 6308.9 6525.6 6550.2 5249.4 5258.9 5280.9 5295.2 5302.4 4708.1 4709.6 4731.9 4767.9 4776.7 6288.8 6345.7 6356.7 6368.5 6379.0 5262.5 6386.5 6490.9 6498.4 6520.1 6296.4 6329.9 6335.8 6337.0 6350.2 5812.3 5823.1 5843.0 5849.9 5883.7 5706.3 6563.0 6574.9 6775.7 6828.2 6634.1 6634.7 6665.2 6671.7 6676.6 6827.7 6904.3 6909.2 6919.6 6923.6 6341.1 6372.8 6432.7 6437.7 6524.1 6594.3 6650.5 6651.8 6653.0 6658.6 6334.1 6448.0 6477.8 6481.9 6485.4 6282.9 6324.1 6340.5 6346.1 6349.3 5436.3 6372.6 6374.8 6379.5 6395.2 6367.0 6384.2 6385.3 6391.6 6399.8 5392.8 5445.4 5809.9 5896.6 6271.1 20:15:03 INFO - PROCESS | 1641 | 20:15:03 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:45270.48 Median:29289.41 stddev:80559.24 (275.0%) stddev-sans-first:80716.02 20:15:03 INFO - PROCESS | 1641 | Values: 795.6 837.6 861.7 987.2 992.6 39689.4 42440.2 42502.4 42670.4 42696.6 40434.8 40560.6 40712.0 40772.2 41031.4 8279.8 8439.5 11127.6 11407.3 11573.3 11328.4 11383.9 11424.6 11445.9 11459.7 11402.4 11415.5 11418.8 11423.5 11437.9 11355.8 11363.2 11370.0 11377.8 11379.3 11080.3 11097.6 11139.9 11147.6 11153.1 34282.8 34402.1 34433.3 34493.0 34563.0 3882.2 3913.4 3924.4 3939.6 3941.0 26398.9 26418.1 26888.0 27215.7 27438.0 3195.9 3233.6 3260.9 3283.9 3285.6 363800.0 367754.0 386220.0 386337.0 396362.0 43797.8 44198.1 44210.1 44241.1 44249.0 45821.8 45877.1 45896.8 45999.3 46008.1 14093.2 14113.9 14146.9 14192.9 14240.4 12124.6 12198.3 12424.2 12432.6 12438.0 11897.0 11915.0 11936.2 11996.6 12061.0 4918.8 4962.0 5868.9 5911.0 5917.6 1158.6 1183.4 1197.5 1201.8 1212.4 28258.4 28430.0 28480.6 28487.6 28502.9 1149.7 1221.9 1266.4 1312.8 1352.3 12578.4 14362.4 14724.0 14729.7 14860.7 189355.0 201445.0 201674.0 201867.0 202303.0 57604.0 58036.4 58062.4 58112.7 58165.4 1306.9 1524.2 1529.9 1544.4 1551.0 26097.5 27392.5 27807.0 27835.9 27912.0 47685.0 47731.8 47830.8 47836.0 47836.8 309707.9 309951.0 310972.3 312299.0 312830.0 30221.4 30784.5 31734.5 31822.5 32311.4 39980.0 40133.8 40689.6 41087.0 41216.6 28910.6 29080.7 29165.6 29787.6 29955.9 28929.3 29117.7 29193.9 29814.1 29865.7 32644.8 34217.0 34249.8 34357.8 34490.0 28761.3 29285.3 29293.6 29783.9 30019.9 23360.6 23508.1 23523.5 23565.0 23974.0 20:15:03 INFO - PROCESS | 1641 | 20:15:03 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:44189.94 Median:13321.16 stddev:129190.10 (969.8%) stddev-sans-first:129510.67 20:15:03 INFO - PROCESS | 1641 | Values: 400.2 409.7 424.8 437.9 444.9 20277.7 21184.0 22339.6 22525.2 22581.2 17941.6 22923.7 23232.1 23904.6 24215.6 6751.5 6834.3 7108.3 7110.6 7131.8 6875.7 7025.2 7054.3 7066.1 7074.3 6640.7 6930.1 6987.4 7025.8 7045.3 6651.3 6923.8 6957.3 6979.1 7003.8 6747.4 6799.7 6837.9 6842.5 6847.3 22486.9 26426.5 27382.7 27985.4 28229.6 3029.9 3031.2 3046.5 3046.9 3080.4 16267.8 17019.7 17310.3 17316.8 17339.0 2120.5 2159.7 2191.6 2203.7 2426.7 127942.4 166438.0 177999.0 179270.0 179597.1 20842.7 21064.5 21112.7 21171.6 21272.0 31527.8 31705.5 31850.0 31923.0 32065.7 10782.5 10850.6 10866.6 10904.7 11173.7 9175.6 9175.7 9183.5 9220.8 9338.4 7851.5 8110.4 9526.0 9634.5 9752.5 3633.4 4141.2 4262.6 4294.4 4297.3 824.3 892.6 906.3 945.4 951.5 16510.0 16754.4 18766.2 22455.1 22674.5 941.7 942.6 958.9 966.2 973.3 9736.5 10512.8 11844.5 11857.9 11872.3 766848.0 767495.0 773563.1 774925.0 792338.0 123501.0 129099.0 139244.0 139318.9 167044.0 1110.3 1194.6 1353.4 1365.0 1367.6 17459.7 20480.0 21069.3 21081.6 21103.9 63999.7 65288.7 65456.3 65625.3 66362.7 93433.1 94424.1 96075.0 96390.0 98146.0 11672.0 11715.1 11741.9 12067.9 12083.3 12178.8 13150.5 13156.9 13168.5 13472.8 9645.0 10300.3 11518.3 11532.3 11664.7 8734.7 10195.0 10537.8 10730.8 10855.1 11495.1 11810.5 11826.9 11834.6 11949.2 9660.9 11648.0 11664.7 11699.8 11908.6 11372.8 11401.7 11412.5 11662.7 11716.3 20:15:03 INFO - PROCESS | 1641 | 20:15:03 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:6934.19 Median:2361.56 stddev:4482.96 (189.8%) stddev-sans-first:4477.53 20:15:03 INFO - PROCESS | 1641 | Values: 1586.5 1639.5 1642.2 1649.6 1656.9 9892.9 10152.6 10853.7 11441.5 11467.2 10992.7 11343.3 11348.8 11351.3 11384.5 2317.0 2358.8 2364.3 2371.1 2377.3 2007.2 2290.3 2356.4 2357.5 2372.9 2081.7 2245.9 2368.7 2375.9 2393.4 2330.7 2343.1 2350.1 2354.1 2355.5 2095.2 2259.7 2331.5 2336.3 2357.4 9796.6 11453.0 11669.6 11738.7 11741.4 5598.4 5725.7 5759.2 5762.5 5763.5 3294.4 3417.9 3498.9 3527.4 3546.6 1015.8 1102.2 1156.5 1203.2 1209.3 16102.6 16142.3 16153.2 16160.7 16163.1 4349.6 4643.6 4702.1 4732.5 4750.5 13559.7 13596.6 13618.4 13618.7 13669.9 5787.2 5844.5 5854.8 5862.5 5890.6 4471.5 4892.8 4942.3 5112.0 5223.7 3307.5 3879.6 3910.3 3999.2 4059.7 1034.7 1040.2 1042.8 1047.5 1057.2 841.8 968.5 987.3 990.1 1033.5 6662.8 6705.5 6710.4 6961.8 6982.9 984.3 1038.7 1052.6 1084.2 1087.7 4200.7 4220.2 4320.5 4322.5 4385.2 13807.8 14310.9 14324.6 14345.3 14366.2 14104.5 14172.6 14279.9 14317.0 14356.5 10603.2 10723.4 10774.5 10785.5 10808.4 6428.3 6596.5 6725.7 6751.5 6831.6 12185.5 12502.9 12735.0 12861.7 12865.6 12899.6 12937.5 12956.2 13078.3 13112.7 8769.5 8838.0 8917.4 8968.3 8986.3 10440.3 10689.1 10774.5 10785.3 10793.8 9198.5 9294.8 9295.9 9376.1 9458.4 8904.0 9027.7 9078.2 9089.9 9119.0 7974.3 8083.7 8129.9 8195.8 8205.8 5047.5 6065.8 6090.2 6199.2 6222.5 6002.3 6055.3 6157.8 6169.0 6300.4 20:15:03 INFO - PROCESS | 1641 | 20:15:03 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4755.54 Median:5083.67 stddev:1339.27 (26.3%) stddev-sans-first:1302.21 20:15:03 INFO - PROCESS | 1641 | Values: 383.9 396.8 399.4 402.3 406.7 4925.5 5472.6 5519.9 5570.4 5705.3 4371.8 4999.5 5221.9 5345.4 5508.4 4241.6 4509.3 4511.5 4518.0 4556.0 4017.6 4183.7 4190.4 4470.2 4531.5 4415.5 4441.3 4468.6 4482.7 4517.2 3686.6 3909.3 4461.8 4545.9 4567.5 3607.8 3648.0 4151.6 4155.5 4188.9 4912.8 4916.5 4927.5 5036.8 5227.2 4451.5 4732.5 5054.8 5085.8 5217.8 4827.5 4983.3 5563.8 5689.9 5714.6 3852.6 3920.5 3944.7 4594.4 4628.5 5272.7 5356.4 5388.4 5826.1 5880.9 4218.3 4473.7 4656.1 4722.0 4741.8 4129.3 4546.9 4763.8 4774.7 4801.3 4280.7 4684.6 4751.9 4892.2 5142.7 5350.5 5746.8 5778.3 5817.4 5829.7 1421.9 1507.9 1595.4 1658.7 1674.5 1118.7 1124.0 1206.9 1227.5 1254.9 5348.9 5459.3 5483.5 5503.8 5567.5 4988.3 5192.2 5211.2 5518.4 5632.3 4577.6 5119.3 5365.4 5375.0 5490.6 2570.5 2875.1 3093.8 3124.4 3151.9 5889.4 5936.4 6008.5 6173.1 6225.6 5836.7 6133.4 6167.6 6183.7 6283.4 5400.8 5616.7 6068.9 6257.4 6317.5 5081.5 5307.5 5817.4 5995.3 6010.3 4826.6 4943.7 5626.9 5786.4 6097.6 4995.9 5105.8 5738.4 6091.0 6192.4 3871.4 4073.3 4697.9 4709.2 4721.9 5521.8 5796.4 5845.5 5956.5 6131.3 4818.9 5476.5 5620.2 5753.7 5811.8 5162.0 5748.3 5786.6 5865.6 5928.8 5107.4 5202.6 5532.8 5647.5 5678.0 4719.8 5370.7 5722.6 5776.3 5781.4 5036.9 5055.1 5469.8 5619.8 5705.6 20:15:03 INFO - PROCESS | 1641 | 20:15:03 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20883.44 Median:16921.04 stddev:29928.41 (176.9%) stddev-sans-first:29974.23 20:15:03 INFO - PROCESS | 1641 | Values: 763.3 771.2 803.2 851.5 897.1 15767.0 17511.9 17750.0 17770.0 17772.9 15834.6 16477.0 16615.9 16626.9 16693.4 997.1 1041.2 1043.0 1045.9 1047.5 1000.7 1039.7 1057.9 1058.4 1059.4 1082.4 1095.9 1098.4 1100.9 1101.1 1094.0 1095.4 1096.0 1096.2 1098.6 811.7 813.2 816.7 819.1 819.1 17148.7 17476.5 17633.8 18253.5 18426.2 12312.6 13226.9 14244.4 14701.7 14746.6 12104.9 12229.9 12235.5 12286.3 12581.3 3780.9 3909.6 3928.3 3932.4 4064.5 97960.0 108412.0 110338.4 115442.0 116321.0 11040.4 11255.8 11266.8 11750.9 11766.8 21491.7 22524.7 22688.7 22767.7 22823.4 7217.7 8014.4 8033.2 8066.2 8177.3 4566.6 4605.8 4644.3 4667.7 4697.0 3424.1 3479.4 3485.5 3499.5 3532.4 510.6 613.3 621.5 622.5 630.0 672.8 680.4 697.6 725.8 730.8 10382.3 10684.1 10720.4 10993.6 11118.9 568.2 580.6 586.9 603.4 605.5 5087.7 5219.1 5222.9 5233.8 5240.8 109500.0 110838.0 112056.0 112838.0 116929.0 83191.2 83748.6 85652.0 93833.0 97151.0 71272.3 71547.6 72520.0 72621.3 74458.0 10996.8 11120.8 11269.9 11481.7 11482.7 51250.7 51822.5 52244.7 52639.7 52896.9 53205.7 54701.7 65724.3 65808.0 65883.7 11409.8 12770.9 13004.8 13213.8 13263.6 12184.5 14715.5 15173.7 15519.8 15852.9 10386.5 13209.6 13919.9 14215.4 14233.7 11286.9 13032.6 13600.8 13620.0 13632.1 13397.0 13575.8 13855.3 14290.3 14385.5 9872.3 9969.3 10155.2 10352.0 10926.5 9733.5 10390.8 11242.8 11490.1 11573.9 20:15:03 INFO - PROCESS | 1641 | -------- Summary: end -------- 20:15:03 INFO - PROCESS | 1641 | 20:15:04 INFO - TEST-INFO | 1641: exit 0 20:15:04 INFO - TEST-OK | dromaeo_css | took 1115419ms 20:15:04 INFO - TEST-START | kraken 20:15:04 INFO - operating with platform_type : mac_ 20:15:04 INFO - Initialising browser for kraken test... 20:15:04 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmpiYSV7Q/profile http://localhost:49226/getInfo.html) 20:15:06 INFO - PROCESS | 1650 | 2017-06-04 20:15:06.789 plugin-container[1652:10585] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8543, name = 'com.apple.tsm.portname' 20:15:06 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:15:06 INFO - PROCESS | 1650 | 2017-06-04 20:15:06.790 plugin-container[1652:10585] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x871f, name = 'com.apple.CFPasteboardClient' 20:15:06 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:15:06 INFO - PROCESS | 1650 | 2017-06-04 20:15:06.790 plugin-container[1652:10585] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:15:06 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 20:15:06 INFO - PROCESS | 1650 | colorDepth:24 20:15:06 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 20:15:06 INFO - PROCESS | 1650 | __metrics 20:15:06 ERROR - PROCESS | 1650 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 20:15:07 INFO - TEST-INFO | 1650: exit 0 20:15:07 INFO - Browser initialized. 20:15:07 INFO - Running cycle 1/1 for kraken test... 20:15:07 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/T/tmpiYSV7Q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:15:09 INFO - PROCESS | 1653 | 2017-06-04 20:15:09.799 plugin-container[1654:10715] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x423f, name = 'com.apple.tsm.portname' 20:15:09 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:15:09 INFO - PROCESS | 1653 | 2017-06-04 20:15:09.800 plugin-container[1654:10715] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 20:15:09 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:15:09 INFO - PROCESS | 1653 | 2017-06-04 20:15:09.800 plugin-container[1654:10715] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:15:19 INFO - PROCESS | 1653 | RSS: Main: 174419968 20:15:19 INFO - PROCESS | 1653 | 20:15:22 INFO - PROCESS | 1653 | 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) 20:15:23 INFO - PROCESS | 1653 | RSS: Main: 188002304 20:15:23 INFO - PROCESS | 1653 | 20:15:25 INFO - PROCESS | 1653 | 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) 20:15:26 INFO - PROCESS | 1653 | RSS: Main: 195960832 20:15:26 INFO - PROCESS | 1653 | 20:15:29 INFO - PROCESS | 1653 | 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) 20:15:30 INFO - PROCESS | 1653 | RSS: Main: 199528448 20:15:30 INFO - PROCESS | 1653 | 20:15:32 INFO - PROCESS | 1653 | 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) 20:15:32 INFO - PROCESS | 1653 | RSS: Main: 201220096 20:15:32 INFO - PROCESS | 1653 | 20:15:35 INFO - PROCESS | 1653 | 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) 20:15:35 INFO - PROCESS | 1653 | RSS: Main: 201322496 20:15:35 INFO - PROCESS | 1653 | 20:15:38 INFO - PROCESS | 1653 | 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) 20:15:39 INFO - PROCESS | 1653 | RSS: Main: 205484032 20:15:39 INFO - PROCESS | 1653 | 20:15:42 INFO - PROCESS | 1653 | 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) 20:15:43 INFO - PROCESS | 1653 | RSS: Main: 205156352 20:15:43 INFO - PROCESS | 1653 | 20:15:46 INFO - PROCESS | 1653 | 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) 20:15:47 INFO - PROCESS | 1653 | RSS: Main: 205283328 20:15:47 INFO - PROCESS | 1653 | 20:15:48 INFO - PROCESS | 1653 | 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) 20:15:49 INFO - PROCESS | 1653 | RSS: Main: 204734464 20:15:49 INFO - PROCESS | 1653 | 20:15:51 INFO - PROCESS | 1653 | 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) 20:15:52 INFO - PROCESS | 1653 | RSS: Main: 204779520 20:15:52 INFO - PROCESS | 1653 | 20:15:54 INFO - PROCESS | 1653 | 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) 20:15:54 INFO - PROCESS | 1653 | RSS: Main: 204775424 20:15:54 INFO - PROCESS | 1653 | 20:15:57 INFO - PROCESS | 1653 | 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) 20:15:58 INFO - PROCESS | 1653 | RSS: Main: 205193216 20:15:58 INFO - PROCESS | 1653 | 20:16:01 INFO - PROCESS | 1653 | 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) 20:16:01 INFO - PROCESS | 1653 | RSS: Main: 204902400 20:16:01 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | 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) 20:16:03 INFO - PROCESS | 1653 | RSS: Main: 206594048 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | __start_tp_report 20:16:03 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 20:16:03 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 20:16:03 INFO - PROCESS | 1653 | |i|pagename|runs| 20:16:03 INFO - PROCESS | 1653 | |0;ai-astar;107;117;122;121;115;120;120;119;120;119 20:16:03 INFO - PROCESS | 1653 | |1;audio-beat-detection;140;121;139;160;109;109;110;110;127;111 20:16:03 INFO - PROCESS | 1653 | |2;audio-dft;199;205;197;201;198;196;198;196;200;195 20:16:03 INFO - PROCESS | 1653 | |3;audio-fft;76;75;49;104;84;87;68;87;72;58 20:16:03 INFO - PROCESS | 1653 | |4;audio-oscillator;77;90;115;86;57;101;113;77;57;94 20:16:03 INFO - PROCESS | 1653 | |5;imaging-gaussian-blur;89;108;87;137;105;89;97;97;133;110 20:16:03 INFO - PROCESS | 1653 | |6;imaging-darkroom;115;149;147;96;102;96;96;119;95;133 20:16:03 INFO - PROCESS | 1653 | |7;imaging-desaturate;111;146;139;138;92;92;91;95;93;89 20:16:03 INFO - PROCESS | 1653 | |8;json-parse-financial;62;64;65;65;66;68;66;68;66;67 20:16:03 INFO - PROCESS | 1653 | |9;json-stringify-tinderbox;50;62;99;94;87;82;50;68;84;85 20:16:03 INFO - PROCESS | 1653 | |10;stanford-crypto-aes;81;85;84;91;78;91;87;83;86;86 20:16:03 INFO - PROCESS | 1653 | |11;stanford-crypto-ccm;135;156;144;144;151;153;149;148;179;144 20:16:03 INFO - PROCESS | 1653 | |12;stanford-crypto-pbkdf2;162;166;160;168;162;165;160;165;164;163 20:16:03 INFO - PROCESS | 1653 | |13;stanford-crypto-sha256-iterative;66;65;63;64;65;66;66;63;64;64 20:16:03 INFO - PROCESS | 1653 | __end_tp_report 20:16:03 INFO - PROCESS | 1653 | __start_cc_report 20:16:03 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,981 20:16:03 INFO - PROCESS | 1653 | __end_cc_report 20:16:03 INFO - PROCESS | 1653 | __startTimestamp1496632563767__endTimestamp 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | ------- Summary: start ------- 20:16:03 INFO - PROCESS | 1653 | Number of tests: 14 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | [#0] ai-astar Cycles:10 Average:118.00 Median:120.00 stddev:4.35 (3.6%) stddev-sans-first:2.11 20:16:03 INFO - PROCESS | 1653 | Values: 107.0 117.0 122.0 121.0 115.0 120.0 120.0 119.0 120.0 119.0 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | [#1] audio-beat-detection Cycles:10 Average:123.60 Median:124.00 stddev:17.65 (14.2%) stddev-sans-first:17.70 20:16:03 INFO - PROCESS | 1653 | Values: 140.0 121.0 139.0 160.0 109.0 109.0 110.0 110.0 127.0 111.0 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | [#2] audio-dft Cycles:10 Average:198.50 Median:198.50 stddev:2.95 (1.5%) stddev-sans-first:3.13 20:16:03 INFO - PROCESS | 1653 | Values: 199.0 205.0 197.0 201.0 198.0 196.0 198.0 196.0 200.0 195.0 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | [#3] audio-fft Cycles:10 Average:76.00 Median:75.50 stddev:15.72 (20.8%) stddev-sans-first:16.67 20:16:03 INFO - PROCESS | 1653 | Values: 76.0 75.0 49.0 104.0 84.0 87.0 68.0 87.0 72.0 58.0 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:77.00 stddev:20.31 (26.4%) stddev-sans-first:21.24 20:16:03 INFO - PROCESS | 1653 | Values: 77.0 90.0 115.0 86.0 57.0 101.0 113.0 77.0 57.0 94.0 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | [#5] imaging-gaussian-blur Cycles:10 Average:105.20 Median:88.00 stddev:17.66 (20.1%) stddev-sans-first:17.73 20:16:03 INFO - PROCESS | 1653 | Values: 89.0 108.0 87.0 137.0 105.0 89.0 97.0 97.0 133.0 110.0 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | [#6] imaging-darkroom Cycles:10 Average:114.80 Median:122.00 stddev:21.53 (17.6%) stddev-sans-first:22.84 20:16:03 INFO - PROCESS | 1653 | Values: 115.0 149.0 147.0 96.0 102.0 96.0 96.0 119.0 95.0 133.0 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | [#7] imaging-desaturate Cycles:10 Average:108.60 Median:91.50 stddev:23.25 (25.4%) stddev-sans-first:24.65 20:16:03 INFO - PROCESS | 1653 | Values: 111.0 146.0 139.0 138.0 92.0 92.0 91.0 95.0 93.0 89.0 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | [#8] json-parse-financial Cycles:10 Average:65.70 Median:66.00 stddev:1.83 (2.8%) stddev-sans-first:1.36 20:16:03 INFO - PROCESS | 1653 | Values: 62.0 64.0 65.0 65.0 66.0 68.0 66.0 68.0 66.0 67.0 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | [#9] json-stringify-tinderbox Cycles:10 Average:76.10 Median:84.50 stddev:17.53 (20.8%) stddev-sans-first:15.85 20:16:03 INFO - PROCESS | 1653 | Values: 50.0 62.0 99.0 94.0 87.0 82.0 50.0 68.0 84.0 85.0 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:86.00 stddev:4.05 (4.7%) stddev-sans-first:4.00 20:16:03 INFO - PROCESS | 1653 | Values: 81.0 85.0 84.0 91.0 78.0 91.0 87.0 83.0 86.0 86.0 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:150.00 stddev:11.66 (7.8%) stddev-sans-first:10.98 20:16:03 INFO - PROCESS | 1653 | Values: 135.0 156.0 144.0 144.0 151.0 153.0 149.0 148.0 179.0 144.0 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.50 Median:164.50 stddev:2.59 (1.6%) stddev-sans-first:2.69 20:16:03 INFO - PROCESS | 1653 | Values: 162.0 166.0 160.0 168.0 162.0 165.0 160.0 165.0 164.0 163.0 20:16:03 INFO - PROCESS | 1653 | 20:16:03 INFO - PROCESS | 1653 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.60 Median:65.00 stddev:1.17 (1.8%) stddev-sans-first:1.13 20:16:03 INFO - PROCESS | 1653 | Values: 66.0 65.0 63.0 64.0 65.0 66.0 66.0 63.0 64.0 64.0 20:16:03 INFO - PROCESS | 1653 | -------- Summary: end -------- 20:16:03 INFO - PROCESS | 1653 | 20:16:04 INFO - TEST-INFO | 1653: exit 0 20:16:04 INFO - TEST-OK | kraken | took 60473ms 20:16:04 INFO - SUITE-END | took 1175s 20:16:04 INFO - Completed test suite (00:19:36) 20:16:04 INFO - javascript benchmark 20:16:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7839.106108368502, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3389.796612203268, 3651.6896063834556, 3682.3924052531333, 3690.0959264980074, 3697.7596456230326, 4838.0, 6145.938540614593, 6168.660723660202, 6192.009278515438, 6228.688565571721, 5079.644424890259, 6220.191375121228, 6258.405451482102, 6285.434310912017, 6323.77866774663, 4101.0, 4330.458692663417, 4938.580220681247, 5224.85053288276, 5484.985277723624, 4653.511381304965, 5631.0, 5632.971835140818, 5639.2668953036255, 5724.370319264877, 5463.262459567947, 5474.315710536183, 5613.21415001898, 5622.775088996435, 5629.268195134648, 4800.759962001921, 5538.446155384469, 5552.944470555303, 5553.111502159675, 5555.388907220203, 5076.568491678175, 5095.566876815476, 5587.161925711135, 5620.353659329147, 5624.493795558419, 6291.5281353898645, 6302.432781049728, 6322.74709011639, 6336.271328797156, 6336.936630633681, 6114.541409394314, 6301.842453938688, 6546.1490006299355, 6594.835129121762, 6599.6370199639005, 5368.0, 5693.686847223401, 6434.7104380303, 6438.45273151778, 6526.869462610769, 6312.242530896276, 6352.777652782131, 6364.140840986453, 6378.681065946684, 6381.585196962183, 6751.864962700718, 6803.93196068033, 6837.076994605764, 6853.657317134123, 6868.141482314711, 6297.433231009231, 6362.045693146065, 6374.107624932513, 6468.482521398277, 6504.317046710122, 6490.0589414534625, 6540.215174179129, 6568.6058836469965, 6577.210734711865, 6592.8351791205605, 6503.447206987365, 6530.510211734139, 6532.542722009509, 6542.0, 6559.934400656028, 5893.673923367208, 6259.4679452246155, 6308.936910630835, 6525.575837570543, 6550.181227346582, 5249.448807875195, 5258.8948221036135, 5280.947190528045, 5295.232191332312, 5302.390225124083, 4708.081924024782, 4709.576138147515, 4731.858044258678, 4767.856964291077, 4776.665633405698, 6288.779892703734, 6345.650989195547, 6356.745730170834, 6368.490520758328, 6379.043143528415, 5262.500062494057, 6386.489080873612, 6490.935090649127, 6498.350164983558, 6520.054592084121, 6296.370362963758, 6329.936700633027, 6335.841603959938, 6336.968315158395, 6350.206224221973, 5812.302523697099, 5823.068309070529, 5843.035899076604, 5849.8797081050025, 5883.7058147092475, 5706.28671416073, 6562.967185164043, 6574.868502630016, 6775.695093720893, 6828.180618325832, 6634.104395906684, 6634.668266586747, 6665.200175978914, 6671.699773510107, 6676.632785196666, 6827.658617069027, 6904.344087311697, 6909.239983601699, 6919.619420931897, 6923.584584924719, 6341.080543321101, 6372.808815735535, 6432.6554964451225, 6437.742490300336, 6524.119243902012, 6594.340565943367, 6650.501212408992, 6651.800445986628, 6652.966735166293, 6658.5671931324305, 6334.144890439732, 6448.0, 6477.77327793525, 6481.8703625928165, 6485.44873685742, 6282.905756413566, 6324.0513922910095, 6340.524460665376, 6346.079818426395, 6349.269833969152, 5436.320459942507, 6372.58578192416, 6374.808755737336, 6379.489640828815, 6395.232572091379, 6367.0, 6384.201974753156, 6385.3295403983775, 6391.648459334595, 6399.8080057598345, 5392.784288628411, 5445.40100588927, 5809.8547536311935, 5896.584819643169, 6271.122042914087], "value": 5985.938913756727, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [795.614127148333, 837.5770236030803, 861.7027125641654, 987.2250283527433, 992.6476100984155, 39689.4046589301, 42440.15119697612, 42502.362464563055, 42670.35994460086, 42696.57303426973, 40434.79782601083, 40560.594394056054, 40711.98220044493, 40772.18455630886, 41031.35874565014, 8279.834403311945, 8439.535825529623, 11127.554897804119, 11407.315561066322, 11573.305601663928, 11328.433578321092, 11383.886161138365, 11424.600138995136, 11445.942770286138, 11459.713507162303, 11402.372869492174, 11415.54337826486, 11418.771624567504, 11423.543058277659, 11437.885621143807, 11355.829662555027, 11363.204575679705, 11370.033547148503, 11377.772444551063, 11379.26034807739, 11080.335179889233, 11097.611583594617, 11139.944300278528, 11147.609833655782, 11153.051990580729, 34282.82858585716, 34402.13994650129, 34433.311333773185, 34493.0, 34562.96311110671, 3882.2235552889533, 3913.4325522799318, 3924.3524818405212, 3939.5666476687543, 3940.9802950985063, 26398.944042238287, 26418.075367362242, 26888.0, 27215.727842721517, 27438.0, 3195.8561864716144, 3233.611966564004, 3260.8858689945737, 3283.8522266498067, 3285.5564498792596, 363800.0, 367754.0, 386220.0, 386337.0, 396362.0181899101, 43797.78101109538, 44198.116037679065, 44210.115797683866, 44241.11517769691, 44249.0, 45821.77089114533, 45877.08245835132, 45896.770516147204, 45999.31001034987, 46008.07983840304, 14093.224872632103, 14113.85886141146, 14146.85853141476, 14192.929035354964, 14240.359183836752, 12124.636260912186, 12198.268103913793, 12424.192427492184, 12432.564860229848, 12438.0, 11897.048236141089, 11915.0, 11936.224145430519, 11996.58011969577, 12061.035117190604, 4918.754062296871, 4961.950380496149, 5868.911966320508, 5910.970445147746, 5917.556183286185, 1158.5829101523445, 1183.408295852074, 1197.479096592985, 1201.7596480703894, 1212.3877441891789, 28258.43483130326, 28430.0, 28480.572791408147, 28487.57268640972, 28502.857485712855, 1149.747055647756, 1221.883921027501, 1266.3858028855998, 1312.8490223624406, 1352.317079874657, 12578.427696537932, 14362.353694083791, 14724.0, 14729.660311111282, 14860.70278594422, 189355.0, 201445.0, 201674.0, 201866.96266074595, 202303.0, 57604.0, 58036.419635803104, 58062.4193758057, 58112.70943645255, 58165.418345816, 1306.9346532673328, 1524.1540944775534, 1529.8852586056091, 1544.4208421841809, 1550.9534713958599, 26097.478050439266, 27392.45215095727, 27807.02675406351, 27835.860820695765, 27912.0, 47685.0, 47731.76134119307, 47830.760846196936, 47836.0, 47836.760816195696, 309707.9029209679, 309951.0, 310972.3354149644, 312299.0, 312830.0, 30221.395572088873, 30784.53823192609, 31734.52398214075, 31822.52266216055, 32311.35377292488, 39980.000499987735, 40133.79933100316, 40689.59310406858, 41087.0, 41216.587834121274, 28910.566341505313, 29080.7091929078, 29165.562516561844, 29787.55318670265, 29955.850220748758, 28929.276768080126, 29117.708822912347, 29193.85403072971, 29814.10557683273, 29865.701342987162, 32644.83677581597, 34217.0, 34249.82875085609, 34357.828210859785, 34490.0, 28761.28096797513, 29285.26786830261, 29293.560596591495, 29783.851080744458, 30019.949301774333, 23360.6495902565, 23508.11935045158, 23523.529529409658, 23565.0, 23974.0], "value": 18331.052312873304, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.15167844170344, 409.6538425030849, 424.8067129456098, 437.90585024219797, 444.93882091212464, 20277.695834562455, 21184.0, 22339.553208935813, 22525.211617593388, 22581.209657661984, 17941.551461213476, 22923.656145157795, 23232.070717171337, 23904.641430378557, 24215.636765448533, 6751.493637977147, 6834.316568343175, 7108.324709152648, 7110.60891650959, 7131.8217044573785, 6875.724971001154, 7025.192102908154, 7054.294570542956, 7066.116735408074, 7074.292570742936, 6640.7343706251695, 6930.099087118668, 6987.441004719611, 7025.824354391156, 7045.330693584101, 6651.268360480343, 6923.757668481604, 6957.339052790002, 6979.092717946685, 7003.7548685795755, 6747.426468750162, 6799.66001699918, 6837.863242735118, 6842.521023528355, 6847.349501797321, 22486.88756556207, 26426.471470570672, 27382.726172738217, 27985.440291194267, 28229.576556351672, 3029.8939537116094, 3031.2118849099174, 3046.5430185472137, 3046.8628911698806, 3080.3839232153437, 16267.755983660254, 17019.74470382933, 17310.307587696476, 17316.826831731647, 17339.04635245061, 2120.4592828828704, 2159.676048592708, 2191.616467118248, 2203.6694495825595, 2426.6845310109575, 127942.36028819889, 166438.0, 177999.0, 179270.0, 179597.1020144904, 20842.68735968962, 21064.473388165112, 21112.67760546339, 21171.576568468852, 21272.0, 31527.84236078805, 31705.52441713376, 31850.0, 31923.042308730775, 32065.679343206735, 10782.514786834612, 10850.565977360975, 10866.61966831157, 10904.727381815517, 11173.664790056311, 9175.577785443267, 9175.678851240174, 9183.494907780136, 9220.769480763034, 9338.439693618404, 7851.450398472166, 8110.432269741062, 9525.952370238105, 9634.470104144339, 9752.512374381253, 3633.4368172933237, 4141.192467468875, 4262.616364527145, 4294.377315289265, 4297.269464191095, 824.2664029014182, 892.6295587331315, 906.2523418180028, 945.4280160502797, 951.4671783801093, 16510.015523687474, 16754.4135955241, 18766.249350025846, 22455.10179592798, 22674.546509070056, 941.7457286532598, 942.5852624845046, 958.8561715742555, 966.183574879226, 973.3478569358547, 9736.464494452732, 10512.842307365396, 11844.466998985066, 11857.881421185677, 11872.34702091376, 766848.0, 767495.0, 773563.1321843468, 774925.0, 792338.0, 123501.0, 129099.0, 139244.0, 139318.9102163448, 167044.0, 1110.2505808579176, 1194.5580135349976, 1353.4383230959238, 1364.9590512284647, 1367.6102310841363, 17459.738103928707, 20479.976001199582, 21069.262575809775, 21081.578368432853, 21103.8944805275, 63999.68000159969, 65288.67355663191, 65456.34543654693, 65625.34374656192, 66362.66818665876, 93433.05254154778, 94424.05575944428, 96075.0, 96390.0, 98146.0, 11672.0, 11715.062794976551, 11741.882581174421, 12067.879321206676, 12083.275003499819, 12178.75642487163, 13150.539731109366, 13156.868431315565, 13168.539101131415, 13472.79790803119, 9645.0, 10300.278980471296, 11518.251313664585, 11532.25040372373, 11664.708382290171, 8734.737957861274, 10195.0, 10537.841932371173, 10730.839037414602, 10855.131589472665, 11495.137864660017, 11810.468528916392, 11826.93557579822, 11834.644960651194, 11949.22330048544, 9660.860220428249, 11648.0, 11664.708382290512, 11699.824502632297, 11908.642740717793, 11372.829407558727, 11401.713138700678, 11412.543498259976, 11662.70843228926, 11716.297022178696], "value": 11782.970237798563, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1586.5319730679448, 1639.4753678822779, 1642.162497126465, 1649.5876030992251, 1656.8757343199263, 9892.911779704245, 10152.593896244141, 10853.67438976831, 11441.485133169017, 11467.19729618927, 10992.670219893416, 11343.319400835935, 11348.829767553494, 11351.262167959097, 11384.544618215261, 2316.999999999996, 2358.7759162879584, 2364.3498038039593, 2371.0989823866994, 2377.3224630980176, 2007.2071531744953, 2290.335802617239, 2356.422676444265, 2357.457784709518, 2372.9288121356385, 2081.740546969077, 2245.9101635934544, 2368.6802281691926, 2375.9168429104984, 2393.4255778613183, 2330.7086614173227, 2343.121329501437, 2350.1421980976993, 2354.117206047732, 2355.5288942211455, 2095.172406899266, 2259.728832540089, 2331.5220379822094, 2336.3458231695154, 2357.446000189954, 9796.559154838049, 11453.026492748126, 11669.591564295293, 11738.706532336675, 11741.412929353499, 5598.440155984369, 5725.713714314267, 5759.22250496186, 5762.510186634141, 5763.510101641324, 3294.374068926896, 3417.8638351030604, 3498.877539286138, 3527.4356103023392, 3546.6098729139776, 1015.7816069545083, 1102.1513434655271, 1156.5157981246223, 1203.1818363512807, 1209.3288225035103, 16102.597435064217, 16142.273597688014, 16153.192340383051, 16160.676786464204, 16163.111028893407, 4349.630281426114, 4643.558861908114, 4702.130105930345, 4732.503087175797, 4750.501197374295, 13559.66100847467, 13596.592102236751, 13618.387172577257, 13618.659533511742, 13669.931650341883, 5787.247657804459, 5844.47399734026, 5854.765809367577, 5862.530997520188, 5890.617109887844, 4471.463424389094, 4892.804287828447, 4942.258661200849, 5111.974440127776, 5223.686578805284, 3307.4873394623874, 3879.631435013669, 3910.3156947534067, 3999.1801680655976, 4059.736117152435, 1034.7464871106627, 1040.2250323509033, 1042.775803202315, 1047.5233768635253, 1057.212376779289, 841.7600983719733, 968.4625033106553, 987.2842189412762, 990.0842466264015, 1033.5245786937942, 6662.766803161866, 6705.530612857053, 6710.396064354231, 6961.82595435108, 6982.93017069833, 984.3404918704485, 1038.6912490262173, 1052.5684469367523, 1084.224779282801, 1087.749817541954, 4200.663946884242, 4220.198162349144, 4320.524742278347, 4322.481302243688, 4385.210662080856, 13807.792883106762, 14310.85689143116, 14324.641883952985, 14345.282735863166, 14366.209858457885, 14104.506342277971, 14172.645683857985, 14279.85720142806, 14317.0, 14356.49752258666, 10603.151747860153, 10723.410212438235, 10774.46127693628, 10785.514651840685, 10808.405537695502, 6428.292887782247, 6596.538242322991, 6725.73097076131, 6751.45988320943, 6831.59010459374, 12185.51257949672, 12502.937485312515, 12735.0, 12861.742765144832, 12865.649106844021, 12899.613011609667, 12937.482500700244, 12956.222626642431, 13078.28069456189, 13112.672183195114, 8769.473831570127, 8838.027816940003, 8917.42036767608, 8968.327375446997, 8986.281097512052, 10440.26918115755, 10689.144868410353, 10774.46127693628, 10785.298955567863, 10793.838092428778, 9198.494082825242, 9294.767630809283, 9295.907040929504, 9376.109269619375, 9458.385204961656, 8903.955480222558, 9027.684031058881, 9078.228350590274, 9089.909100908906, 9118.954405228196, 7974.322182614311, 8083.7170699025255, 8129.878051829345, 8195.795105122419, 8205.753827385188, 5047.470015648304, 6065.848353791191, 6090.177825993434, 6199.225096862892, 6222.533310001678, 6002.279726432856, 6055.273367195965, 6157.84605384869, 6168.9691551541955, 6300.369963003663], "value": 5212.482391559009, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.89059118151334, 396.7936672930076, 399.4008986520218, 402.29598203144496, 406.69904270839567, 4925.458199598051, 5472.5621950243985, 5519.902437073912, 5570.359408668, 5705.343885453174, 4371.781410929457, 4999.500049994998, 5221.895562088756, 5345.385280692712, 5508.421615730351, 4241.618254357115, 4509.286471140962, 4511.458624965009, 4518.007601600234, 4556.0, 4017.5982401759884, 4183.728057676241, 4190.371444283352, 4470.217711900421, 4531.456225252974, 4415.542870852627, 4441.333799930004, 4468.575485328888, 4482.708623939449, 4517.232070548015, 3686.6313368663054, 3909.3354129798004, 4461.754603496806, 4545.886352841172, 4567.451905771329, 3607.8196090195383, 3648.0, 4151.571330771502, 4155.480564929384, 4188.937165942513, 4912.828051018198, 4916.50834916509, 4927.50724927508, 5036.84889453317, 5227.163653815409, 4451.5103338632725, 4732.491779356947, 5054.82308119214, 5085.847424577269, 5217.799906021636, 4827.513830377358, 4983.287450849104, 5563.833085007456, 5689.857753556153, 5714.571407144481, 3852.5569559500454, 3920.471016303644, 3944.7435916665327, 4594.448666160049, 4628.467726211461, 5272.710000949946, 5356.3572371315695, 5388.43421440747, 5826.126081087828, 5880.882382352372, 4218.261804184255, 4473.696046536036, 4656.115338085753, 4722.008378240539, 4741.83403580873, 4129.2773764591075, 4546.886012926854, 4763.809447622057, 4774.665773395829, 4801.303810947463, 4280.72175308604, 4684.601808846286, 4751.928721069186, 4892.186992479338, 5142.742862856842, 5350.545203657733, 5746.798862039808, 5778.306603207642, 5817.360090390053, 5829.7376618052285, 1421.8933579981542, 1507.8663690877286, 1595.4045954045955, 1658.7428948513, 1674.5264167532494, 1118.7358285137905, 1124.0333313350511, 1206.897413719826, 1227.5028613411582, 1254.943527541263, 5348.88718956018, 5459.344878614512, 5483.451654834565, 5503.816679413945, 5567.47109024642, 4988.326575912278, 5192.182736042355, 5211.218317252366, 5518.420565840609, 5632.295963004624, 4577.61090307321, 5119.259451786336, 5365.409804921455, 5374.951884382586, 5490.6431081979545, 2570.506608589665, 2875.079974408169, 3093.808883579838, 3124.375124975014, 3151.8896838611, 5889.381614930457, 5936.387989284701, 6008.519318454513, 6173.135760993465, 6225.595336303126, 5836.737346819404, 6133.355997620275, 6167.638636942787, 6183.690815459209, 6283.371662833772, 5400.810971615974, 5616.680080181154, 6068.939310606926, 6257.405546473078, 6317.526185536011, 5081.491850815037, 5307.548858347083, 5817.418258174149, 5995.310539288038, 6010.3088144863905, 4826.638002149923, 4943.703377797344, 5626.915596265977, 5786.392428794916, 6097.5731698780655, 4995.875103122451, 5105.821296254613, 5738.368779434176, 6090.9949858272885, 6192.411720886508, 3871.3933717508044, 4073.348264277701, 4697.906041879075, 4709.199436095941, 4721.858344249678, 5521.8310175817005, 5796.449337312949, 5845.503132233808, 5956.515438468545, 6131.294901086432, 4818.8795280118275, 5476.507114359727, 5620.229627667271, 5753.741081651252, 5811.76752929895, 5161.974190129025, 5748.310202775527, 5786.594938354275, 5865.648061116347, 5928.822135335947, 5107.438181800073, 5202.583793296603, 5532.806351777668, 5647.548196144381, 5678.0, 4719.787609557647, 5370.704611246263, 5722.599418040697, 5776.277965254343, 5781.421857814353, 5036.924446133237, 5055.1406260936465, 5469.835904922858, 5619.847931174181, 5705.62913410627], "value": 4367.924031134546, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [763.3282711214131, 771.1748429180778, 803.204827221051, 851.5401683091131, 897.125302829741, 15766.975146615461, 17511.912440437813, 17750.0, 17770.022648754315, 17772.91113544432, 15834.604134896634, 16477.0, 16615.919965202283, 16626.919250248724, 16693.415730449437, 997.0727223681955, 1041.2450973044558, 1043.0091413157493, 1045.9110975567087, 1047.4605578127232, 1000.7298029532059, 1039.7036844499319, 1057.9312344697607, 1058.391424930664, 1059.3749687684265, 1082.3992684060383, 1095.906847917928, 1098.3849044535086, 1100.8568886044802, 1101.1136035491427, 1094.0099210214769, 1095.3920574081383, 1096.0, 1096.1723898456694, 1098.5825386353215, 811.703728139225, 813.2030610002172, 816.7182322098867, 819.0703551469065, 819.1194465949128, 17148.657026859517, 17476.475705728848, 17633.823661763345, 18253.54366140844, 18426.170822313026, 12312.569060082944, 13226.933865330708, 14244.359003844851, 14701.705965880623, 14746.557603271918, 12104.878951210463, 12229.93885030578, 12235.510579576807, 12286.324252166127, 12581.308028058453, 3780.9479248588787, 3909.5894931032117, 3928.253631809947, 3932.390479475686, 4064.5325787534516, 97960.02039979651, 108411.99999999921, 110338.44830775875, 115442.0, 116321.0, 11040.392778397105, 11255.774884502263, 11266.830997535044, 11750.882491175149, 11766.823497647541, 21491.67762483564, 22524.662130068064, 22688.65967010496, 22767.658485122738, 22823.429414264778, 7217.700813853452, 8014.398920081017, 8033.236842499953, 8066.23370779775, 8177.304929080976, 4566.634669226454, 4605.815767369335, 4644.349791029259, 4667.719936803803, 4696.990147118386, 3424.109731469786, 3479.356319080978, 3485.4946032825096, 3499.4575840744724, 3532.3994920863515, 510.57877251267854, 613.3192156706052, 621.5493767018875, 622.4957784194817, 630.0140280461064, 672.8015235505537, 680.4318394140848, 697.5884228305324, 725.804032911111, 730.8246020955039, 10382.273240873066, 10684.145268378512, 10720.41037742916, 10993.6152234673, 11118.944405277922, 568.1705306236022, 580.5993864233666, 586.9354371019184, 603.3724926076831, 605.4581149870841, 5087.745612719349, 5219.138842091088, 5222.947770522323, 5233.764480598382, 5240.842774716688, 109500.0, 110838.0, 112056.0, 112838.0, 116929.0, 83191.16808831834, 83748.58125709454, 85652.0, 93833.0, 97151.0, 71272.28727712863, 71547.64226178835, 72520.0, 72621.27378726251, 74458.0, 10996.780064398827, 11120.777584448428, 11269.887301127212, 11481.712957176138, 11482.655520334069, 51250.74374628103, 51822.481775183274, 52244.73877630587, 52639.73680131575, 52896.94206115933, 53205.73397132989, 54701.72649136729, 65724.34275657182, 65808.0128798078, 65883.67058164679, 11409.828852567383, 12770.91447226959, 13004.804927926278, 13213.801792972921, 13263.602091937257, 12184.51261949512, 14715.484958026418, 15173.696526069638, 15519.84480155184, 15852.920735396248, 10386.480675966322, 13209.603711888658, 13919.9458878764, 14215.431382744575, 14233.715325693222, 11286.943565282121, 13032.609021729364, 13600.795988060385, 13620.0, 13632.113912595649, 13397.0, 13575.79636305476, 13855.334880323511, 14290.28548572588, 14385.496507622183, 9872.259580531345, 9969.30214884951, 10155.238357123097, 10352.0, 10926.508307126358, 9733.464659443798, 10390.844137338097, 11242.7751444969, 11490.080793536665, 11573.942130289295], "value": 7882.547469427058, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1536.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 122.0, 121.0, 115.0, 120.0, 120.0, 119.0, 120.0, 119.0], "value": 118.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 121.0, 139.0, 160.0, 109.0, 109.0, 110.0, 110.0, 127.0, 111.0], "value": 123.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 205.0, 197.0, 201.0, 198.0, 196.0, 198.0, 196.0, 200.0, 195.0], "value": 198.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 49.0, 104.0, 84.0, 87.0, 68.0, 87.0, 72.0, 58.0], "value": 76.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 90.0, 115.0, 86.0, 57.0, 101.0, 113.0, 77.0, 57.0, 94.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 108.0, 87.0, 137.0, 105.0, 89.0, 97.0, 97.0, 133.0, 110.0], "value": 105.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 149.0, 147.0, 96.0, 102.0, 96.0, 96.0, 119.0, 95.0, 133.0], "value": 114.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 146.0, 139.0, 138.0, 92.0, 92.0, 91.0, 95.0, 93.0, 89.0], "value": 108.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 65.0, 65.0, 66.0, 68.0, 66.0, 68.0, 66.0, 67.0], "value": 65.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 62.0, 99.0, 94.0, 87.0, 82.0, 50.0, 68.0, 84.0, 85.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 84.0, 91.0, 78.0, 91.0, 87.0, 83.0, 86.0, 86.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 156.0, 144.0, 144.0, 151.0, 153.0, 149.0, 148.0, 179.0, 144.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 166.0, 160.0, 168.0, 162.0, 165.0, 160.0, 165.0, 164.0, 163.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 63.0, 64.0, 65.0, 66.0, 66.0, 63.0, 64.0, 64.0], "value": 64.6, "unit": "score"}]}]} 20:16:04 INFO - Return code: 0 20:16:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:16:05 ERROR - # TBPL SUCCESS # 20:16:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:16:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:16:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:16:05 INFO - 2017-06-04 20:16:05.087 screenresolution[1658:11104] starting screenresolution argv=screenresolution get 20:16:05 INFO - 2017-06-04 20:16:05.090 screenresolution[1658:11104] Display 0: 1600x1200x32@60 20:16:05 INFO - 2017-06-04 20:16:05.096 screenresolution[1659:11107] starting screenresolution argv=screenresolution list 20:16:05 INFO - Available Modes on Display 0 20:16:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:16:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:16:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:16:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:16:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:16:05 INFO - Intel Iris: 20:16:05 INFO - Chipset Model: Intel Iris 20:16:05 INFO - Type: GPU 20:16:05 INFO - Bus: Built-In 20:16:05 INFO - VRAM (Dynamic, Max): 1536 MB 20:16:05 INFO - Vendor: Intel (0x8086) 20:16:05 INFO - Device ID: 0x0a2e 20:16:05 INFO - Revision ID: 0x0009 20:16:05 INFO - Displays: 20:16:05 INFO - Display: 20:16:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:16:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:16:05 INFO - Display Serial Number: 1600x1200 60 20:16:05 INFO - Main Display: Yes 20:16:05 INFO - Mirror: Off 20:16:05 INFO - Online: Yes 20:16:05 INFO - Rotation: Supported 20:16:05 INFO - Return code: 0 20:16:05 INFO - Running post-action listener: _resource_record_post_action 20:16:05 INFO - [mozharness: 2017-06-05 03:16:05.333230Z] Finished run-tests step (success) 20:16:05 INFO - Running post-run listener: _resource_record_post_run 20:16:05 INFO - Total resource usage - Wall time: 1191s; CPU: 55.0%; Read bytes: 34557952; Write bytes: 423951872; Read time: 328; Write time: 1521 20:16:05 INFO - TinderboxPrint: CPU usage
54.5% 20:16:05 INFO - TinderboxPrint: I/O read bytes / time
34,557,952 / 328 20:16:05 INFO - TinderboxPrint: I/O write bytes / time
423,951,872 / 1,521 20:16:05 INFO - TinderboxPrint: CPU idle
2,168.0 (45.5%) 20:16:05 INFO - TinderboxPrint: CPU system
959.2 (20.1%) 20:16:05 INFO - TinderboxPrint: CPU user
1,638.6 (34.4%) 20:16:05 INFO - TinderboxPrint: Swap in / out
152,920,064 / 0 20:16:05 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119802368; Write bytes: 115381760; Read time: 10971; Write time: 317 20:16:05 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 32460800; Write bytes: 297494528; Read time: 303; Write time: 1171 20:16:05 INFO - Running post-run listener: _upload_blobber_files 20:16:05 INFO - Blob upload gear active. 20:16:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:16:05 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 20:16:05 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'] 20:16:05 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 20:16:06 INFO - (blobuploader) - INFO - Open directory for files ... 20:16:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:16:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:16:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:16:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:16:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:16:07 INFO - (blobuploader) - INFO - Done attempting. 20:16:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:16:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:16:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:16:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:16:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:16:08 INFO - (blobuploader) - INFO - Done attempting. 20:16:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:16:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:16:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:16:09 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:16:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:16:09 INFO - (blobuploader) - INFO - Done attempting. 20:16:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:16:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:16:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:16:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:16:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:16:11 INFO - (blobuploader) - INFO - Done attempting. 20:16:11 INFO - (blobuploader) - INFO - Iteration through files over. 20:16:11 INFO - Return code: 0 20:16:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:16:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:16:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d83a0028e38687d974af72b0e1854071fb3f6914be53698c70aa3c46e30b8a49a97137e3c29131ef3073702831603229bba5d839b10df83b49f320d0132a086f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e79fe032278fb80c01c48c851a32642e07841db838ad59dce3448c05ba03bd79b6340b9f4ea5459c60dfd2b87465b6c9ef8c0c2e6dba128f8bde3757734bab1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/027f09e9fc6f8df033c9ee9203a9b04021c415b2e0aac42336df417a85a38376311cb9ea769dedb5eaae8e26d8dd92195a558b95589980121a16324ccca648da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/372ea9606f3a74b2ab5979757684a3de8219ea60ecf1e6d2533d8e0c4dbbb832c4a4c0ac47806130d0f884547524ae53460ff55fb630de7168312d1ec600316f"} 20:16:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:16:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:16:11 INFO - Contents: 20:16:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d83a0028e38687d974af72b0e1854071fb3f6914be53698c70aa3c46e30b8a49a97137e3c29131ef3073702831603229bba5d839b10df83b49f320d0132a086f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e79fe032278fb80c01c48c851a32642e07841db838ad59dce3448c05ba03bd79b6340b9f4ea5459c60dfd2b87465b6c9ef8c0c2e6dba128f8bde3757734bab1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/027f09e9fc6f8df033c9ee9203a9b04021c415b2e0aac42336df417a85a38376311cb9ea769dedb5eaae8e26d8dd92195a558b95589980121a16324ccca648da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/372ea9606f3a74b2ab5979757684a3de8219ea60ecf1e6d2533d8e0c4dbbb832c4a4c0ac47806130d0f884547524ae53460ff55fb630de7168312d1ec600316f"} 20:16:11 INFO - Running post-run listener: copy_logs_to_upload_dir 20:16:11 INFO - Copying logs to upload dir... 20:16:11 INFO - mkdir: /builds/slave/test/build/upload/logs 20:16:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.507134 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-06-04 20:16:11.115232) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-04 20:16:11.119346) ========= 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.xUAri7zf6H/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.iTKhxA83Nd/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d83a0028e38687d974af72b0e1854071fb3f6914be53698c70aa3c46e30b8a49a97137e3c29131ef3073702831603229bba5d839b10df83b49f320d0132a086f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e79fe032278fb80c01c48c851a32642e07841db838ad59dce3448c05ba03bd79b6340b9f4ea5459c60dfd2b87465b6c9ef8c0c2e6dba128f8bde3757734bab1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/027f09e9fc6f8df033c9ee9203a9b04021c415b2e0aac42336df417a85a38376311cb9ea769dedb5eaae8e26d8dd92195a558b95589980121a16324ccca648da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/372ea9606f3a74b2ab5979757684a3de8219ea60ecf1e6d2533d8e0c4dbbb832c4a4c0ac47806130d0f884547524ae53460ff55fb630de7168312d1ec600316f"} build_url:https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011046 build_url: 'https://queue.taskcluster.net/v1/task/YmubvILwTymedS21mpWXwA/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d83a0028e38687d974af72b0e1854071fb3f6914be53698c70aa3c46e30b8a49a97137e3c29131ef3073702831603229bba5d839b10df83b49f320d0132a086f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0e79fe032278fb80c01c48c851a32642e07841db838ad59dce3448c05ba03bd79b6340b9f4ea5459c60dfd2b87465b6c9ef8c0c2e6dba128f8bde3757734bab1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/027f09e9fc6f8df033c9ee9203a9b04021c415b2e0aac42336df417a85a38376311cb9ea769dedb5eaae8e26d8dd92195a558b95589980121a16324ccca648da", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/372ea9606f3a74b2ab5979757684a3de8219ea60ecf1e6d2533d8e0c4dbbb832c4a4c0ac47806130d0f884547524ae53460ff55fb630de7168312d1ec600316f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-04 20:16:11.154515) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 20:16:11.155042) ========= 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.xUAri7zf6H/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.iTKhxA83Nd/Listeners TMPDIR=/var/folders/hr/szhjjxc16kx9yf5ff1qqhnn400000w/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.005273 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-04 20:16:11.203971) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-04 20:16:11.204328) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-04 20:16:11.204713) ========= ========= Total master_lag: 0.18 =========