builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0216 starttime: 1495223716.57 results: success (0) buildid: 20170519110323 builduid: 75edb1ae74334715baf896ab50844b55 revision: 22a02cb46ff22cc1d5428e138a956e349770e4ab ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 12:55:16.572416) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 12:55:16.572898) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 12:55:16.573175) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 12:55:16.832452) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-19 12:55:16.832720) ========= 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.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-19 12:55:16-- 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% 145M=0s 2017-05-19 12:55:16 (145 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.156141 ========= master_lag: 3.29 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-05-19 12:55:20.277389) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 12:55:20.277739) ========= 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.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.026984 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 12:55:20.331873) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-19 12:55:20.332712) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 22a02cb46ff22cc1d5428e138a956e349770e4ab --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 22a02cb46ff22cc1d5428e138a956e349770e4ab --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-19 12:55:20,675 truncating revision to first 12 chars 2017-05-19 12:55:20,675 Setting DEBUG logging. 2017-05-19 12:55:20,675 attempt 1/10 2017-05-19 12:55:20,675 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/22a02cb46ff2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-19 12:55:22,681 unpacking tar archive at: mozilla-beta-22a02cb46ff2/testing/mozharness/ program finished with exit code 0 elapsedTime=2.290190 ========= master_lag: 0.27 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-19 12:55:22.888188) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 12:55:22.888513) ========= script_repo_revision: 22a02cb46ff22cc1d5428e138a956e349770e4ab ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 12:55:22.891935) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 12:55:22.892221) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 12:55:22.910724) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-05-19 12:55:22.911084) ========= /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.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:55:23 INFO - MultiFileLogger online at 20170519 12:55:23 in /builds/slave/test 12:55:23 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 12:55:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:55:23 INFO - {'append_to_log': False, 12:55:23 INFO - 'base_work_dir': '/builds/slave/test', 12:55:23 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:55:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:55:23 INFO - 'branch': 'Mozilla-Beta', 12:55:23 INFO - 'buildbot_json_path': 'buildprops.json', 12:55:23 INFO - 'config_files': ('talos/mac_config.py',), 12:55:23 INFO - 'default_actions': ('clobber', 12:55:23 INFO - 'read-buildbot-config', 12:55:23 INFO - 'download-and-extract', 12:55:23 INFO - 'populate-webroot', 12:55:23 INFO - 'create-virtualenv', 12:55:23 INFO - 'install', 12:55:23 INFO - 'run-tests'), 12:55:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:55:23 INFO - 'download_minidump_stackwalk': True, 12:55:23 INFO - 'download_symbols': 'ondemand', 12:55:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:55:23 INFO - 'tooltool.py': '/tools/tooltool.py', 12:55:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:55:23 INFO - '/tools/misc-python/virtualenv.py')}, 12:55:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:55:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:55:23 INFO - 'gecko_profile': False, 12:55:23 INFO - 'gecko_profile_interval': 0, 12:55:23 INFO - 'installer_path': 'installer.exe', 12:55:23 INFO - 'log_level': 'info', 12:55:23 INFO - 'log_name': 'talos', 12:55:23 INFO - 'log_to_console': True, 12:55:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:55:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:55:23 INFO - 'opt_config_files': (), 12:55:23 INFO - 'pip_index': False, 12:55:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:55:23 INFO - 'cmd': ('bash', 12:55:23 INFO - '-c', 12:55:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:55:23 INFO - 'enabled': True, 12:55:23 INFO - 'halt_on_failure': False, 12:55:23 INFO - 'name': 'check_screen_resolution'},), 12:55:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:55:23 INFO - 'cmd': ('bash', 12:55:23 INFO - '-c', 12:55:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:55:23 INFO - 'enabled': True, 12:55:23 INFO - 'halt_on_failure': False, 12:55:23 INFO - 'name': 'check_screen_resolution'},), 12:55:23 INFO - 'run_cmd_checks_enabled': True, 12:55:23 INFO - 'suite': 'dromaeojs-e10s', 12:55:23 INFO - 'system_bits': '32', 12:55:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:55:23 INFO - 'title': 't-yosemite-r7-0216', 12:55:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:55:23 INFO - 'use_talos_json': True, 12:55:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:55:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:55:23 INFO - 'work_dir': 'build'} 12:55:23 INFO - [mozharness: 2017-05-19 19:55:23.111905Z] Running clobber step. 12:55:23 INFO - Running pre-action listener: _resource_record_pre_action 12:55:23 INFO - Running main action method: clobber 12:55:23 INFO - rmtree: /builds/slave/test/build 12:55:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:55:25 INFO - Running post-action listener: _resource_record_post_action 12:55:25 INFO - [mozharness: 2017-05-19 19:55:25.477257Z] Finished clobber step (success) 12:55:25 INFO - [mozharness: 2017-05-19 19:55:25.477544Z] Running read-buildbot-config step. 12:55:25 INFO - Running pre-action listener: _resource_record_pre_action 12:55:25 INFO - Running main action method: read_buildbot_config 12:55:25 INFO - Using buildbot properties: 12:55:25 INFO - { 12:55:25 INFO - "project": "", 12:55:25 INFO - "product": "firefox", 12:55:25 INFO - "script_repo_revision": "production", 12:55:25 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 12:55:25 INFO - "repository": "", 12:55:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 12:55:25 INFO - "buildid": "20170519110323", 12:55:25 INFO - "pgo_build": "False", 12:55:25 INFO - "basedir": "/builds/slave/test", 12:55:25 INFO - "buildnumber": 48, 12:55:25 INFO - "slavename": "t-yosemite-r7-0216", 12:55:25 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:55:25 INFO - "platform": "yosemite_r7", 12:55:25 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 12:55:25 INFO - "branch": "mozilla-beta", 12:55:25 INFO - "repo_path": "releases/mozilla-beta", 12:55:25 INFO - "revision": "22a02cb46ff22cc1d5428e138a956e349770e4ab", 12:55:25 INFO - "stage_platform": "macosx64", 12:55:25 INFO - "builduid": "75edb1ae74334715baf896ab50844b55", 12:55:25 INFO - "slavebuilddir": "test" 12:55:25 INFO - } 12:55:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 12:55:25 INFO - Running post-action listener: _resource_record_post_action 12:55:25 INFO - [mozharness: 2017-05-19 19:55:25.483180Z] Finished read-buildbot-config step (success) 12:55:25 INFO - [mozharness: 2017-05-19 19:55:25.483407Z] Running download-and-extract step. 12:55:25 INFO - Running pre-action listener: _resource_record_pre_action 12:55:25 INFO - Running main action method: download_and_extract 12:55:25 INFO - mkdir: /builds/slave/test/build/tests 12:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:55:25 INFO - trying https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 12:55:25 INFO - Downloading https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:55:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 12:55:26 INFO - Downloaded 1287 bytes. 12:55:26 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:55:26 INFO - Using the following test package requirements: 12:55:26 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:55:26 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 12:55:26 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:55:26 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:55:26 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 12:55:26 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:55:26 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 12:55:26 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:55:26 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:55:26 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 12:55:26 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:55:26 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:55:26 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 12:55:26 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:55:26 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 12:55:26 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:55:26 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 12:55:26 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:55:26 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 12:55:26 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 12:55:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:55:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:55:26 INFO - Fetch https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:55:27 INFO - Expected file size: 51392482 12:55:27 INFO - Obtained file size: 51392482 12:55:43 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:55:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:55:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:55:43 INFO - Fetch https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:55:45 INFO - Expected file size: 51392482 12:55:45 INFO - Obtained file size: 51392482 12:56:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 12:56:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 12:56:00 INFO - Fetch https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 12:56:00 INFO - Expected file size: 14404449 12:56:00 INFO - Obtained file size: 14404449 12:56:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:01 INFO - trying https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:56:01 INFO - Downloading https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 12:56:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 12:56:03 INFO - Downloaded 59541626 bytes. 12:56:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:56:03 INFO - mkdir: /builds/slave/test/properties 12:56:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:56:03 INFO - Writing to file /builds/slave/test/properties/build_url 12:56:03 INFO - Contents: 12:56:03 INFO - build_url:https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:56:03 INFO - Running post-action listener: _resource_record_post_action 12:56:03 INFO - Running post-action listener: set_extra_try_arguments 12:56:03 INFO - [mozharness: 2017-05-19 19:56:03.749913Z] Finished download-and-extract step (success) 12:56:03 INFO - [mozharness: 2017-05-19 19:56:03.750060Z] Running populate-webroot step. 12:56:03 INFO - Running pre-action listener: _resource_record_pre_action 12:56:03 INFO - Running main action method: populate_webroot 12:56:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:56:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:56:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:56:03 INFO - '--tppagecycles', 12:56:03 INFO - '7'], 12:56:03 INFO - 'tests': ['tsvgm']}}, 12:56:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:56:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:56:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:56:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:56:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:56:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:56:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:56:03 INFO - 'talos_options': ['--disable-e10s'], 12:56:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:56:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:56:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:56:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:56:03 INFO - 'talos_options': ['--disable-e10s'], 12:56:03 INFO - 'tests': ['damp', 'tps']}, 12:56:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:56:03 INFO - 'tests': ['damp', 'tps']}, 12:56:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:56:03 INFO - 'tests': ['dromaeo_dom']}, 12:56:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:56:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:56:03 INFO - 'tests': ['basic_compositor_video']}, 12:56:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:56:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:56:03 INFO - 'tests': ['a11yr', 12:56:03 INFO - 'ts_paint', 12:56:03 INFO - 'tpaint', 12:56:03 INFO - 'sessionrestore', 12:56:03 INFO - 'sessionrestore_no_auto_restore', 12:56:03 INFO - 'tabpaint']}, 12:56:03 INFO - 'other-e10s': {'tests': ['a11yr', 12:56:03 INFO - 'ts_paint', 12:56:03 INFO - 'tpaint', 12:56:03 INFO - 'sessionrestore', 12:56:03 INFO - 'sessionrestore_no_auto_restore', 12:56:03 INFO - 'tabpaint']}, 12:56:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:56:03 INFO - 'ts_paint', 12:56:03 INFO - 'tpaint', 12:56:03 INFO - 'sessionrestore', 12:56:03 INFO - 'sessionrestore_no_auto_restore', 12:56:03 INFO - 'tabpaint']}, 12:56:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:56:03 INFO - 'ts_paint', 12:56:03 INFO - 'tpaint', 12:56:03 INFO - 'sessionrestore', 12:56:03 INFO - 'sessionrestore_no_auto_restore', 12:56:03 INFO - 'tabpaint']}, 12:56:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:56:03 INFO - 'tests': ['a11yr', 12:56:03 INFO - 'ts_paint', 12:56:03 INFO - 'tpaint', 12:56:03 INFO - 'sessionrestore', 12:56:03 INFO - 'sessionrestore_no_auto_restore', 12:56:03 INFO - 'tabpaint']}, 12:56:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:56:03 INFO - 'tests': ['a11yr', 12:56:03 INFO - 'ts_paint', 12:56:03 INFO - 'tpaint', 12:56:03 INFO - 'sessionrestore', 12:56:03 INFO - 'sessionrestore_no_auto_restore', 12:56:03 INFO - 'tabpaint']}, 12:56:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:56:03 INFO - 'tests': ['tsvgx', 12:56:03 INFO - 'tsvgr_opacity', 12:56:03 INFO - 'tart', 12:56:03 INFO - 'tscrollx', 12:56:03 INFO - 'cart', 12:56:03 INFO - 'tsvg_static']}, 12:56:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:56:03 INFO - 'tsvgr_opacity', 12:56:03 INFO - 'tart', 12:56:03 INFO - 'tscrollx', 12:56:03 INFO - 'cart', 12:56:03 INFO - 'tsvg_static']}, 12:56:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:56:03 INFO - 'talos_options': ['--disable-e10s'], 12:56:03 INFO - 'tests': ['tp5o']}, 12:56:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:56:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:56:03 INFO - 'talos_options': ['--disable-e10s', 12:56:03 INFO - '--xperf_path', 12:56:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:56:03 INFO - 'tests': ['tp5n']}, 12:56:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:56:03 INFO - 'talos_options': ['--xperf_path', 12:56:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:56:03 INFO - 'tests': ['tp5n']}}, 12:56:03 INFO - 'talos.zip': {'path': '', 12:56:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:56:03 INFO - Running post-action listener: _resource_record_post_action 12:56:03 INFO - [mozharness: 2017-05-19 19:56:03.768384Z] Finished populate-webroot step (success) 12:56:03 INFO - [mozharness: 2017-05-19 19:56:03.768514Z] Running create-virtualenv step. 12:56:03 INFO - Running pre-action listener: _resource_record_pre_action 12:56:03 INFO - Running main action method: create_virtualenv 12:56:03 INFO - Creating virtualenv /builds/slave/test/build/venv 12:56:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:56:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:56:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:56:03 INFO - Using real prefix '/tools/python27' 12:56:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:56:04 INFO - Installing distribute.............................................................................................................................................................................................done. 12:56:07 INFO - Installing pip.................done. 12:56:07 INFO - Return code: 0 12:56:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:56:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:56:07 INFO - Reading from file tmpfile_stdout 12:56:07 INFO - Output received: 12:56:07 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:56:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c14fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c152768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3b3cb7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2dd30>, '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 0x10be54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf22be8>, '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.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:56:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:56:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:56:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIe7B6J4M/Render', 12:56:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:56:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:56:07 INFO - 'HOME': '/Users/cltbld', 12:56:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:56:07 INFO - 'LOGNAME': 'cltbld', 12:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:07 INFO - 'MOZ_NO_REMOTE': '1', 12:56:07 INFO - 'NO_EM_RESTART': '1', 12:56:07 INFO - 'PAGER': '/bin/cat', 12:56:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:07 INFO - 'PWD': '/builds/slave/test', 12:56:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:56:07 INFO - 'SHELL': '/bin/bash', 12:56:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qdH6Kr2UpC/Listeners', 12:56:07 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:56:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:56:07 INFO - 'USER': 'cltbld', 12:56:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:56:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:07 INFO - 'XPC_FLAGS': '0x0', 12:56:07 INFO - 'XPC_SERVICE_NAME': '0', 12:56:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:08 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:56:08 INFO - Cleaning up... 12:56:08 INFO - Return code: 0 12:56:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12: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')]} 12:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12: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')]} 12:56:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c14fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c152768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3b3cb7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2dd30>, '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 0x10be54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf22be8>, '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.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:56:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:56:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:56:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIe7B6J4M/Render', 12:56:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:56:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:56:08 INFO - 'HOME': '/Users/cltbld', 12:56:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:56:08 INFO - 'LOGNAME': 'cltbld', 12:56:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:08 INFO - 'MOZ_NO_REMOTE': '1', 12:56:08 INFO - 'NO_EM_RESTART': '1', 12:56:08 INFO - 'PAGER': '/bin/cat', 12:56:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:56:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:08 INFO - 'PWD': '/builds/slave/test', 12:56:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:56:08 INFO - 'SHELL': '/bin/bash', 12:56:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qdH6Kr2UpC/Listeners', 12:56:08 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:56:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:56:08 INFO - 'USER': 'cltbld', 12:56:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:56:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:56:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:08 INFO - 'XPC_FLAGS': '0x0', 12:56:08 INFO - 'XPC_SERVICE_NAME': '0', 12:56:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:56:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:08 INFO - Downloading/unpacking psutil>=3.1.1 12:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:56:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:56:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:56:10 INFO - Installing collected packages: psutil 12:56:10 INFO - Running setup.py install for psutil 12:56:10 INFO - building 'psutil._psutil_osx' extension 12:56:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:56:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:56:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:56:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:56:11 INFO - building 'psutil._psutil_posix' extension 12:56:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:56:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:56:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:56:11 INFO - ^ 12:56:11 INFO - 1 warning generated. 12:56:11 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:56:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:56:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:56:11 INFO - Successfully installed psutil 12:56:11 INFO - Cleaning up... 12:56:11 INFO - Return code: 0 12:56:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c14fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c152768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3b3cb7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2dd30>, '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 0x10be54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf22be8>, '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.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:56:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:56:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:56:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIe7B6J4M/Render', 12:56:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:56:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:56:11 INFO - 'HOME': '/Users/cltbld', 12:56:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:56:11 INFO - 'LOGNAME': 'cltbld', 12:56:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:11 INFO - 'MOZ_NO_REMOTE': '1', 12:56:11 INFO - 'NO_EM_RESTART': '1', 12:56:11 INFO - 'PAGER': '/bin/cat', 12:56:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:56:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:11 INFO - 'PWD': '/builds/slave/test', 12:56:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:56:11 INFO - 'SHELL': '/bin/bash', 12:56:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qdH6Kr2UpC/Listeners', 12:56:11 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:56:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:56:11 INFO - 'USER': 'cltbld', 12:56:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:56:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:56:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:11 INFO - 'XPC_FLAGS': '0x0', 12:56:11 INFO - 'XPC_SERVICE_NAME': '0', 12:56:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:56:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:56:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:56:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:56:13 INFO - Installing collected packages: mozsystemmonitor 12:56:13 INFO - Running setup.py install for mozsystemmonitor 12:56:13 INFO - Successfully installed mozsystemmonitor 12:56:13 INFO - Cleaning up... 12:56:13 INFO - Return code: 0 12:56:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:56:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:14 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 0x10c14fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c152768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3b3cb7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2dd30>, '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 0x10be54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf22be8>, '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.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:56:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:56:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:56:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIe7B6J4M/Render', 12:56:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:56:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:56:14 INFO - 'HOME': '/Users/cltbld', 12:56:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:56:14 INFO - 'LOGNAME': 'cltbld', 12:56:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:14 INFO - 'MOZ_NO_REMOTE': '1', 12:56:14 INFO - 'NO_EM_RESTART': '1', 12:56:14 INFO - 'PAGER': '/bin/cat', 12:56:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:56:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:14 INFO - 'PWD': '/builds/slave/test', 12:56:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:56:14 INFO - 'SHELL': '/bin/bash', 12:56:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qdH6Kr2UpC/Listeners', 12:56:14 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:56:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:56:14 INFO - 'USER': 'cltbld', 12:56:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:56:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:56:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:14 INFO - 'XPC_FLAGS': '0x0', 12:56:14 INFO - 'XPC_SERVICE_NAME': '0', 12:56:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:56:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:14 INFO - Downloading/unpacking jsonschema==2.5.1 12:56:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:56:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:16 INFO - Downloading functools32-3.2.3-2.tar.gz 12:56:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:56:16 INFO - warning: no files found matching '*.txt' 12:56:16 INFO - no previously-included directories found matching 'build' 12:56:16 INFO - no previously-included directories found matching 'dist' 12:56:16 INFO - no previously-included directories found matching '.git*' 12:56:16 INFO - Installing collected packages: jsonschema, functools32 12:56:16 INFO - Running setup.py install for functools32 12:56:16 INFO - warning: no files found matching '*.txt' 12:56:16 INFO - no previously-included directories found matching 'build' 12:56:16 INFO - no previously-included directories found matching 'dist' 12:56:16 INFO - no previously-included directories found matching '.git*' 12:56:16 INFO - Successfully installed jsonschema functools32 12:56:16 INFO - Cleaning up... 12:56:17 INFO - Return code: 0 12:56:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:56:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:17 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 0x10c14fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c152768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3b3cb7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2dd30>, '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 0x10be54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf22be8>, '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.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:56:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:56:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:56:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIe7B6J4M/Render', 12:56:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:56:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:56:17 INFO - 'HOME': '/Users/cltbld', 12:56:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:56:17 INFO - 'LOGNAME': 'cltbld', 12:56:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:17 INFO - 'MOZ_NO_REMOTE': '1', 12:56:17 INFO - 'NO_EM_RESTART': '1', 12:56:17 INFO - 'PAGER': '/bin/cat', 12:56:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:56:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:17 INFO - 'PWD': '/builds/slave/test', 12:56:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:56:17 INFO - 'SHELL': '/bin/bash', 12:56:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qdH6Kr2UpC/Listeners', 12:56:17 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:56:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:56:17 INFO - 'USER': 'cltbld', 12:56:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:56:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:56:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:17 INFO - 'XPC_FLAGS': '0x0', 12:56:17 INFO - 'XPC_SERVICE_NAME': '0', 12:56:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:56:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:56:17 INFO - Cleaning up... 12:56:17 INFO - Return code: 0 12:56:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:56:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:17 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 0x10c14fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c152768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3b3cb7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2dd30>, '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 0x10be54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf22be8>, '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.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:56:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:56:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:56:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIe7B6J4M/Render', 12:56:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:56:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:56:17 INFO - 'HOME': '/Users/cltbld', 12:56:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:56:17 INFO - 'LOGNAME': 'cltbld', 12:56:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:17 INFO - 'MOZ_NO_REMOTE': '1', 12:56:17 INFO - 'NO_EM_RESTART': '1', 12:56:17 INFO - 'PAGER': '/bin/cat', 12:56:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:56:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:17 INFO - 'PWD': '/builds/slave/test', 12:56:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:56:17 INFO - 'SHELL': '/bin/bash', 12:56:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qdH6Kr2UpC/Listeners', 12:56:17 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:56:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:56:17 INFO - 'USER': 'cltbld', 12:56:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:56:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:56:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:17 INFO - 'XPC_FLAGS': '0x0', 12:56:17 INFO - 'XPC_SERVICE_NAME': '0', 12:56:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:56:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:17 INFO - Downloading/unpacking blobuploader==1.2.4 12:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:18 INFO - Downloading blobuploader-1.2.4.tar.gz 12:56:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:56:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:56:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:19 INFO - Downloading docopt-0.6.1.tar.gz 12:56:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:56:19 INFO - Installing collected packages: blobuploader, requests, docopt 12:56:19 INFO - Running setup.py install for blobuploader 12:56:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:56:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:56:20 INFO - Running setup.py install for requests 12:56:20 INFO - Running setup.py install for docopt 12:56:20 INFO - Successfully installed blobuploader requests docopt 12:56:20 INFO - Cleaning up... 12:56:20 INFO - Return code: 0 12:56:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:20 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 0x10c14fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c152768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3b3cb7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2dd30>, '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 0x10be54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf22be8>, '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.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:56:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:56:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:56:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIe7B6J4M/Render', 12:56:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:56:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:56:20 INFO - 'HOME': '/Users/cltbld', 12:56:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:56:20 INFO - 'LOGNAME': 'cltbld', 12:56:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:20 INFO - 'MOZ_NO_REMOTE': '1', 12:56:20 INFO - 'NO_EM_RESTART': '1', 12:56:20 INFO - 'PAGER': '/bin/cat', 12:56:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:56:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:20 INFO - 'PWD': '/builds/slave/test', 12:56:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:56:20 INFO - 'SHELL': '/bin/bash', 12:56:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qdH6Kr2UpC/Listeners', 12:56:20 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:56:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:56:20 INFO - 'USER': 'cltbld', 12:56:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:56:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:56:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:20 INFO - 'XPC_FLAGS': '0x0', 12:56:20 INFO - 'XPC_SERVICE_NAME': '0', 12:56:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:56:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:56:21 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-GYW70O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:56:21 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-EJAMHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:56:21 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-j9DZ1R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:56:21 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-x96ZEZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:56:21 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-wadlCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:56:21 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-whVBAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:56:21 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Znh5IH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:56:21 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-NE5DEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:56:21 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-TAusMB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:56:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:56:21 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-YYrxTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:56:22 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-xDvp3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:56:22 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-YumhP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:56:22 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-hr48cE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:56:22 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-xj5vtN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:56:22 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-lHN1zZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:56:22 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-k3y_6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:56:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:56:22 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-VCw6Yl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:56:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:56:22 INFO - Running setup.py install for manifestparser 12:56:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:56:22 INFO - Running setup.py install for mozcrash 12:56:23 INFO - Running setup.py install for mozdebug 12:56:23 INFO - Running setup.py install for mozdevice 12:56:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:56:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:56:23 INFO - Running setup.py install for mozfile 12:56:23 INFO - Running setup.py install for mozhttpd 12:56:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:56:23 INFO - Running setup.py install for mozinfo 12:56:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:56:23 INFO - Running setup.py install for mozInstall 12:56:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:56:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:56:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:56:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:56:23 INFO - Running setup.py install for mozleak 12:56:24 INFO - Running setup.py install for mozlog 12:56:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:56:24 INFO - Running setup.py install for moznetwork 12:56:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:56:24 INFO - Running setup.py install for mozprocess 12:56:24 INFO - Running setup.py install for mozprofile 12:56:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:56:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:56:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:56:24 INFO - Running setup.py install for mozrunner 12:56:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:56:24 INFO - Running setup.py install for mozscreenshot 12:56:25 INFO - Running setup.py install for moztest 12:56:25 INFO - Running setup.py install for mozversion 12:56:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:56:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:56:25 INFO - Cleaning up... 12:56:25 INFO - Return code: 0 12:56:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:25 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 0x10c14fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c152768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3b3cb7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2dd30>, '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 0x10be54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf22be8>, '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.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:56:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:56:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:56:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIe7B6J4M/Render', 12:56:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:56:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:56:25 INFO - 'HOME': '/Users/cltbld', 12:56:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:56:25 INFO - 'LOGNAME': 'cltbld', 12:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:25 INFO - 'MOZ_NO_REMOTE': '1', 12:56:25 INFO - 'NO_EM_RESTART': '1', 12:56:25 INFO - 'PAGER': '/bin/cat', 12:56:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:25 INFO - 'PWD': '/builds/slave/test', 12:56:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:56:25 INFO - 'SHELL': '/bin/bash', 12:56:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qdH6Kr2UpC/Listeners', 12:56:25 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:56:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:56:25 INFO - 'USER': 'cltbld', 12:56:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:56:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:25 INFO - 'XPC_FLAGS': '0x0', 12:56:25 INFO - 'XPC_SERVICE_NAME': '0', 12:56:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:56:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:56:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-RcDr5z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:56:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:56:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-2iB5uY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:56:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:56:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-9OZN52-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:56:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:56:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:56:25 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-h_ci6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:56:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:56:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-PvsSD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:56:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:56:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-hdjWLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:56:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:56:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-D5ajXd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:56:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:56:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-3t_8Bq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:56:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:56:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-EUdB9u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:56:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:56:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-5K1jY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:56:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:56:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-m5UYls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:56:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:56:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-0iBYjJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:56:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:56:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:56:26 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-6znKdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:56:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:56:27 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-tsvq2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:56:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:56:27 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-NIGxpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:56:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:56:27 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-Bz_QJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:56:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:56:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:56:27 INFO - Running setup.py (path:/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/pip-2Ztos6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:56:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:56:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:56:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:56:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:56:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:56:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:56:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:56:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:56:28 INFO - Downloading blessings-1.6.tar.gz 12:56:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:56:28 INFO - Installing collected packages: blessings 12:56:28 INFO - Running setup.py install for blessings 12:56:29 INFO - Successfully installed blessings 12:56:29 INFO - Cleaning up... 12:56:29 INFO - Return code: 0 12:56:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:56:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:56:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:56:29 INFO - Reading from file tmpfile_stdout 12:56:29 INFO - Current package versions: 12:56:29 INFO - blessings == 1.6 12:56:29 INFO - blobuploader == 1.2.4 12:56:29 INFO - docopt == 0.6.1 12:56:29 INFO - functools32 == 3.2.3-2 12:56:29 INFO - jsonschema == 2.5.1 12:56:29 INFO - manifestparser == 1.1 12:56:29 INFO - mozInstall == 1.12 12:56:29 INFO - mozcrash == 1.0 12:56:29 INFO - mozdebug == 0.1 12:56:29 INFO - mozdevice == 0.49 12:56:29 INFO - mozfile == 1.2 12:56:29 INFO - mozhttpd == 0.7 12:56:29 INFO - mozinfo == 0.9 12:56:29 INFO - mozleak == 0.1 12:56:29 INFO - mozlog == 3.4 12:56:29 INFO - moznetwork == 0.27 12:56:29 INFO - mozprocess == 0.25 12:56:29 INFO - mozprofile == 0.28 12:56:29 INFO - mozrunner == 6.13 12:56:29 INFO - mozscreenshot == 0.1 12:56:29 INFO - mozsystemmonitor == 0.3 12:56:29 INFO - moztest == 0.8 12:56:29 INFO - mozversion == 1.4 12:56:29 INFO - psutil == 3.1.1 12:56:29 INFO - requests == 1.2.3 12:56:29 INFO - wsgiref == 0.1.2 12:56:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:56:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:29 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 0x10c14fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c152768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3b3cb7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2dd30>, '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 0x10be54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf22be8>, '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.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:56:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:56:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:56:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIe7B6J4M/Render', 12:56:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:56:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:56:29 INFO - 'HOME': '/Users/cltbld', 12:56:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:56:29 INFO - 'LOGNAME': 'cltbld', 12:56:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:29 INFO - 'MOZ_NO_REMOTE': '1', 12:56:29 INFO - 'NO_EM_RESTART': '1', 12:56:29 INFO - 'PAGER': '/bin/cat', 12:56:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:56:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:29 INFO - 'PWD': '/builds/slave/test', 12:56:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:56:29 INFO - 'SHELL': '/bin/bash', 12:56:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qdH6Kr2UpC/Listeners', 12:56:29 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:56:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:56:29 INFO - 'USER': 'cltbld', 12:56:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:56:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:56:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:29 INFO - 'XPC_FLAGS': '0x0', 12:56:29 INFO - 'XPC_SERVICE_NAME': '0', 12:56:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:56:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:56:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:56:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:56:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:56:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:56:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:56:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:56:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:56:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:56:29 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:56:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:56:29 INFO - Cleaning up... 12:56:29 INFO - Return code: 0 12:56:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:56:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:56:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:56:29 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 0x10c14fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c152768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3b3cb7240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf2dd30>, '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 0x10be54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf22be8>, '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.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:56:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:56:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:56:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIe7B6J4M/Render', 12:56:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:56:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:56:29 INFO - 'HOME': '/Users/cltbld', 12:56:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:56:29 INFO - 'LOGNAME': 'cltbld', 12:56:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:29 INFO - 'MOZ_NO_REMOTE': '1', 12:56:29 INFO - 'NO_EM_RESTART': '1', 12:56:29 INFO - 'PAGER': '/bin/cat', 12:56:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:56:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:29 INFO - 'PWD': '/builds/slave/test', 12:56:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:56:29 INFO - 'SHELL': '/bin/bash', 12:56:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qdH6Kr2UpC/Listeners', 12:56:29 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:56:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:56:29 INFO - 'USER': 'cltbld', 12:56:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:56:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:56:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:29 INFO - 'XPC_FLAGS': '0x0', 12:56:29 INFO - 'XPC_SERVICE_NAME': '0', 12:56:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:56:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:56:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:56:29 INFO - Cleaning up... 12:56:29 INFO - Return code: 0 12:56:29 INFO - Running post-action listener: _resource_record_post_action 12:56:29 INFO - Running post-action listener: _start_resource_monitoring 12:56:29 INFO - Starting resource monitoring. 12:56:29 INFO - [mozharness: 2017-05-19 19:56:29.969244Z] Finished create-virtualenv step (success) 12:56:29 INFO - [mozharness: 2017-05-19 19:56:29.970209Z] Running install step. 12:56:29 INFO - Running pre-action listener: _resource_record_pre_action 12:56:29 INFO - Running main action method: install 12:56:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:56:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:56:30 INFO - Reading from file tmpfile_stdout 12:56:30 INFO - Detecting whether we're running mozinstall >=1.0... 12:56:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:56:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:56:30 INFO - Reading from file tmpfile_stdout 12:56:30 INFO - Output received: 12:56:30 INFO - Usage: mozinstall [options] installer 12:56:30 INFO - Options: 12:56:30 INFO - -h, --help show this help message and exit 12:56:30 INFO - -d DEST, --destination=DEST 12:56:30 INFO - Directory to install application into. [default: 12:56:30 INFO - "/builds/slave/test"] 12:56:30 INFO - --app=APP Application being installed. [default: firefox] 12:56:30 INFO - mkdir: /builds/slave/test/build/application 12:56:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:56:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:56:44 INFO - Reading from file tmpfile_stdout 12:56:44 INFO - Output received: 12:56:44 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 12:56:44 INFO - Running post-action listener: _resource_record_post_action 12:56:44 INFO - [mozharness: 2017-05-19 19:56:44.826531Z] Finished install step (success) 12:56:44 INFO - [mozharness: 2017-05-19 19:56:44.826690Z] Running run-tests step. 12:56:44 INFO - Running pre-action listener: _resource_record_pre_action 12:56:44 INFO - Running main action method: run_tests 12:56:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:56:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:56:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:56:44 INFO - 2017-05-19 12:56:44.840 screenresolution[1636:7180] starting screenresolution argv=screenresolution get 12:56:44 INFO - 2017-05-19 12:56:44.843 screenresolution[1636:7180] Display 0: 1600x1200x32@60 12:56:44 INFO - 2017-05-19 12:56:44.849 screenresolution[1637:7182] starting screenresolution argv=screenresolution list 12:56:44 INFO - Available Modes on Display 0 12:56:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:56:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:56:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:56:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:56:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:56:45 INFO - Intel Iris: 12:56:45 INFO - Chipset Model: Intel Iris 12:56:45 INFO - Type: GPU 12:56:45 INFO - Bus: Built-In 12:56:45 INFO - VRAM (Dynamic, Max): 1536 MB 12:56:45 INFO - Vendor: Intel (0x8086) 12:56:45 INFO - Device ID: 0x0a2e 12:56:45 INFO - Revision ID: 0x0009 12:56:45 INFO - Displays: 12:56:45 INFO - Display: 12:56:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:56:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:56:45 INFO - Display Serial Number: 1600x1200 60 12:56:45 INFO - Main Display: Yes 12:56:45 INFO - Mirror: Off 12:56:45 INFO - Online: Yes 12:56:45 INFO - Rotation: Supported 12:56:45 INFO - Return code: 0 12:56:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:56:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:56:45 INFO - Python 2.7.3 12:56:45 INFO - Return code: 0 12:56:45 INFO - grabbing minidump binary from tooltool 12:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:56:45 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 0x10bf2dd30>, '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 0x10be54db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf22be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:56:45 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:56:45 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:56:45 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:56:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:56:45 INFO - Return code: 0 12:56:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:56:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:56:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:56:45 INFO - ENV: RUST_BACKTRACE is now 1 12:56:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:56:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:56:45 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/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0216', '--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 12:56:45 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/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0216 --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 12:56:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KwIe7B6J4M/Render', 12:56:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:56:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:56:45 INFO - 'HOME': '/Users/cltbld', 12:56:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:56:45 INFO - 'LOGNAME': 'cltbld', 12:56:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:56:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:56:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:56:45 INFO - 'MOZ_NO_REMOTE': '1', 12:56:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:56:45 INFO - 'NO_EM_RESTART': '1', 12:56:45 INFO - 'PAGER': '/bin/cat', 12:56:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:56:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:56:45 INFO - 'PWD': '/builds/slave/test', 12:56:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:56:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:56:45 INFO - 'RUST_BACKTRACE': '1', 12:56:45 INFO - 'SHELL': '/bin/bash', 12:56:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qdH6Kr2UpC/Listeners', 12:56:45 INFO - 'TMPDIR': '/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/', 12:56:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:56:45 INFO - 'USER': 'cltbld', 12:56:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:56:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:56:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:56:45 INFO - 'XPC_FLAGS': '0x0', 12:56:45 INFO - 'XPC_SERVICE_NAME': '0', 12:56:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:56:45 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/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0216', '--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 12:56:45 INFO - mozversion application_buildid: 20170519110323 12:56:45 INFO - mozversion application_changeset: 22a02cb46ff22cc1d5428e138a956e349770e4ab 12:56:45 INFO - mozversion application_display_name: Firefox 12:56:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:56:45 INFO - mozversion application_name: Firefox 12:56:45 INFO - mozversion application_remotingname: firefox 12:56:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:56:45 INFO - mozversion application_vendor: Mozilla 12:56:45 INFO - mozversion application_version: 54.0 12:56:45 INFO - mozversion platform_buildid: 20170519110323 12:56:45 INFO - mozversion platform_changeset: 22a02cb46ff22cc1d5428e138a956e349770e4ab 12:56:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:56:45 INFO - mozversion platform_version: 54.0 12:56:45 INFO - using testdate: 1495223805 12:56:45 INFO - actual date: 1495223805 12:56:45 INFO - starting webserver on 'localhost:49223' 12:56:45 INFO - SUITE-START | Running 2 tests 12:56:45 INFO - TEST-START | dromaeo_css 12:56:45 INFO - operating with platform_type : mac_ 12:56:45 INFO - Initialising browser for dromaeo_css test... 12:56:45 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpzVAAnA/profile http://localhost:49223/getInfo.html) 12:56:48 INFO - PROCESS | 1647 | 2017-05-19 12:56:48.291 plugin-container[1650:7349] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4b, name = 'com.apple.tsm.portname' 12:56:48 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:56:48 INFO - PROCESS | 1647 | 2017-05-19 12:56:48.291 plugin-container[1650:7349] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c13, name = 'com.apple.CFPasteboardClient' 12:56:48 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:56:48 INFO - PROCESS | 1647 | 2017-05-19 12:56:48.291 plugin-container[1650:7349] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:56:48 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 12:56:48 INFO - PROCESS | 1647 | colorDepth:24 12:56:48 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 12:56:48 INFO - PROCESS | 1647 | __metrics 12:56:48 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 12:56:49 INFO - TEST-INFO | 1647: exit 0 12:56:49 INFO - Browser initialized. 12:56:49 INFO - Running cycle 1/1 for dromaeo_css test... 12:56:49 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpzVAAnA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:56:51 INFO - PROCESS | 1651 | 2017-05-19 12:56:51.317 plugin-container[1652:7482] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 12:56:51 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:56:51 INFO - PROCESS | 1651 | 2017-05-19 12:56:51.317 plugin-container[1652:7482] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 12:56:51 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:56:51 INFO - PROCESS | 1651 | 2017-05-19 12:56:51.317 plugin-container[1652:7482] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:57:01 INFO - PROCESS | 1651 | RSS: Main: 174342144 12:57:01 INFO - PROCESS | 1651 | 13:00:04 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49223/tests/dromaeo/cssquery-ext.html) 13:00:05 INFO - PROCESS | 1651 | RSS: Main: 189456384 13:00:05 INFO - PROCESS | 1651 | 13:03:07 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-ext.html (next: http://localhost:49223/tests/dromaeo/cssquery-jquery.html) 13:03:08 INFO - PROCESS | 1651 | RSS: Main: 204136448 13:03:08 INFO - PROCESS | 1651 | 13:06:10 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49223/tests/dromaeo/cssquery-mootools.html) 13:06:11 INFO - PROCESS | 1651 | RSS: Main: 207114240 13:06:11 INFO - PROCESS | 1651 | 13:09:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49223/tests/dromaeo/cssquery-prototype.html) 13:09:13 INFO - PROCESS | 1651 | RSS: Main: 210169856 13:09:13 INFO - PROCESS | 1651 | 13:12:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49223/tests/dromaeo/cssquery-yui.html) 13:12:17 INFO - PROCESS | 1651 | RSS: Main: 212692992 13:12:17 INFO - PROCESS | 1651 | 13:15:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49223/tests/dromaeo/cssquery-yui.html (next: http://localhost:49223/tests/dromaeo/cssquery-dojo.html) 13:15:20 INFO - PROCESS | 1651 | RSS: Main: 210890752 13:15:20 INFO - PROCESS | 1651 | 13:15:20 INFO - PROCESS | 1651 | __start_tp_report 13:15:20 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 13:15:20 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 13:15:20 INFO - PROCESS | 1651 | |i|pagename|runs| 13:15:20 INFO - PROCESS | 1651 | |0;dojo.html;3048.542718592211;3624.9818750906247;3633.218857945541;3648.927021459569;3662.10278481772;6251.843703907399;6263.655498947556;6273.65494897781;6287.025511045789;6294.716737746801;4803.9519604803945;6022.728977196026;6235.719392627331;6239.719212635431;6267.968660156705;5603.775848966046;5666.065099258638;5688.60179787415;5700.543956483492;5763.654180749148;5726.599138060337;5734.541236701054;5736.6558006519745;5737.598368114233;5767.9423205768035;4927.581155601778;5165.81919632813;5753.367129615739;5767.798127065554;5811.099279611667;5728.083506638938;5730.2837145356625;5775.104858746901;5804.680742559158;5845.2401187845735;5535.363433205192;5691.8292451226525;5693.658380497183;5706.9429305707245;5781.566382521286;6027.156198132265;6242.719077641517;6286.748530058838;6319.210098737658;6346.524010699216;6372.904406433907;6488.480921526314;6504.317046710122;6642.601443913332;6660.100886380325;6791.185057793047;6823;6823.454123670094;6825.35159159879;6828.112345395117;6300.873982520324;6360.0459931010255;6377.776777812801;6379.202599675041;6391.2330520337855;6540;6589.967050164719;6769.627670478171;6782.152230971129;6818.829529261808;6549.410553050248;6563.442107420923;6573.605583664996;6578.868422631521;6579.736810527621;6694.799156025327;6707.698153583003;6763.526553141233;6773.525853190229;6790.8641827164165;6337.524685648595;6364.427201551883;6408.967955160194;6480.837979050561;6646.335366463315;6622.768203112868;6649.567778094505;6670.466362690973;6679.666016699146;6685.197776266879;5230.08473517133;5262;5310.973445132827;5364.704941228192;5385.78456861729;4737.170995075848;4804.303376010459;4827.806887724522;4839.056384005155;4880.097182021266;5182.533571978464;6146.7399183167345;6193.411625895533;6229.595076320115;6295.401936815995;6651.800445986628;6742.932570674328;6783.694733736994;6787.389134977876;6845.760398386033;6319.273283572356;6334.5249106317215;6338.144350512714;6346.904796428057;6353.078803573456;5706.144078388275;5768.048272035151;5985.311689155803;5988.850278743066;6005.729742161613;6863.6568171591225;6865.313468653095;6882.724691012303;6884.311568843075;6913;6726.293739157513;6789.89815152783;6794.524383293122;6802.319768023158;6808.9659551701925;5040.647154699136;6801.319868013159;6938.098047253825;6957.130358705162;6999.30006999296;5860.882782344414;5959.195508606456;6418.390252925965;6535.052417399545;6689.498287628545;5588.6646801192055;5921.970390148022;5950.702464876652;6743.393094621508;6762.120924279812;6530.771423000172;6551.508636852351;6579.276279609335;6610.966945165435;6637.137172167781;6297.716602752796;6321.936780632319;6458.354164583504;6470.288268290578;6539.280679125388;6404.295527492065;6435.292117867125;6454.774082907075;6457.4511166551365;6473.093766872736;5280.630355875205;6321.083442900662;6600.438962688226;6605.801825945229;6625.635590042594;5754.769809207753;6317.052442133717;6373.553851230369;6401.3598640135615;6427.196600424947 13:15:20 INFO - PROCESS | 1651 | |1;ext.html;772.5210369570865;776.9123227481525;856.9228769410752;994.1847684898386;997.8004399120173;39622.603773962284;40632.79683601581;42270.36594451086;42282.15435691284;42302.15395692088;29463.705362946363;39544;40679.983000425;40779.79610101953;40926;8288.254057134842;8419.115992820758;11338.319700817978;11543.653690389301;11743.588974385899;10376.22178336624;10703.983121603475;11494.96545310926;11538.250013749079;11558.364289964049;11515.25150865191;11547.364894930775;11571.016463600605;11584;11595.1303652226;11510.424478776069;11530.192886497949;11533;11543.538258469735;11548.30710157389;11132;11148.888511114947;11165.497552610152;11236.719082023015;11238.606648767254;31683.940543864574;34861.651383486096;35297.82351088254;35546.64453355485;35607.64392356069;3488.738344624138;3890;3901.356276214421;3918.608139186087;3961.4057891316247;25999.74000259992;26079.95680172791;26680.59979100315;27680.86159569189;27717.58423623647;3193.616765988073;3237;3397.7281817454545;3445.707114895237;3447.9655203448146;366450;367876;368548.157259212;368737;370043;45971.77014114908;45972;46034.61896143121;46045.61863144111;46110.077798444516;36291;47271.05457890823;47468.762656186496;47548.049039019716;47582.5241747585;14053.437862485385;14253.002289839818;14327.426902923975;14421.35103920326;14442.566722998326;12448.377581120907;12455.252684838939;12461.563845265373;12467.563635272903;12589.622311330675;9566;11453.312801231787;12034.699132521582;12184.451699673536;12208.901198892143;5624.550035997192;5713.685747283939;5732.3407808101765;5746.798862039892;5757.337906140848;827.1521690267524;841.3605659698551;984.6110786239395;1056.2131212246925;1146.9139814513778;26850.717688996265;27314.5902811458;27340.726592734216;27430.5885411719;27508.862455687595;955.460165006769;1001.4441984698417;1124.3928278729559;1195.5217912834935;1229.3115855120982;14758;16108.355665773237;16416.425425110065;16422.1788910554;16613.41853035138;464018;485283.14716853085;497537;505882;509212;48072;49106.75446622744;58676.70661646665;59332.70333648304;59957;1441.0849110814709;1495.8952873298765;1508.645468314967;1514.8636622703789;1518.6051626577168;29719.70280297256;30652;31139.53290700596;31149.844250778602;31464;47798.283025753946;47825.521744782105;47971.52028479671;47976;48127;496191;497079;499151.00848991045;499812;504844;32064.51903221407;32167.356652866343;32372.705091796066;32674.183145421553;32770.83614581912;40419.39370909498;45180.774096130626;45866.08267834692;46406.53593464022;46436.767816160704;30643.846780765954;31274.061778146694;31316;31702;32204.355912881143;30794.538081929237;31194.376112478076;31418.21454463565;31754;31875.68124318727;37018.62981370152;37478.81260593679;37663;37928;38447.615523844404;30489.847550762104;30613.846930765205;30848.383032338777;30904.690953090183;31477.527837082922;24868.62697059582;24949.625755614044;25086.49827003413;25141.87429062843;25499.617505737056 13:15:20 INFO - PROCESS | 1651 | |2;jquery.html;421.20812871801047;433.5339510026721;434.6045098959947;443.2863090424417;444.9510553839077;20161.495962600962;20669;21857.234996775114;22023.008964596593;22040.77959220407;22293.66559501609;22564.774352256474;23341.649875251886;23632;24217.152399666014;7012.403945664599;7094.184168820587;7251.5286506376915;7357.043584334056;7358.117025956866;6681.9977003449385;7094.53885497441;7246.963765181166;7248.420126389902;7275.636218189095;6759.1213142291435;6975.232724400312;7143;7185.89221161683;7195.208527062019;6804.591724496546;7151.32062454066;7166.713331466735;7209.711611535532;7237.855242895139;5886.411358864079;6189.0097584386285;6975.8604827903155;7007.124109486314;7187.460940429489;22569.548609027726;27131.7790699419;28311;28566.714332856613;28657.71342286592;2934.970650293491;2962.4075184963094;3069.478188707925;3200.18395309197;3554.9466757998653;13070.934645326808;13485.932570337183;16279.593010174722;16843.242054107595;17522.21150048251;1860.5999710062267;2020.98989505053;2104.452842261008;2146.5170336674278;2219.5449932763745;172142;174385.12807435883;177555;179104;180089;21260.78739212588;21687.566248675255;21907;22154.335369938926;22385.992630331675;33377.16557086044;33849.661503384654;33856.81501147448;34186.82906585451;34242.80150194731;10617.416042117604;10889.401082940356;11027.290769930674;11046.005859472685;11167.888321116685;7360.889586656204;9132.360734748636;9166.991630920593;9278.675246366345;9299.907000929905;8180.731986542096;9990.000999899952;10049.497525123714;10068.647597334058;10186.962607478534;3211.887583934598;4092.3221479193985;4124.443200167999;4150.460440142822;4197.244495990751;812.8780682897494;844.9788755281168;916.7845556294266;972.4797233480055;993.4238141877694;22459.32622021342;22844.086236550684;23095.653565196535;23173.7682623175;23174.1889033883;874.8950125984795;924.950181543939;960.2174228004154;972.4943029624555;976.1166144639113;11200.719982000352;11869.881301186877;11947.223430476992;11985;11987.700307492383;815893;823503;831964;832550;841562;125380.37309813393;152371.23814380856;152719.23640381728;155943;159275;1266.9176503527242;1288.1369482446783;1313.7241179352252;1344.327836081959;1356.409961666678;20509.769413835216;21720.89139554292;21782.346529604136;21865.562688746453;21907;66484.00547983375;68516.94449166533;69528.65235673789;69562;69830.65084674544;100661.49669251607;100775;101692;103034;103105;11753.949205686698;11760.470778814803;11854.110941679237;11975.461104250155;12072.3360215189;11067.667969960914;13127.868721312665;13167.73664526685;13424.530141445002;13538.86461135376;11413.486393112164;11671.183017189054;11686.415679215836;11691.941540292244;11837.289762614271;10020.64927727526;10408.375497470177;10645.733856653645;10811.837822432513;11023.779524409627;11563.132765042823;11593.768124637629;11989.580364687194;12026.699332516757;12063.457144428698;11742.295462272292;11760;11773.764524709286;12037.97677197448;12050.819237711617;11528.250663706833;11543.018843398406;11718.121140914636;11735.41322933867;11837.112216583619 13:15:20 INFO - PROCESS | 1651 | |3;mootools.html;1601.9839801601981;1647.901125932444;1664.600495880989;1671.2897018767026;1674.8911320764146;9568.473733944647;11158.553657853687;11855;11919.523219071227;11919.880801191995;11368.09055275578;11544.191906566522;11595;11620.535178592847;11627;2144.6274384393923;2429.307647320514;2483.4039830440743;2491.4145175883655;2494.87525623719;2397.61638137898;2412.963805542918;2452.0069371904406;2477.54165479386;2482.726900040994;2418.5283869645427;2455.2143314139485;2461.4584791345965;2462.7906627936645;2474.8762561871927;2377.9286621401384;2434.330559096245;2438.548868459332;2441.6703744994466;2458.5943319352286;2416.9244686114685;2419.1774796569075;2441.438469152105;2443.0716327795326;2446.816488763332;11483;11642.010429113536;11673.649790506299;11677.357745324003;11680.065594752485;5824.980628390057;5981.671008094553;5988.61074030191;5989.580729348947;5997.130416089686;3469.757117001811;3510.157562185083;3575.535180426528;3676.3015027144756;3693.9445908311395;1228.619128070301;1233.2477188914754;1258.5154715434533;1260.7793636113643;1327.8207441995303;14043.78934315986;14314.64213394663;14730.18983955882;15945.282462289224;16084.356625734956;4556.749378784199;4587.243104887723;4724.692894961817;4747.0505898820375;4750.738709371018;12042.458089385998;12099.87900121005;12127.575534856238;12142.45358958849;12156.513739450324;5748.13777933305;5749.108888122348;5751.482366587027;5798.88402231953;5838.095095260156;4198.580141985838;5216.921746173811;5285.497877701612;5304;5366.892662146735;3412.6861158453676;3485.7385696072897;3799.4110912808005;3887.086534664352;3888.8833334999995;1034.2656713733184;1041.146260066753;1042.9009244121796;1059.8516207730925;1061.6231237910586;982.8967958364411;994.6966175316453;1003.2024540490328;1006.3961623026272;1024.666983230453;6123.724432400552;6810.8297292567095;6900.516963812585;7080.9992354320675;7106.18278897923;924.5931790012373;961.9326647134633;1071.4535586850761;1114.1810769084716;1118.8601424821898;3768.6985041197186;4381.802818873159;4451.532589078165;4515.209838278288;4562.270036794097;13936.303184840717;14471.059381140192;14512.2018288993;14536.709265814625;14654.047486913316;13497.122687025312;14014.369353379123;14366.784498232535;14454.783178252335;14460.349284282232;9040.683576074805;10431.009054139844;10615.416152111553;10676.092532134855;10684.46577671129;6109.511239100772;6861.759838405632;6979.54632948877;7067.5052746307265;7174.995500629811;10822.621208257988;12622.99016078693;12668.99999999963;12696.873031269939;12743.362831858185;10608.734781630521;13053.412596432994;13074.215547067206;13238.470461181445;13330.666733331356;9602.183814375856;9668.129868311886;9682.56428460707;9732.562034708597;9770.902290977;10679.199060070681;10701.357918524913;10756.408397538211;10797.62208322705;10799.67600971972;7617.352525035212;8175.305099066408;9234;9239.491827949527;9247.167754902091;8931.419457735487;8987.640494380414;9033.548322583712;9169.679061232824;9185.816283674423;8331;8380.455270407405;8383.916160838558;8452.36607254446;8505.106963768716;6009.489193418608;6110.663913484801;6162.137300777984;6249.34381889895;6257.217847769029;6139.171211886517;6158.137860699416;6299.96850015747;6333.936660633519;6378.170837791057 13:15:20 INFO - PROCESS | 1651 | |4;prototype.html;336.6549286980844;359.22228375566897;388.2316050390472;394.16633819471826;399.76214152579206;4393.868183954486;4599.839005634799;4760.881407773542;5240.60695447841;5519.4756498132665;4212.852550160745;4586.082783443317;5254.448282930294;5427.755750991205;5428.647137936031;3798.3732684107226;3886.300465916134;4021.296273152201;4283.764392958386;4294.530644879192;4214;4233.23801715691;4270.167317373113;4299.26912424887;4299.978500107495;4153.522344930341;4249.597632781175;4265.253580623394;4289.4209281747035;4308.82764689412;3694.963050369502;4035.2938235808765;4107.527634322061;4146.253674338618;4249.703840328699;3124.1252449314;3264.722498587623;3526.5768107827225;3648.7081033517256;3707.4624179494094;4433.69206084206;5258.370814592704;5278.624618921501;5285.894282114336;5390.164524498709;4102.712810103294;4163;4284.892877678083;4345.9348109778375;4915.729634870081;4844.903101937942;4881.1213981483315;5119.718415487147;5177.792888284464;5847.883042339172;4060.0308850207625;4213.789310534492;4215.388768628532;4233.153369326147;4253.914921701548;5497.862553436156;5907.291125064977;5917.171595976567;6015.639061656271;6194.659293738842;3607.621199773986;4221.058313175912;4248.266497601275;4258.297380932174;4267.850625228102;3542.6457354264367;3847.846086156578;4211.031462763582;4304.74171549708;4316.611504964569;3854.6530812227033;4322.783860806947;4588.993771653775;4912.315763177617;4917.237828136645;5113.309703190096;5242.767949531857;5723.370429252779;5815.539753846455;5815.651060936273;1787.8122797106116;1790.534461040139;1791.6864548703925;1801.5406071451696;1804.1790985101748;1157.3692337675986;1305.8628843971437;1388.7430825297351;1399.510171439988;1406.3319923036477;4634.374359461497;4674.065186962621;4784.832530861403;4867.123917694849;5311.044012077786;5149.871253218699;5249.7189256714555;5703;5720.2849643794525;5747;4622.422197225347;4759.928601070987;5396.757145928443;5416.10634245353;5451.037626454473;2990.461716890937;3076.8615612223175;3110.4090222857585;3118.454270502653;3129.5931528901096;6068.484178844759;6130.877382452326;6152.3540028297275;6167.229096362955;6289.018959027983;5185.688858668492;5807.796727114615;5915.639402937349;6054.424829641177;6095.939040609537;5135.378159656339;5158.191187632289;5324.041672498987;5495.587830912697;5781.161731548986;5570.025245581925;5603.691796951207;5933.970330148321;5943.52451803846;5959.404059594006;5208.24480450325;5232.921506177334;5822.94177058224;5934.584579079423;5957;5691.325077213703;5875.001249787461;6081.939180608138;6131.945798157667;6166.352532984151;4091.792921088346;4158.604932531404;4161.396597493407;4325.5241923387775;4625.704802655127;5353.9732301338245;5633.211350410864;5854.9121763174435;5873.769426815149;5987.64074155552;5046.949530504648;5527.695976721319;5693.146028095819;5767.538596912321;5770.44198066528;4656;5412.485813847678;5738.196652468741;5773.769049237983;5785.884282314414;5142.562882155059;5388.787522807337;5568.638038527645;5595.216669666332;5617.747201375866;4654.25531914892;5116.872078198075;5152.613553983391;5558.972205138948;5744.224529688606;5456.645318054314;5537.944620553904;5580.405111623115;5659.462351076641;5665.291838520185 13:15:20 INFO - PROCESS | 1651 | |5;yui.html;729.9160596531399;749.7525816480562;765.7358211417063;795.9164287749786;889.6752685269877;16299.674006519863;16897.324107035703;17376.91311543442;17540.29838806448;17800.376986805466;16098.678026439464;16328.265228064736;16428.178591070428;16604.916975415104;16710.331586736545;966.5264020629911;1009.4498498318416;1035.6996471023394;1039.8544203811473;1041.208681402136;950.239808153478;1005.894381089986;1007.9344842585245;1009.7425156585098;1016.3952448293254;1028.4292217819107;1056.6090546497805;1059.814532456817;1060.247224470623;1060.8779990301136;1047.0628787235457;1047.679923296648;1048.2400259811652;1048.8321868501002;1050.921180911427;780.7813812132612;793.5080250244828;798.0702481608919;800.087899794235;801.1988011988012;17094.145292735313;17486.47540573785;17493.212805423787;18410.171542280495;18470.815291847044;14789.926050369679;15082.773758393632;15185.164815935119;15271.618209544738;15294.158821264826;11904.702382440508;12237.755244895052;12288.754224915452;12289.52525696922;12512.687182820411;3856.710746693981;3904.902377440558;3914.743372364395;3938.7636741795295;4110.856120035784;128220;128336;130290;136761.94857077152;137093;11657.650270491898;11674.00770934463;11710.1217408693;12209.206401583868;12258.20321679088;23684.407889802893;24383.634245486333;24517.75482245155;24875.378115547257;24888.004479820967;8183.836323273501;8275.379346549033;8295.709650162215;8315.792105197419;8448.493090414595;4666.8133274668635;4669.41632295963;4777.378940737682;4789.257665061921;4795.592374648124;3540.079579309398;3646.890593282206;3701.1672373716237;3710.2023065041144;3712.535933008374;532.8782911970325;639.4820195641545;649.3668673043744;665.0024962556166;665.0390186180922;596.2874365133624;625.2278436131375;627.1783963008473;637.2989711317514;660.2341284110408;10349.327293725884;10804.243702940876;10844.403557804239;10913.56345746177;11064.94882986115;581.5260562641433;586.4106572894299;587.3421767620292;593.3977013331472;607.419913982146;3784.9591362375018;4281.550437204049;4953.23224900141;4956.033573453215;4970;79628;97901.10494475247;99841;101446;101945.47081793779;64092;69657.65171174111;72890;80802.59598701968;82495;66715.33284667188;67735.66132169307;72842;73938;74851.5029699403;11140.610078647209;11279.492422840827;11398.658040258806;11545.653630391102;11563.595274165364;46370.98588591731;47400.52599473961;47760.28359574539;48726.512734872194;49048.75475622742;41541;59809;60373.39626603854;60664;60936.085958709766;13158.494191286403;13255.469781208643;13444.596662100153;13497.190168589916;13742.175469471464;12698.682532936751;12772.680682982627;13742.519011834538;15274.389024438899;15492.380304787683;12148.696282593006;12437.75124497523;12478.625641230778;12588.370581471074;13019.739605207653;11919.284842909454;11956.940215298868;12266.570670026505;12606.621801345975;12669.93665031669;11635.301881887113;12239.32683702405;12266.26402415858;12337.383130843242;12766.234025958473;7934;9344.906550934676;9743.610255589698;9781.755456113655;9917.256205784739;9431.481268530297;10290.12533934624;10294.176465882862;10539.841902371623;11056.226064175753 13:15:20 INFO - PROCESS | 1651 | __end_tp_report 13:15:20 INFO - PROCESS | 1651 | __start_cc_report 13:15:20 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,389 13:15:20 INFO - PROCESS | 1651 | __end_cc_report 13:15:20 INFO - PROCESS | 1651 | __startTimestamp1495224920209__endTimestamp 13:15:20 INFO - PROCESS | 1651 | 13:15:20 INFO - PROCESS | 1651 | ------- Summary: start ------- 13:15:20 INFO - PROCESS | 1651 | Number of tests: 6 13:15:20 INFO - PROCESS | 1651 | 13:15:20 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:6150.85 Median:6346.90 stddev:708.60 (11.2%) stddev-sans-first:667.67 13:15:20 INFO - PROCESS | 1651 | Values: 3048.5 3625.0 3633.2 3648.9 3662.1 6251.8 6263.7 6273.7 6287.0 6294.7 4804.0 6022.7 6235.7 6239.7 6268.0 5603.8 5666.1 5688.6 5700.5 5763.7 5726.6 5734.5 5736.7 5737.6 5767.9 4927.6 5165.8 5753.4 5767.8 5811.1 5728.1 5730.3 5775.1 5804.7 5845.2 5535.4 5691.8 5693.7 5706.9 5781.6 6027.2 6242.7 6286.7 6319.2 6346.5 6372.9 6488.5 6504.3 6642.6 6660.1 6791.2 6823.0 6823.5 6825.4 6828.1 6300.9 6360.0 6377.8 6379.2 6391.2 6540.0 6590.0 6769.6 6782.2 6818.8 6549.4 6563.4 6573.6 6578.9 6579.7 6694.8 6707.7 6763.5 6773.5 6790.9 6337.5 6364.4 6409.0 6480.8 6646.3 6622.8 6649.6 6670.5 6679.7 6685.2 5230.1 5262.0 5311.0 5364.7 5385.8 4737.2 4804.3 4827.8 4839.1 4880.1 5182.5 6146.7 6193.4 6229.6 6295.4 6651.8 6742.9 6783.7 6787.4 6845.8 6319.3 6334.5 6338.1 6346.9 6353.1 5706.1 5768.0 5985.3 5988.9 6005.7 6863.7 6865.3 6882.7 6884.3 6913.0 6726.3 6789.9 6794.5 6802.3 6809.0 5040.6 6801.3 6938.1 6957.1 6999.3 5860.9 5959.2 6418.4 6535.1 6689.5 5588.7 5922.0 5950.7 6743.4 6762.1 6530.8 6551.5 6579.3 6611.0 6637.1 6297.7 6321.9 6458.4 6470.3 6539.3 6404.3 6435.3 6454.8 6457.5 6473.1 5280.6 6321.1 6600.4 6605.8 6625.6 5754.8 6317.1 6373.6 6401.4 6427.2 13:15:20 INFO - PROCESS | 1651 | 13:15:20 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:58743.18 Median:32115.94 stddev:121607.46 (378.7%) stddev-sans-first:121870.71 13:15:20 INFO - PROCESS | 1651 | Values: 772.5 776.9 856.9 994.2 997.8 39622.6 40632.8 42270.4 42282.2 42302.2 29463.7 39544.0 40680.0 40779.8 40926.0 8288.3 8419.1 11338.3 11543.7 11743.6 10376.2 10704.0 11495.0 11538.3 11558.4 11515.3 11547.4 11571.0 11584.0 11595.1 11510.4 11530.2 11533.0 11543.5 11548.3 11132.0 11148.9 11165.5 11236.7 11238.6 31683.9 34861.7 35297.8 35546.6 35607.6 3488.7 3890.0 3901.4 3918.6 3961.4 25999.7 26080.0 26680.6 27680.9 27717.6 3193.6 3237.0 3397.7 3445.7 3448.0 366450.0 367876.0 368548.2 368737.0 370043.0 45971.8 45972.0 46034.6 46045.6 46110.1 36291.0 47271.1 47468.8 47548.0 47582.5 14053.4 14253.0 14327.4 14421.4 14442.6 12448.4 12455.3 12461.6 12467.6 12589.6 9566.0 11453.3 12034.7 12184.5 12208.9 5624.6 5713.7 5732.3 5746.8 5757.3 827.2 841.4 984.6 1056.2 1146.9 26850.7 27314.6 27340.7 27430.6 27508.9 955.5 1001.4 1124.4 1195.5 1229.3 14758.0 16108.4 16416.4 16422.2 16613.4 464018.0 485283.1 497537.0 505882.0 509212.0 48072.0 49106.8 58676.7 59332.7 59957.0 1441.1 1495.9 1508.6 1514.9 1518.6 29719.7 30652.0 31139.5 31149.8 31464.0 47798.3 47825.5 47971.5 47976.0 48127.0 496191.0 497079.0 499151.0 499812.0 504844.0 32064.5 32167.4 32372.7 32674.2 32770.8 40419.4 45180.8 45866.1 46406.5 46436.8 30643.8 31274.1 31316.0 31702.0 32204.4 30794.5 31194.4 31418.2 31754.0 31875.7 37018.6 37478.8 37663.0 37928.0 38447.6 30489.8 30613.8 30848.4 30904.7 31477.5 24868.6 24949.6 25086.5 25141.9 25499.6 13:15:20 INFO - PROCESS | 1651 | 13:15:20 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:46790.11 Median:12186.94 stddev:138189.93 (1133.9%) stddev-sans-first:138533.73 13:15:20 INFO - PROCESS | 1651 | Values: 421.2 433.5 434.6 443.3 445.0 20161.5 20669.0 21857.2 22023.0 22040.8 22293.7 22564.8 23341.6 23632.0 24217.2 7012.4 7094.2 7251.5 7357.0 7358.1 6682.0 7094.5 7247.0 7248.4 7275.6 6759.1 6975.2 7143.0 7185.9 7195.2 6804.6 7151.3 7166.7 7209.7 7237.9 5886.4 6189.0 6975.9 7007.1 7187.5 22569.5 27131.8 28311.0 28566.7 28657.7 2935.0 2962.4 3069.5 3200.2 3554.9 13070.9 13485.9 16279.6 16843.2 17522.2 1860.6 2021.0 2104.5 2146.5 2219.5 172142.0 174385.1 177555.0 179104.0 180089.0 21260.8 21687.6 21907.0 22154.3 22386.0 33377.2 33849.7 33856.8 34186.8 34242.8 10617.4 10889.4 11027.3 11046.0 11167.9 7360.9 9132.4 9167.0 9278.7 9299.9 8180.7 9990.0 10049.5 10068.6 10187.0 3211.9 4092.3 4124.4 4150.5 4197.2 812.9 845.0 916.8 972.5 993.4 22459.3 22844.1 23095.7 23173.8 23174.2 874.9 925.0 960.2 972.5 976.1 11200.7 11869.9 11947.2 11985.0 11987.7 815893.0 823503.0 831964.0 832550.0 841562.0 125380.4 152371.2 152719.2 155943.0 159275.0 1266.9 1288.1 1313.7 1344.3 1356.4 20509.8 21720.9 21782.3 21865.6 21907.0 66484.0 68516.9 69528.7 69562.0 69830.7 100661.5 100775.0 101692.0 103034.0 103105.0 11753.9 11760.5 11854.1 11975.5 12072.3 11067.7 13127.9 13167.7 13424.5 13538.9 11413.5 11671.2 11686.4 11691.9 11837.3 10020.6 10408.4 10645.7 10811.8 11023.8 11563.1 11593.8 11989.6 12026.7 12063.5 11742.3 11760.0 11773.8 12038.0 12050.8 11528.3 11543.0 11718.1 11735.4 11837.1 13:15:20 INFO - PROCESS | 1651 | 13:15:20 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:6927.58 Median:2460.03 stddev:4355.02 (177.0%) stddev-sans-first:4348.85 13:15:20 INFO - PROCESS | 1651 | Values: 1602.0 1647.9 1664.6 1671.3 1674.9 9568.5 11158.6 11855.0 11919.5 11919.9 11368.1 11544.2 11595.0 11620.5 11627.0 2144.6 2429.3 2483.4 2491.4 2494.9 2397.6 2413.0 2452.0 2477.5 2482.7 2418.5 2455.2 2461.5 2462.8 2474.9 2377.9 2434.3 2438.5 2441.7 2458.6 2416.9 2419.2 2441.4 2443.1 2446.8 11483.0 11642.0 11673.6 11677.4 11680.1 5825.0 5981.7 5988.6 5989.6 5997.1 3469.8 3510.2 3575.5 3676.3 3693.9 1228.6 1233.2 1258.5 1260.8 1327.8 14043.8 14314.6 14730.2 15945.3 16084.4 4556.7 4587.2 4724.7 4747.1 4750.7 12042.5 12099.9 12127.6 12142.5 12156.5 5748.1 5749.1 5751.5 5798.9 5838.1 4198.6 5216.9 5285.5 5304.0 5366.9 3412.7 3485.7 3799.4 3887.1 3888.9 1034.3 1041.1 1042.9 1059.9 1061.6 982.9 994.7 1003.2 1006.4 1024.7 6123.7 6810.8 6900.5 7081.0 7106.2 924.6 961.9 1071.5 1114.2 1118.9 3768.7 4381.8 4451.5 4515.2 4562.3 13936.3 14471.1 14512.2 14536.7 14654.0 13497.1 14014.4 14366.8 14454.8 14460.3 9040.7 10431.0 10615.4 10676.1 10684.5 6109.5 6861.8 6979.5 7067.5 7175.0 10822.6 12623.0 12669.0 12696.9 12743.4 10608.7 13053.4 13074.2 13238.5 13330.7 9602.2 9668.1 9682.6 9732.6 9770.9 10679.2 10701.4 10756.4 10797.6 10799.7 7617.4 8175.3 9234.0 9239.5 9247.2 8931.4 8987.6 9033.5 9169.7 9185.8 8331.0 8380.5 8383.9 8452.4 8505.1 6009.5 6110.7 6162.1 6249.3 6257.2 6139.2 6158.1 6300.0 6333.9 6378.2 13:15:20 INFO - PROCESS | 1651 | 13:15:20 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4643.04 Median:4982.09 stddev:1324.72 (26.6%) stddev-sans-first:1288.40 13:15:20 INFO - PROCESS | 1651 | Values: 336.7 359.2 388.2 394.2 399.8 4393.9 4599.8 4760.9 5240.6 5519.5 4212.9 4586.1 5254.4 5427.8 5428.6 3798.4 3886.3 4021.3 4283.8 4294.5 4214.0 4233.2 4270.2 4299.3 4300.0 4153.5 4249.6 4265.3 4289.4 4308.8 3695.0 4035.3 4107.5 4146.3 4249.7 3124.1 3264.7 3526.6 3648.7 3707.5 4433.7 5258.4 5278.6 5285.9 5390.2 4102.7 4163.0 4284.9 4345.9 4915.7 4844.9 4881.1 5119.7 5177.8 5847.9 4060.0 4213.8 4215.4 4233.2 4253.9 5497.9 5907.3 5917.2 6015.6 6194.7 3607.6 4221.1 4248.3 4258.3 4267.9 3542.6 3847.8 4211.0 4304.7 4316.6 3854.7 4322.8 4589.0 4912.3 4917.2 5113.3 5242.8 5723.4 5815.5 5815.7 1787.8 1790.5 1791.7 1801.5 1804.2 1157.4 1305.9 1388.7 1399.5 1406.3 4634.4 4674.1 4784.8 4867.1 5311.0 5149.9 5249.7 5703.0 5720.3 5747.0 4622.4 4759.9 5396.8 5416.1 5451.0 2990.5 3076.9 3110.4 3118.5 3129.6 6068.5 6130.9 6152.4 6167.2 6289.0 5185.7 5807.8 5915.6 6054.4 6095.9 5135.4 5158.2 5324.0 5495.6 5781.2 5570.0 5603.7 5934.0 5943.5 5959.4 5208.2 5232.9 5822.9 5934.6 5957.0 5691.3 5875.0 6081.9 6131.9 6166.4 4091.8 4158.6 4161.4 4325.5 4625.7 5354.0 5633.2 5854.9 5873.8 5987.6 5046.9 5527.7 5693.1 5767.5 5770.4 4656.0 5412.5 5738.2 5773.8 5785.9 5142.6 5388.8 5568.6 5595.2 5617.7 4654.3 5116.9 5152.6 5559.0 5744.2 5456.6 5537.9 5580.4 5659.5 5665.3 13:15:20 INFO - PROCESS | 1651 | 13:15:20 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:20341.51 Median:17670.34 stddev:29657.13 (167.8%) stddev-sans-first:29703.76 13:15:20 INFO - PROCESS | 1651 | Values: 729.9 749.8 765.7 795.9 889.7 16299.7 16897.3 17376.9 17540.3 17800.4 16098.7 16328.3 16428.2 16604.9 16710.3 966.5 1009.4 1035.7 1039.9 1041.2 950.2 1005.9 1007.9 1009.7 1016.4 1028.4 1056.6 1059.8 1060.2 1060.9 1047.1 1047.7 1048.2 1048.8 1050.9 780.8 793.5 798.1 800.1 801.2 17094.1 17486.5 17493.2 18410.2 18470.8 14789.9 15082.8 15185.2 15271.6 15294.2 11904.7 12237.8 12288.8 12289.5 12512.7 3856.7 3904.9 3914.7 3938.8 4110.9 128220.0 128336.0 130290.0 136761.9 137093.0 11657.7 11674.0 11710.1 12209.2 12258.2 23684.4 24383.6 24517.8 24875.4 24888.0 8183.8 8275.4 8295.7 8315.8 8448.5 4666.8 4669.4 4777.4 4789.3 4795.6 3540.1 3646.9 3701.2 3710.2 3712.5 532.9 639.5 649.4 665.0 665.0 596.3 625.2 627.2 637.3 660.2 10349.3 10804.2 10844.4 10913.6 11064.9 581.5 586.4 587.3 593.4 607.4 3785.0 4281.6 4953.2 4956.0 4970.0 79628.0 97901.1 99841.0 101446.0 101945.5 64092.0 69657.7 72890.0 80802.6 82495.0 66715.3 67735.7 72842.0 73938.0 74851.5 11140.6 11279.5 11398.7 11545.7 11563.6 46371.0 47400.5 47760.3 48726.5 49048.8 41541.0 59809.0 60373.4 60664.0 60936.1 13158.5 13255.5 13444.6 13497.2 13742.2 12698.7 12772.7 13742.5 15274.4 15492.4 12148.7 12437.8 12478.6 12588.4 13019.7 11919.3 11956.9 12266.6 12606.6 12669.9 11635.3 12239.3 12266.3 12337.4 12766.2 7934.0 9344.9 9743.6 9781.8 9917.3 9431.5 10290.1 10294.2 10539.8 11056.2 13:15:20 INFO - PROCESS | 1651 | -------- Summary: end -------- 13:15:20 INFO - PROCESS | 1651 | 13:15:20 INFO - TEST-INFO | 1651: exit 0 13:15:20 INFO - TEST-OK | dromaeo_css | took 1115323ms 13:15:20 INFO - TEST-START | kraken 13:15:20 INFO - operating with platform_type : mac_ 13:15:20 INFO - Initialising browser for kraken test... 13:15:20 INFO - TEST-INFO | started process 1773 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpRuKIrI/profile http://localhost:49223/getInfo.html) 13:15:23 INFO - PROCESS | 1773 | 2017-05-19 13:15:23.258 plugin-container[1775:10134] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 13:15:23 INFO - PROCESS | 1773 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:15:23 INFO - PROCESS | 1773 | 2017-05-19 13:15:23.258 plugin-container[1775:10134] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.CFPasteboardClient' 13:15:23 INFO - PROCESS | 1773 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:15:23 INFO - PROCESS | 1773 | 2017-05-19 13:15:23.258 plugin-container[1775:10134] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:15:23 INFO - PROCESS | 1773 | __metrics Screen width/height:1600/1200 13:15:23 INFO - PROCESS | 1773 | colorDepth:24 13:15:23 INFO - PROCESS | 1773 | Browser inner width/height: 1024/689 13:15:23 INFO - PROCESS | 1773 | __metrics 13:15:23 ERROR - PROCESS | 1773 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 13:15:24 INFO - TEST-INFO | 1773: exit 0 13:15:24 INFO - Browser initialized. 13:15:24 INFO - Running cycle 1/1 for kraken test... 13:15:24 INFO - TEST-INFO | started process 1777 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/T/tmpRuKIrI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:15:26 INFO - PROCESS | 1777 | 2017-05-19 13:15:26.471 plugin-container[1778:10258] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 13:15:26 INFO - PROCESS | 1777 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:15:26 INFO - PROCESS | 1777 | 2017-05-19 13:15:26.472 plugin-container[1778:10258] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 13:15:26 INFO - PROCESS | 1777 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:15:26 INFO - PROCESS | 1777 | 2017-05-19 13:15:26.472 plugin-container[1778:10258] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:15:36 INFO - PROCESS | 1777 | RSS: Main: 174317568 13:15:36 INFO - PROCESS | 1777 | 13:15:39 INFO - PROCESS | 1777 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-beat-detection) 13:15:40 INFO - PROCESS | 1777 | RSS: Main: 187961344 13:15:40 INFO - PROCESS | 1777 | 13:15:42 INFO - PROCESS | 1777 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-dft) 13:15:43 INFO - PROCESS | 1777 | RSS: Main: 195891200 13:15:43 INFO - PROCESS | 1777 | 13:15:46 INFO - PROCESS | 1777 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-fft) 13:15:47 INFO - PROCESS | 1777 | RSS: Main: 199475200 13:15:47 INFO - PROCESS | 1777 | 13:15:49 INFO - PROCESS | 1777 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49223/tests/kraken/driver.html?testName=audio-oscillator) 13:15:49 INFO - PROCESS | 1777 | RSS: Main: 199876608 13:15:49 INFO - PROCESS | 1777 | 13:15:51 INFO - PROCESS | 1777 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:15:52 INFO - PROCESS | 1777 | RSS: Main: 201236480 13:15:52 INFO - PROCESS | 1777 | 13:15:55 INFO - PROCESS | 1777 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-darkroom) 13:15:56 INFO - PROCESS | 1777 | RSS: Main: 205619200 13:15:56 INFO - PROCESS | 1777 | 13:15:59 INFO - PROCESS | 1777 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49223/tests/kraken/driver.html?testName=imaging-desaturate) 13:15:59 INFO - PROCESS | 1777 | RSS: Main: 204767232 13:15:59 INFO - PROCESS | 1777 | 13:16:02 INFO - PROCESS | 1777 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49223/tests/kraken/driver.html?testName=json-parse-financial) 13:16:03 INFO - PROCESS | 1777 | RSS: Main: 205094912 13:16:03 INFO - PROCESS | 1777 | 13:16:05 INFO - PROCESS | 1777 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49223/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:16:05 INFO - PROCESS | 1777 | RSS: Main: 204652544 13:16:05 INFO - PROCESS | 1777 | 13:16:07 INFO - PROCESS | 1777 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:16:08 INFO - PROCESS | 1777 | RSS: Main: 204623872 13:16:08 INFO - PROCESS | 1777 | 13:16:10 INFO - PROCESS | 1777 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:16:11 INFO - PROCESS | 1777 | RSS: Main: 204652544 13:16:11 INFO - PROCESS | 1777 | 13:16:13 INFO - PROCESS | 1777 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:16:14 INFO - PROCESS | 1777 | RSS: Main: 204992512 13:16:14 INFO - PROCESS | 1777 | 13:16:17 INFO - PROCESS | 1777 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:16:17 INFO - PROCESS | 1777 | RSS: Main: 205070336 13:16:17 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | Cycle 1(1): loaded http://localhost:49223/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49223/tests/kraken/driver.html?testName=ai-astar) 13:16:19 INFO - PROCESS | 1777 | RSS: Main: 207101952 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | __start_tp_report 13:16:19 INFO - PROCESS | 1777 | _x_x_mozilla_page_load 13:16:19 INFO - PROCESS | 1777 | _x_x_mozilla_page_load_details 13:16:19 INFO - PROCESS | 1777 | |i|pagename|runs| 13:16:19 INFO - PROCESS | 1777 | |0;ai-astar;107;119;119;120;119;119;121;134;177;163 13:16:19 INFO - PROCESS | 1777 | |1;audio-beat-detection;141;106;113;145;112;94;130;142;156;136 13:16:19 INFO - PROCESS | 1777 | |2;audio-dft;194;199;196;194;194;194;196;196;193;199 13:16:19 INFO - PROCESS | 1777 | |3;audio-fft;81;58;70;86;53;76;89;52;66;66 13:16:19 INFO - PROCESS | 1777 | |4;audio-oscillator;86;67;105;104;71;70;82;72;71;83 13:16:19 INFO - PROCESS | 1777 | |5;imaging-gaussian-blur;88;89;92;89;89;88;99;90;137;111 13:16:19 INFO - PROCESS | 1777 | |6;imaging-darkroom;96;98;135;152;96;119;154;98;97;96 13:16:19 INFO - PROCESS | 1777 | |7;imaging-desaturate;90;91;90;89;93;92;89;94;91;90 13:16:19 INFO - PROCESS | 1777 | |8;json-parse-financial;64;62;63;68;67;67;69;69;69;67 13:16:19 INFO - PROCESS | 1777 | |9;json-stringify-tinderbox;60;62;65;60;57;59;61;60;60;61 13:16:19 INFO - PROCESS | 1777 | |10;stanford-crypto-aes;87;85;85;83;86;83;90;90;102;84 13:16:19 INFO - PROCESS | 1777 | |11;stanford-crypto-ccm;135;137;135;136;132;140;143;140;160;138 13:16:19 INFO - PROCESS | 1777 | |12;stanford-crypto-pbkdf2;163;163;148;224;206;160;165;163;161;163 13:16:19 INFO - PROCESS | 1777 | |13;stanford-crypto-sha256-iterative;71;68;56;78;88;84;86;85;66;67 13:16:19 INFO - PROCESS | 1777 | __end_tp_report 13:16:19 INFO - PROCESS | 1777 | __start_cc_report 13:16:19 INFO - PROCESS | 1777 | _x_x_mozilla_cycle_collect,989 13:16:19 INFO - PROCESS | 1777 | __end_cc_report 13:16:19 INFO - PROCESS | 1777 | __startTimestamp1495224979986__endTimestamp 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | ------- Summary: start ------- 13:16:19 INFO - PROCESS | 1777 | Number of tests: 14 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | [#0] ai-astar Cycles:10 Average:129.80 Median:120.50 stddev:22.38 (18.6%) stddev-sans-first:22.16 13:16:19 INFO - PROCESS | 1777 | Values: 107.0 119.0 119.0 120.0 119.0 119.0 121.0 134.0 177.0 163.0 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:141.50 stddev:20.07 (14.2%) stddev-sans-first:20.68 13:16:19 INFO - PROCESS | 1777 | Values: 141.0 106.0 113.0 145.0 112.0 94.0 130.0 142.0 156.0 136.0 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | [#2] audio-dft Cycles:10 Average:195.50 Median:196.00 stddev:2.12 (1.1%) stddev-sans-first:2.18 13:16:19 INFO - PROCESS | 1777 | Values: 194.0 199.0 196.0 194.0 194.0 194.0 196.0 196.0 193.0 199.0 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | [#3] audio-fft Cycles:10 Average:69.70 Median:73.00 stddev:13.17 (18.0%) stddev-sans-first:13.32 13:16:19 INFO - PROCESS | 1777 | Values: 81.0 58.0 70.0 86.0 53.0 76.0 89.0 52.0 66.0 66.0 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:71.50 stddev:13.88 (19.4%) stddev-sans-first:14.60 13:16:19 INFO - PROCESS | 1777 | Values: 86.0 67.0 105.0 104.0 71.0 70.0 82.0 72.0 71.0 83.0 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | [#5] imaging-gaussian-blur Cycles:10 Average:97.20 Median:89.00 stddev:15.73 (17.7%) stddev-sans-first:16.33 13:16:19 INFO - PROCESS | 1777 | Values: 88.0 89.0 92.0 89.0 89.0 88.0 99.0 90.0 137.0 111.0 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | [#6] imaging-darkroom Cycles:10 Average:114.10 Median:96.00 stddev:24.23 (25.2%) stddev-sans-first:24.80 13:16:19 INFO - PROCESS | 1777 | Values: 96.0 98.0 135.0 152.0 96.0 119.0 154.0 98.0 97.0 96.0 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | [#7] imaging-desaturate Cycles:10 Average:90.90 Median:91.00 stddev:1.66 (1.8%) stddev-sans-first:1.73 13:16:19 INFO - PROCESS | 1777 | Values: 90.0 91.0 90.0 89.0 93.0 92.0 89.0 94.0 91.0 90.0 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | [#8] json-parse-financial Cycles:10 Average:66.50 Median:67.50 stddev:2.59 (3.8%) stddev-sans-first:2.59 13:16:19 INFO - PROCESS | 1777 | Values: 64.0 62.0 63.0 68.0 67.0 67.0 69.0 69.0 69.0 67.0 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:60.50 stddev:2.07 (3.4%) stddev-sans-first:2.19 13:16:19 INFO - PROCESS | 1777 | Values: 60.0 62.0 65.0 60.0 57.0 59.0 61.0 60.0 60.0 61.0 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:85.50 stddev:5.68 (6.6%) stddev-sans-first:6.02 13:16:19 INFO - PROCESS | 1777 | Values: 87.0 85.0 85.0 83.0 86.0 83.0 90.0 90.0 102.0 84.0 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | [#11] stanford-crypto-ccm Cycles:10 Average:139.60 Median:139.00 stddev:7.82 (5.6%) stddev-sans-first:8.12 13:16:19 INFO - PROCESS | 1777 | Values: 135.0 137.0 135.0 136.0 132.0 140.0 143.0 140.0 160.0 138.0 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:163.00 stddev:23.74 (14.6%) stddev-sans-first:24.98 13:16:19 INFO - PROCESS | 1777 | Values: 163.0 163.0 148.0 224.0 206.0 160.0 165.0 163.0 161.0 163.0 13:16:19 INFO - PROCESS | 1777 | 13:16:19 INFO - PROCESS | 1777 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.90 Median:81.00 stddev:10.81 (13.3%) stddev-sans-first:11.37 13:16:19 INFO - PROCESS | 1777 | Values: 71.0 68.0 56.0 78.0 88.0 84.0 86.0 85.0 66.0 67.0 13:16:19 INFO - PROCESS | 1777 | -------- Summary: end -------- 13:16:19 INFO - PROCESS | 1777 | 13:16:20 INFO - TEST-INFO | 1777: exit 0 13:16:20 INFO - TEST-OK | kraken | took 60059ms 13:16:20 INFO - SUITE-END | took 1175s 13:16:21 INFO - Completed test suite (00:19:35) 13:16:21 INFO - javascript benchmark 13:16:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7916.274293277546, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3048.542718592211, 3624.9818750906247, 3633.218857945541, 3648.927021459569, 3662.10278481772, 6251.843703907399, 6263.655498947556, 6273.65494897781, 6287.025511045789, 6294.716737746801, 4803.9519604803945, 6022.728977196026, 6235.719392627331, 6239.719212635431, 6267.968660156705, 5603.775848966046, 5666.065099258638, 5688.60179787415, 5700.543956483492, 5763.654180749148, 5726.599138060337, 5734.541236701054, 5736.6558006519745, 5737.598368114233, 5767.9423205768035, 4927.581155601778, 5165.81919632813, 5753.367129615739, 5767.798127065554, 5811.099279611667, 5728.083506638938, 5730.2837145356625, 5775.104858746901, 5804.680742559158, 5845.2401187845735, 5535.363433205192, 5691.8292451226525, 5693.658380497183, 5706.9429305707245, 5781.566382521286, 6027.156198132265, 6242.719077641517, 6286.748530058838, 6319.210098737658, 6346.524010699216, 6372.904406433907, 6488.480921526314, 6504.317046710122, 6642.601443913332, 6660.100886380325, 6791.185057793047, 6823.0, 6823.454123670094, 6825.35159159879, 6828.112345395117, 6300.873982520324, 6360.0459931010255, 6377.776777812801, 6379.202599675041, 6391.2330520337855, 6540.0, 6589.967050164719, 6769.627670478171, 6782.152230971129, 6818.829529261808, 6549.410553050248, 6563.442107420923, 6573.605583664996, 6578.868422631521, 6579.736810527621, 6694.799156025327, 6707.698153583003, 6763.526553141233, 6773.525853190229, 6790.8641827164165, 6337.524685648595, 6364.427201551883, 6408.967955160194, 6480.837979050561, 6646.335366463315, 6622.768203112868, 6649.567778094505, 6670.466362690973, 6679.666016699146, 6685.197776266879, 5230.08473517133, 5262.0, 5310.973445132827, 5364.704941228192, 5385.78456861729, 4737.170995075848, 4804.303376010459, 4827.806887724522, 4839.056384005155, 4880.097182021266, 5182.533571978464, 6146.7399183167345, 6193.411625895533, 6229.595076320115, 6295.401936815995, 6651.800445986628, 6742.932570674328, 6783.694733736994, 6787.389134977876, 6845.760398386033, 6319.273283572356, 6334.5249106317215, 6338.144350512714, 6346.904796428057, 6353.078803573456, 5706.144078388275, 5768.048272035151, 5985.311689155803, 5988.850278743066, 6005.729742161613, 6863.6568171591225, 6865.313468653095, 6882.724691012303, 6884.311568843075, 6913.0, 6726.293739157513, 6789.89815152783, 6794.524383293122, 6802.319768023158, 6808.9659551701925, 5040.647154699136, 6801.319868013159, 6938.098047253825, 6957.130358705162, 6999.30006999296, 5860.882782344414, 5959.195508606456, 6418.390252925965, 6535.052417399545, 6689.498287628545, 5588.6646801192055, 5921.970390148022, 5950.702464876652, 6743.393094621508, 6762.120924279812, 6530.771423000172, 6551.508636852351, 6579.276279609335, 6610.966945165435, 6637.137172167781, 6297.716602752796, 6321.936780632319, 6458.354164583504, 6470.288268290578, 6539.280679125388, 6404.295527492065, 6435.292117867125, 6454.774082907075, 6457.4511166551365, 6473.093766872736, 5280.630355875205, 6321.083442900662, 6600.438962688226, 6605.801825945229, 6625.635590042594, 5754.769809207753, 6317.052442133717, 6373.553851230369, 6401.3598640135615, 6427.196600424947], "value": 6107.571479316518, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [772.5210369570865, 776.9123227481525, 856.9228769410752, 994.1847684898386, 997.8004399120173, 39622.603773962284, 40632.79683601581, 42270.36594451086, 42282.15435691284, 42302.15395692088, 29463.705362946363, 39544.0, 40679.983000425, 40779.79610101953, 40926.0, 8288.254057134842, 8419.115992820758, 11338.319700817978, 11543.653690389301, 11743.588974385899, 10376.22178336624, 10703.983121603475, 11494.96545310926, 11538.250013749079, 11558.364289964049, 11515.25150865191, 11547.364894930775, 11571.016463600605, 11584.0, 11595.1303652226, 11510.424478776069, 11530.192886497949, 11533.0, 11543.538258469735, 11548.30710157389, 11132.0, 11148.888511114947, 11165.497552610152, 11236.719082023015, 11238.606648767254, 31683.940543864574, 34861.651383486096, 35297.82351088254, 35546.64453355485, 35607.64392356069, 3488.738344624138, 3890.0, 3901.356276214421, 3918.608139186087, 3961.4057891316247, 25999.74000259992, 26079.95680172791, 26680.59979100315, 27680.86159569189, 27717.58423623647, 3193.616765988073, 3237.0, 3397.7281817454545, 3445.707114895237, 3447.9655203448146, 366450.0, 367876.0, 368548.157259212, 368737.0, 370043.0, 45971.77014114908, 45972.0, 46034.61896143121, 46045.61863144111, 46110.077798444516, 36291.0, 47271.05457890823, 47468.762656186496, 47548.049039019716, 47582.5241747585, 14053.437862485385, 14253.002289839818, 14327.426902923975, 14421.35103920326, 14442.566722998326, 12448.377581120907, 12455.252684838939, 12461.563845265373, 12467.563635272903, 12589.622311330675, 9566.0, 11453.312801231787, 12034.699132521582, 12184.451699673536, 12208.901198892143, 5624.550035997192, 5713.685747283939, 5732.3407808101765, 5746.798862039892, 5757.337906140848, 827.1521690267524, 841.3605659698551, 984.6110786239395, 1056.2131212246925, 1146.9139814513778, 26850.717688996265, 27314.5902811458, 27340.726592734216, 27430.5885411719, 27508.862455687595, 955.460165006769, 1001.4441984698417, 1124.3928278729559, 1195.5217912834935, 1229.3115855120982, 14758.0, 16108.355665773237, 16416.425425110065, 16422.1788910554, 16613.41853035138, 464018.0, 485283.14716853085, 497537.0, 505882.0, 509212.0, 48072.0, 49106.75446622744, 58676.70661646665, 59332.70333648304, 59957.0, 1441.0849110814709, 1495.8952873298765, 1508.645468314967, 1514.8636622703789, 1518.6051626577168, 29719.70280297256, 30652.0, 31139.53290700596, 31149.844250778602, 31464.0, 47798.283025753946, 47825.521744782105, 47971.52028479671, 47976.0, 48127.0, 496191.0, 497079.0, 499151.00848991045, 499812.0, 504844.0, 32064.51903221407, 32167.356652866343, 32372.705091796066, 32674.183145421553, 32770.83614581912, 40419.39370909498, 45180.774096130626, 45866.08267834692, 46406.53593464022, 46436.767816160704, 30643.846780765954, 31274.061778146694, 31316.0, 31702.0, 32204.355912881143, 30794.538081929237, 31194.376112478076, 31418.21454463565, 31754.0, 31875.68124318727, 37018.62981370152, 37478.81260593679, 37663.0, 37928.0, 38447.615523844404, 30489.847550762104, 30613.846930765205, 30848.383032338777, 30904.690953090183, 31477.527837082922, 24868.62697059582, 24949.625755614044, 25086.49827003413, 25141.87429062843, 25499.617505737056], "value": 19172.67531805745, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [421.20812871801047, 433.5339510026721, 434.6045098959947, 443.2863090424417, 444.9510553839077, 20161.495962600962, 20669.0, 21857.234996775114, 22023.008964596593, 22040.77959220407, 22293.66559501609, 22564.774352256474, 23341.649875251886, 23632.0, 24217.152399666014, 7012.403945664599, 7094.184168820587, 7251.5286506376915, 7357.043584334056, 7358.117025956866, 6681.9977003449385, 7094.53885497441, 7246.963765181166, 7248.420126389902, 7275.636218189095, 6759.1213142291435, 6975.232724400312, 7143.0, 7185.89221161683, 7195.208527062019, 6804.591724496546, 7151.32062454066, 7166.713331466735, 7209.711611535532, 7237.855242895139, 5886.411358864079, 6189.0097584386285, 6975.8604827903155, 7007.124109486314, 7187.460940429489, 22569.548609027726, 27131.7790699419, 28311.0, 28566.714332856613, 28657.71342286592, 2934.970650293491, 2962.4075184963094, 3069.478188707925, 3200.18395309197, 3554.9466757998653, 13070.934645326808, 13485.932570337183, 16279.593010174722, 16843.242054107595, 17522.21150048251, 1860.5999710062267, 2020.98989505053, 2104.452842261008, 2146.5170336674278, 2219.5449932763745, 172142.0, 174385.12807435883, 177555.0, 179104.0, 180089.0, 21260.78739212588, 21687.566248675255, 21907.0, 22154.335369938926, 22385.992630331675, 33377.16557086044, 33849.661503384654, 33856.81501147448, 34186.82906585451, 34242.80150194731, 10617.416042117604, 10889.401082940356, 11027.290769930674, 11046.005859472685, 11167.888321116685, 7360.889586656204, 9132.360734748636, 9166.991630920593, 9278.675246366345, 9299.907000929905, 8180.731986542096, 9990.000999899952, 10049.497525123714, 10068.647597334058, 10186.962607478534, 3211.887583934598, 4092.3221479193985, 4124.443200167999, 4150.460440142822, 4197.244495990751, 812.8780682897494, 844.9788755281168, 916.7845556294266, 972.4797233480055, 993.4238141877694, 22459.32622021342, 22844.086236550684, 23095.653565196535, 23173.7682623175, 23174.1889033883, 874.8950125984795, 924.950181543939, 960.2174228004154, 972.4943029624555, 976.1166144639113, 11200.719982000352, 11869.881301186877, 11947.223430476992, 11985.0, 11987.700307492383, 815893.0, 823503.0, 831964.0, 832550.0, 841562.0, 125380.37309813393, 152371.23814380856, 152719.23640381728, 155943.0, 159275.0, 1266.9176503527242, 1288.1369482446783, 1313.7241179352252, 1344.327836081959, 1356.409961666678, 20509.769413835216, 21720.89139554292, 21782.346529604136, 21865.562688746453, 21907.0, 66484.00547983375, 68516.94449166533, 69528.65235673789, 69562.0, 69830.65084674544, 100661.49669251607, 100775.0, 101692.0, 103034.0, 103105.0, 11753.949205686698, 11760.470778814803, 11854.110941679237, 11975.461104250155, 12072.3360215189, 11067.667969960914, 13127.868721312665, 13167.73664526685, 13424.530141445002, 13538.86461135376, 11413.486393112164, 11671.183017189054, 11686.415679215836, 11691.941540292244, 11837.289762614271, 10020.64927727526, 10408.375497470177, 10645.733856653645, 10811.837822432513, 11023.779524409627, 11563.132765042823, 11593.768124637629, 11989.580364687194, 12026.699332516757, 12063.457144428698, 11742.295462272292, 11760.0, 11773.764524709286, 12037.97677197448, 12050.819237711617, 11528.250663706833, 11543.018843398406, 11718.121140914636, 11735.41322933867, 11837.112216583619], "value": 12066.411989919983, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1601.9839801601981, 1647.901125932444, 1664.600495880989, 1671.2897018767026, 1674.8911320764146, 9568.473733944647, 11158.553657853687, 11855.0, 11919.523219071227, 11919.880801191995, 11368.09055275578, 11544.191906566522, 11595.0, 11620.535178592847, 11627.0, 2144.6274384393923, 2429.307647320514, 2483.4039830440743, 2491.4145175883655, 2494.87525623719, 2397.61638137898, 2412.963805542918, 2452.0069371904406, 2477.54165479386, 2482.726900040994, 2418.5283869645427, 2455.2143314139485, 2461.4584791345965, 2462.7906627936645, 2474.8762561871927, 2377.9286621401384, 2434.330559096245, 2438.548868459332, 2441.6703744994466, 2458.5943319352286, 2416.9244686114685, 2419.1774796569075, 2441.438469152105, 2443.0716327795326, 2446.816488763332, 11483.0, 11642.010429113536, 11673.649790506299, 11677.357745324003, 11680.065594752485, 5824.980628390057, 5981.671008094553, 5988.61074030191, 5989.580729348947, 5997.130416089686, 3469.757117001811, 3510.157562185083, 3575.535180426528, 3676.3015027144756, 3693.9445908311395, 1228.619128070301, 1233.2477188914754, 1258.5154715434533, 1260.7793636113643, 1327.8207441995303, 14043.78934315986, 14314.64213394663, 14730.18983955882, 15945.282462289224, 16084.356625734956, 4556.749378784199, 4587.243104887723, 4724.692894961817, 4747.0505898820375, 4750.738709371018, 12042.458089385998, 12099.87900121005, 12127.575534856238, 12142.45358958849, 12156.513739450324, 5748.13777933305, 5749.108888122348, 5751.482366587027, 5798.88402231953, 5838.095095260156, 4198.580141985838, 5216.921746173811, 5285.497877701612, 5304.0, 5366.892662146735, 3412.6861158453676, 3485.7385696072897, 3799.4110912808005, 3887.086534664352, 3888.8833334999995, 1034.2656713733184, 1041.146260066753, 1042.9009244121796, 1059.8516207730925, 1061.6231237910586, 982.8967958364411, 994.6966175316453, 1003.2024540490328, 1006.3961623026272, 1024.666983230453, 6123.724432400552, 6810.8297292567095, 6900.516963812585, 7080.9992354320675, 7106.18278897923, 924.5931790012373, 961.9326647134633, 1071.4535586850761, 1114.1810769084716, 1118.8601424821898, 3768.6985041197186, 4381.802818873159, 4451.532589078165, 4515.209838278288, 4562.270036794097, 13936.303184840717, 14471.059381140192, 14512.2018288993, 14536.709265814625, 14654.047486913316, 13497.122687025312, 14014.369353379123, 14366.784498232535, 14454.783178252335, 14460.349284282232, 9040.683576074805, 10431.009054139844, 10615.416152111553, 10676.092532134855, 10684.46577671129, 6109.511239100772, 6861.759838405632, 6979.54632948877, 7067.5052746307265, 7174.995500629811, 10822.621208257988, 12622.99016078693, 12668.99999999963, 12696.873031269939, 12743.362831858185, 10608.734781630521, 13053.412596432994, 13074.215547067206, 13238.470461181445, 13330.666733331356, 9602.183814375856, 9668.129868311886, 9682.56428460707, 9732.562034708597, 9770.902290977, 10679.199060070681, 10701.357918524913, 10756.408397538211, 10797.62208322705, 10799.67600971972, 7617.352525035212, 8175.305099066408, 9234.0, 9239.491827949527, 9247.167754902091, 8931.419457735487, 8987.640494380414, 9033.548322583712, 9169.679061232824, 9185.816283674423, 8331.0, 8380.455270407405, 8383.916160838558, 8452.36607254446, 8505.106963768716, 6009.489193418608, 6110.663913484801, 6162.137300777984, 6249.34381889895, 6257.217847769029, 6139.171211886517, 6158.137860699416, 6299.96850015747, 6333.936660633519, 6378.170837791057], "value": 5278.353099486692, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.6549286980844, 359.22228375566897, 388.2316050390472, 394.16633819471826, 399.76214152579206, 4393.868183954486, 4599.839005634799, 4760.881407773542, 5240.60695447841, 5519.4756498132665, 4212.852550160745, 4586.082783443317, 5254.448282930294, 5427.755750991205, 5428.647137936031, 3798.3732684107226, 3886.300465916134, 4021.296273152201, 4283.764392958386, 4294.530644879192, 4214.0, 4233.23801715691, 4270.167317373113, 4299.26912424887, 4299.978500107495, 4153.522344930341, 4249.597632781175, 4265.253580623394, 4289.4209281747035, 4308.82764689412, 3694.963050369502, 4035.2938235808765, 4107.527634322061, 4146.253674338618, 4249.703840328699, 3124.1252449314, 3264.722498587623, 3526.5768107827225, 3648.7081033517256, 3707.4624179494094, 4433.69206084206, 5258.370814592704, 5278.624618921501, 5285.894282114336, 5390.164524498709, 4102.712810103294, 4163.0, 4284.892877678083, 4345.9348109778375, 4915.729634870081, 4844.903101937942, 4881.1213981483315, 5119.718415487147, 5177.792888284464, 5847.883042339172, 4060.0308850207625, 4213.789310534492, 4215.388768628532, 4233.153369326147, 4253.914921701548, 5497.862553436156, 5907.291125064977, 5917.171595976567, 6015.639061656271, 6194.659293738842, 3607.621199773986, 4221.058313175912, 4248.266497601275, 4258.297380932174, 4267.850625228102, 3542.6457354264367, 3847.846086156578, 4211.031462763582, 4304.74171549708, 4316.611504964569, 3854.6530812227033, 4322.783860806947, 4588.993771653775, 4912.315763177617, 4917.237828136645, 5113.309703190096, 5242.767949531857, 5723.370429252779, 5815.539753846455, 5815.651060936273, 1787.8122797106116, 1790.534461040139, 1791.6864548703925, 1801.5406071451696, 1804.1790985101748, 1157.3692337675986, 1305.8628843971437, 1388.7430825297351, 1399.510171439988, 1406.3319923036477, 4634.374359461497, 4674.065186962621, 4784.832530861403, 4867.123917694849, 5311.044012077786, 5149.871253218699, 5249.7189256714555, 5703.0, 5720.2849643794525, 5747.0, 4622.422197225347, 4759.928601070987, 5396.757145928443, 5416.10634245353, 5451.037626454473, 2990.461716890937, 3076.8615612223175, 3110.4090222857585, 3118.454270502653, 3129.5931528901096, 6068.484178844759, 6130.877382452326, 6152.3540028297275, 6167.229096362955, 6289.018959027983, 5185.688858668492, 5807.796727114615, 5915.639402937349, 6054.424829641177, 6095.939040609537, 5135.378159656339, 5158.191187632289, 5324.041672498987, 5495.587830912697, 5781.161731548986, 5570.025245581925, 5603.691796951207, 5933.970330148321, 5943.52451803846, 5959.404059594006, 5208.24480450325, 5232.921506177334, 5822.94177058224, 5934.584579079423, 5957.0, 5691.325077213703, 5875.001249787461, 6081.939180608138, 6131.945798157667, 6166.352532984151, 4091.792921088346, 4158.604932531404, 4161.396597493407, 4325.5241923387775, 4625.704802655127, 5353.9732301338245, 5633.211350410864, 5854.9121763174435, 5873.769426815149, 5987.64074155552, 5046.949530504648, 5527.695976721319, 5693.146028095819, 5767.538596912321, 5770.44198066528, 4656.0, 5412.485813847678, 5738.196652468741, 5773.769049237983, 5785.884282314414, 5142.562882155059, 5388.787522807337, 5568.638038527645, 5595.216669666332, 5617.747201375866, 4654.25531914892, 5116.872078198075, 5152.613553983391, 5558.972205138948, 5744.224529688606, 5456.645318054314, 5537.944620553904, 5580.405111623115, 5659.462351076641, 5665.291838520185], "value": 4274.696802256571, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.9160596531399, 749.7525816480562, 765.7358211417063, 795.9164287749786, 889.6752685269877, 16299.674006519863, 16897.324107035703, 17376.91311543442, 17540.29838806448, 17800.376986805466, 16098.678026439464, 16328.265228064736, 16428.178591070428, 16604.916975415104, 16710.331586736545, 966.5264020629911, 1009.4498498318416, 1035.6996471023394, 1039.8544203811473, 1041.208681402136, 950.239808153478, 1005.894381089986, 1007.9344842585245, 1009.7425156585098, 1016.3952448293254, 1028.4292217819107, 1056.6090546497805, 1059.814532456817, 1060.247224470623, 1060.8779990301136, 1047.0628787235457, 1047.679923296648, 1048.2400259811652, 1048.8321868501002, 1050.921180911427, 780.7813812132612, 793.5080250244828, 798.0702481608919, 800.087899794235, 801.1988011988012, 17094.145292735313, 17486.47540573785, 17493.212805423787, 18410.171542280495, 18470.815291847044, 14789.926050369679, 15082.773758393632, 15185.164815935119, 15271.618209544738, 15294.158821264826, 11904.702382440508, 12237.755244895052, 12288.754224915452, 12289.52525696922, 12512.687182820411, 3856.710746693981, 3904.902377440558, 3914.743372364395, 3938.7636741795295, 4110.856120035784, 128220.0, 128336.0, 130290.0, 136761.94857077152, 137093.0, 11657.650270491898, 11674.00770934463, 11710.1217408693, 12209.206401583868, 12258.20321679088, 23684.407889802893, 24383.634245486333, 24517.75482245155, 24875.378115547257, 24888.004479820967, 8183.836323273501, 8275.379346549033, 8295.709650162215, 8315.792105197419, 8448.493090414595, 4666.8133274668635, 4669.41632295963, 4777.378940737682, 4789.257665061921, 4795.592374648124, 3540.079579309398, 3646.890593282206, 3701.1672373716237, 3710.2023065041144, 3712.535933008374, 532.8782911970325, 639.4820195641545, 649.3668673043744, 665.0024962556166, 665.0390186180922, 596.2874365133624, 625.2278436131375, 627.1783963008473, 637.2989711317514, 660.2341284110408, 10349.327293725884, 10804.243702940876, 10844.403557804239, 10913.56345746177, 11064.94882986115, 581.5260562641433, 586.4106572894299, 587.3421767620292, 593.3977013331472, 607.419913982146, 3784.9591362375018, 4281.550437204049, 4953.23224900141, 4956.033573453215, 4970.0, 79628.0, 97901.10494475247, 99841.0, 101446.0, 101945.47081793779, 64092.0, 69657.65171174111, 72890.0, 80802.59598701968, 82495.0, 66715.33284667188, 67735.66132169307, 72842.0, 73938.0, 74851.5029699403, 11140.610078647209, 11279.492422840827, 11398.658040258806, 11545.653630391102, 11563.595274165364, 46370.98588591731, 47400.52599473961, 47760.28359574539, 48726.512734872194, 49048.75475622742, 41541.0, 59809.0, 60373.39626603854, 60664.0, 60936.085958709766, 13158.494191286403, 13255.469781208643, 13444.596662100153, 13497.190168589916, 13742.175469471464, 12698.682532936751, 12772.680682982627, 13742.519011834538, 15274.389024438899, 15492.380304787683, 12148.696282593006, 12437.75124497523, 12478.625641230778, 12588.370581471074, 13019.739605207653, 11919.284842909454, 11956.940215298868, 12266.570670026505, 12606.621801345975, 12669.93665031669, 11635.301881887113, 12239.32683702405, 12266.26402415858, 12337.383130843242, 12766.234025958473, 7934.0, 9344.906550934676, 9743.610255589698, 9781.755456113655, 9917.256205784739, 9431.481268530297, 10290.12533934624, 10294.176465882862, 10539.841902371623, 11056.226064175753], "value": 7718.823440730295, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1506.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 119.0, 119.0, 120.0, 119.0, 119.0, 121.0, 134.0, 177.0, 163.0], "value": 129.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 106.0, 113.0, 145.0, 112.0, 94.0, 130.0, 142.0, 156.0, 136.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 199.0, 196.0, 194.0, 194.0, 194.0, 196.0, 196.0, 193.0, 199.0], "value": 195.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 70.0, 86.0, 53.0, 76.0, 89.0, 52.0, 66.0, 66.0], "value": 69.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 67.0, 105.0, 104.0, 71.0, 70.0, 82.0, 72.0, 71.0, 83.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 92.0, 89.0, 89.0, 88.0, 99.0, 90.0, 137.0, 111.0], "value": 97.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 135.0, 152.0, 96.0, 119.0, 154.0, 98.0, 97.0, 96.0], "value": 114.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 91.0, 90.0, 89.0, 93.0, 92.0, 89.0, 94.0, 91.0, 90.0], "value": 90.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 62.0, 63.0, 68.0, 67.0, 67.0, 69.0, 69.0, 69.0, 67.0], "value": 66.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 65.0, 60.0, 57.0, 59.0, 61.0, 60.0, 60.0, 61.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 85.0, 83.0, 86.0, 83.0, 90.0, 90.0, 102.0, 84.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 137.0, 135.0, 136.0, 132.0, 140.0, 143.0, 140.0, 160.0, 138.0], "value": 139.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 148.0, 224.0, 206.0, 160.0, 165.0, 163.0, 161.0, 163.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 56.0, 78.0, 88.0, 84.0, 86.0, 85.0, 66.0, 67.0], "value": 74.9, "unit": "score"}]}]} 13:16:21 INFO - Return code: 0 13:16:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:16:21 ERROR - # TBPL SUCCESS # 13:16:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:16:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:16:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:16:21 INFO - 2017-05-19 13:16:21.329 screenresolution[1787:10650] starting screenresolution argv=screenresolution get 13:16:21 INFO - 2017-05-19 13:16:21.331 screenresolution[1787:10650] Display 0: 1600x1200x32@60 13:16:21 INFO - 2017-05-19 13:16:21.336 screenresolution[1788:10653] starting screenresolution argv=screenresolution list 13:16:21 INFO - Available Modes on Display 0 13:16:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:16:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:16:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:16:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:16:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:16:21 INFO - Intel Iris: 13:16:21 INFO - Chipset Model: Intel Iris 13:16:21 INFO - Type: GPU 13:16:21 INFO - Bus: Built-In 13:16:21 INFO - VRAM (Dynamic, Max): 1536 MB 13:16:21 INFO - Vendor: Intel (0x8086) 13:16:21 INFO - Device ID: 0x0a2e 13:16:21 INFO - Revision ID: 0x0009 13:16:21 INFO - Displays: 13:16:21 INFO - Display: 13:16:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:16:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:16:21 INFO - Display Serial Number: 1600x1200 60 13:16:21 INFO - Main Display: Yes 13:16:21 INFO - Mirror: Off 13:16:21 INFO - Online: Yes 13:16:21 INFO - Rotation: Supported 13:16:21 INFO - Return code: 0 13:16:21 INFO - Running post-action listener: _resource_record_post_action 13:16:21 INFO - [mozharness: 2017-05-19 20:16:21.564312Z] Finished run-tests step (success) 13:16:21 INFO - Running post-run listener: _resource_record_post_run 13:16:21 INFO - Total resource usage - Wall time: 1191s; CPU: 55.0%; Read bytes: 41039872; Write bytes: 430105088; Read time: 264; Write time: 1501 13:16:21 INFO - TinderboxPrint: CPU usage
54.8% 13:16:21 INFO - TinderboxPrint: I/O read bytes / time
41,039,872 / 264 13:16:21 INFO - TinderboxPrint: I/O write bytes / time
430,105,088 / 1,501 13:16:21 INFO - TinderboxPrint: CPU idle
2,155.5 (45.2%) 13:16:21 INFO - TinderboxPrint: CPU system
966.0 (20.3%) 13:16:21 INFO - TinderboxPrint: CPU user
1,644.1 (34.5%) 13:16:21 INFO - TinderboxPrint: Swap in / out
161,976,320 / 0 13:16:21 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 112873984; Write bytes: 105160704; Read time: 9971; Write time: 225 13:16:21 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 40073216; Write bytes: 307249664; Read time: 252; Write time: 1222 13:16:22 INFO - Running post-run listener: _upload_blobber_files 13:16:22 INFO - Blob upload gear active. 13:16:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:16:22 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:16:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:16:22 INFO - (blobuploader) - INFO - Open directory for files ... 13:16:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:16:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:16:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:16:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:16:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:16:23 INFO - (blobuploader) - INFO - Done attempting. 13:16:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:16:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:16:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:16:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:16:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:16:24 INFO - (blobuploader) - INFO - Done attempting. 13:16:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:16:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:16:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:16:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:16:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:16:25 INFO - (blobuploader) - INFO - Done attempting. 13:16:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:16:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:16:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:16:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:16:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:16:26 INFO - (blobuploader) - INFO - Done attempting. 13:16:26 INFO - (blobuploader) - INFO - Iteration through files over. 13:16:26 INFO - Return code: 0 13:16:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:16:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:16:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e799b72a89e57e820ab2980c3ce6fe49b1a6aba77ac938f64fd7802b55244248f38c67ee0e9e68194a017748c7837d4643e2c038027b4c48e1cc5bb9eb0fe55", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/68dbfc2b469809f1259a791646dd8c4b4fcd0e9f4898070157f6cee0a96563a7c8b29973bfe22742b1ba86a7f2545ac704ba36368ef3d1af275312ad33003c65", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/130aa12e1a6fe4895d719c778f321b8b84e5c2daf5d1540c5785b04f4db3caa4bcfa4a567002b393b6451402cc76c4724c2c2fa5ade61bc82cb4b0fcb808b000", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b5b9495b7593a066941c7595af3d4c00d4d763335c0991c3a8abfe7931d1da257ab2dd9fbfc7ad4dfca0c909d2c9a26ef04649474724a3097b65342909e46778"} 13:16:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:16:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:16:26 INFO - Contents: 13:16:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e799b72a89e57e820ab2980c3ce6fe49b1a6aba77ac938f64fd7802b55244248f38c67ee0e9e68194a017748c7837d4643e2c038027b4c48e1cc5bb9eb0fe55", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/68dbfc2b469809f1259a791646dd8c4b4fcd0e9f4898070157f6cee0a96563a7c8b29973bfe22742b1ba86a7f2545ac704ba36368ef3d1af275312ad33003c65", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/130aa12e1a6fe4895d719c778f321b8b84e5c2daf5d1540c5785b04f4db3caa4bcfa4a567002b393b6451402cc76c4724c2c2fa5ade61bc82cb4b0fcb808b000", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b5b9495b7593a066941c7595af3d4c00d4d763335c0991c3a8abfe7931d1da257ab2dd9fbfc7ad4dfca0c909d2c9a26ef04649474724a3097b65342909e46778"} 13:16:26 INFO - Running post-run listener: copy_logs_to_upload_dir 13:16:26 INFO - Copying logs to upload dir... 13:16:26 INFO - mkdir: /builds/slave/test/build/upload/logs 13:16:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.073351 ========= master_lag: -0.13 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2017-05-19 13:16:26.852190) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 13:16:26.857210) ========= 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.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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/9e799b72a89e57e820ab2980c3ce6fe49b1a6aba77ac938f64fd7802b55244248f38c67ee0e9e68194a017748c7837d4643e2c038027b4c48e1cc5bb9eb0fe55", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/68dbfc2b469809f1259a791646dd8c4b4fcd0e9f4898070157f6cee0a96563a7c8b29973bfe22742b1ba86a7f2545ac704ba36368ef3d1af275312ad33003c65", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/130aa12e1a6fe4895d719c778f321b8b84e5c2daf5d1540c5785b04f4db3caa4bcfa4a567002b393b6451402cc76c4724c2c2fa5ade61bc82cb4b0fcb808b000", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b5b9495b7593a066941c7595af3d4c00d4d763335c0991c3a8abfe7931d1da257ab2dd9fbfc7ad4dfca0c909d2c9a26ef04649474724a3097b65342909e46778"} build_url:https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011241 build_url: 'https://queue.taskcluster.net/v1/task/FjXDzAcESOCNmNEj1VrWtw/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/9e799b72a89e57e820ab2980c3ce6fe49b1a6aba77ac938f64fd7802b55244248f38c67ee0e9e68194a017748c7837d4643e2c038027b4c48e1cc5bb9eb0fe55", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/68dbfc2b469809f1259a791646dd8c4b4fcd0e9f4898070157f6cee0a96563a7c8b29973bfe22742b1ba86a7f2545ac704ba36368ef3d1af275312ad33003c65", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/130aa12e1a6fe4895d719c778f321b8b84e5c2daf5d1540c5785b04f4db3caa4bcfa4a567002b393b6451402cc76c4724c2c2fa5ade61bc82cb4b0fcb808b000", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b5b9495b7593a066941c7595af3d4c00d4d763335c0991c3a8abfe7931d1da257ab2dd9fbfc7ad4dfca0c909d2c9a26ef04649474724a3097b65342909e46778"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 13:16:26.890029) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 13:16:26.890351) ========= 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.KwIe7B6J4M/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.qdH6Kr2UpC/Listeners TMPDIR=/var/folders/63/97m36r0j7js9vzr9g5gsghq400000w/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.005221 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 13:16:26.938501) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 13:16:26.938810) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 13:16:26.939113) ========= ========= Total master_lag: 3.51 =========