builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0090 starttime: 1494934020.8 results: success (0) buildid: 20170516032124 builduid: 2c77510d08ef43c88b03a7fb5009510e revision: 8bf3d5c1b4b0de2673a74d56c7399a80c5431243 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 04:27:00.802497) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-16 04:27:00.802927) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 04:27:00.803297) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-16 04:27:00.829264) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 04:27:00.829629) ========= 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.NHaMyKLOxT/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.wEy2k3gS83/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-16 04:27:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.4M=0.001s 2017-05-16 04:27:00 (13.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124934 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 04:27:00.977839) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 04:27:00.978150) ========= 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.NHaMyKLOxT/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.wEy2k3gS83/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.028570 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 04:27:01.027511) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-16 04:27:01.027878) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 8bf3d5c1b4b0de2673a74d56c7399a80c5431243 --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 8bf3d5c1b4b0de2673a74d56c7399a80c5431243 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NHaMyKLOxT/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.wEy2k3gS83/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-16 04:27:01,106 truncating revision to first 12 chars 2017-05-16 04:27:01,106 Setting DEBUG logging. 2017-05-16 04:27:01,106 attempt 1/10 2017-05-16 04:27:01,106 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/8bf3d5c1b4b0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-16 04:27:02,221 unpacking tar archive at: mozilla-beta-8bf3d5c1b4b0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.389588 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-16 04:27:02.440228) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 04:27:02.440588) ========= script_repo_revision: 8bf3d5c1b4b0de2673a74d56c7399a80c5431243 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-16 04:27:02.440972) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 04:27:02.441248) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-16 04:27:02.460344) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-16 04:27:02.460697) ========= /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.NHaMyKLOxT/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.wEy2k3gS83/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 04:27:02 INFO - MultiFileLogger online at 20170516 04:27:02 in /builds/slave/test 04:27:02 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 04:27:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:27:02 INFO - {'append_to_log': False, 04:27:02 INFO - 'base_work_dir': '/builds/slave/test', 04:27:02 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:27:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:27:02 INFO - 'branch': 'Mozilla-Beta', 04:27:02 INFO - 'buildbot_json_path': 'buildprops.json', 04:27:02 INFO - 'config_files': ('talos/mac_config.py',), 04:27:02 INFO - 'default_actions': ('clobber', 04:27:02 INFO - 'read-buildbot-config', 04:27:02 INFO - 'download-and-extract', 04:27:02 INFO - 'populate-webroot', 04:27:02 INFO - 'create-virtualenv', 04:27:02 INFO - 'install', 04:27:02 INFO - 'run-tests'), 04:27:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:27:02 INFO - 'download_minidump_stackwalk': True, 04:27:02 INFO - 'download_symbols': 'ondemand', 04:27:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:27:02 INFO - 'tooltool.py': '/tools/tooltool.py', 04:27:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:27:02 INFO - '/tools/misc-python/virtualenv.py')}, 04:27:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:27:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:27:02 INFO - 'gecko_profile': False, 04:27:02 INFO - 'gecko_profile_interval': 0, 04:27:02 INFO - 'installer_path': 'installer.exe', 04:27:02 INFO - 'log_level': 'info', 04:27:02 INFO - 'log_name': 'talos', 04:27:02 INFO - 'log_to_console': True, 04:27:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:27:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:27:02 INFO - 'opt_config_files': (), 04:27:02 INFO - 'pip_index': False, 04:27:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:27:02 INFO - 'cmd': ('bash', 04:27:02 INFO - '-c', 04:27:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:27:02 INFO - 'enabled': True, 04:27:02 INFO - 'halt_on_failure': False, 04:27:02 INFO - 'name': 'check_screen_resolution'},), 04:27:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:27:02 INFO - 'cmd': ('bash', 04:27:02 INFO - '-c', 04:27:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:27:02 INFO - 'enabled': True, 04:27:02 INFO - 'halt_on_failure': False, 04:27:02 INFO - 'name': 'check_screen_resolution'},), 04:27:02 INFO - 'run_cmd_checks_enabled': True, 04:27:02 INFO - 'suite': 'dromaeojs-e10s', 04:27:02 INFO - 'system_bits': '32', 04:27:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:27:02 INFO - 'title': 't-yosemite-r7-0090', 04:27:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:27:02 INFO - 'use_talos_json': True, 04:27:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:27:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:27:02 INFO - 'work_dir': 'build'} 04:27:02 INFO - [mozharness: 2017-05-16 11:27:02.648087Z] Running clobber step. 04:27:02 INFO - Running pre-action listener: _resource_record_pre_action 04:27:02 INFO - Running main action method: clobber 04:27:02 INFO - rmtree: /builds/slave/test/build 04:27:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:27:05 INFO - Running post-action listener: _resource_record_post_action 04:27:05 INFO - [mozharness: 2017-05-16 11:27:05.198238Z] Finished clobber step (success) 04:27:05 INFO - [mozharness: 2017-05-16 11:27:05.198360Z] Running read-buildbot-config step. 04:27:05 INFO - Running pre-action listener: _resource_record_pre_action 04:27:05 INFO - Running main action method: read_buildbot_config 04:27:05 INFO - Using buildbot properties: 04:27:05 INFO - { 04:27:05 INFO - "project": "", 04:27:05 INFO - "product": "firefox", 04:27:05 INFO - "script_repo_revision": "production", 04:27:05 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 04:27:05 INFO - "repository": "", 04:27:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 04:27:05 INFO - "buildid": "20170516032124", 04:27:05 INFO - "pgo_build": "False", 04:27:05 INFO - "basedir": "/builds/slave/test", 04:27:05 INFO - "buildnumber": 34, 04:27:05 INFO - "slavename": "t-yosemite-r7-0090", 04:27:05 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 04:27:05 INFO - "platform": "yosemite_r7", 04:27:05 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 04:27:05 INFO - "branch": "mozilla-beta", 04:27:05 INFO - "repo_path": "releases/mozilla-beta", 04:27:05 INFO - "revision": "8bf3d5c1b4b0de2673a74d56c7399a80c5431243", 04:27:05 INFO - "stage_platform": "macosx64", 04:27:05 INFO - "builduid": "2c77510d08ef43c88b03a7fb5009510e", 04:27:05 INFO - "slavebuilddir": "test" 04:27:05 INFO - } 04:27:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 04:27:05 INFO - Running post-action listener: _resource_record_post_action 04:27:05 INFO - [mozharness: 2017-05-16 11:27:05.201697Z] Finished read-buildbot-config step (success) 04:27:05 INFO - [mozharness: 2017-05-16 11:27:05.201815Z] Running download-and-extract step. 04:27:05 INFO - Running pre-action listener: _resource_record_pre_action 04:27:05 INFO - Running main action method: download_and_extract 04:27:05 INFO - mkdir: /builds/slave/test/build/tests 04:27:05 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')]} 04:27:05 INFO - trying https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 04:27:05 INFO - Downloading https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/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 04:27:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/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 04:27:06 INFO - Downloaded 1287 bytes. 04:27:06 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 04:27:06 INFO - Using the following test package requirements: 04:27:06 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:27:06 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 04:27:06 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 04:27:06 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:27:06 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 04:27:06 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:27:06 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 04:27:06 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:27:06 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:27:06 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 04:27:06 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 04:27:06 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:27:06 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 04:27:06 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:27:06 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 04:27:06 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:27:06 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 04:27:06 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 04:27:06 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 04:27:06 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 04:27:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 04:27:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 04:27:06 INFO - Fetch https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 04:27:08 INFO - Expected file size: 51226491 04:27:08 INFO - Obtained file size: 51226491 04:27:24 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 04:27:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 04:27:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 04:27:24 INFO - Fetch https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 04:27:26 INFO - Expected file size: 51226491 04:27:26 INFO - Obtained file size: 51226491 04:27:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 04:27:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 04:27:41 INFO - Fetch https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 04:27:42 INFO - Expected file size: 14404449 04:27:42 INFO - Obtained file size: 14404449 04:27:43 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')]} 04:27:43 INFO - trying https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:27:43 INFO - Downloading https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 04:27:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/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 04:27:44 INFO - Downloaded 59540733 bytes. 04:27:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:27:44 INFO - mkdir: /builds/slave/test/properties 04:27:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:27:44 INFO - Writing to file /builds/slave/test/properties/build_url 04:27:44 INFO - Contents: 04:27:44 INFO - build_url:https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 04:27:45 INFO - Running post-action listener: _resource_record_post_action 04:27:45 INFO - Running post-action listener: set_extra_try_arguments 04:27:45 INFO - [mozharness: 2017-05-16 11:27:45.378723Z] Finished download-and-extract step (success) 04:27:45 INFO - [mozharness: 2017-05-16 11:27:45.378909Z] Running populate-webroot step. 04:27:45 INFO - Running pre-action listener: _resource_record_pre_action 04:27:45 INFO - Running main action method: populate_webroot 04:27:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:27:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:27:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:27:45 INFO - '--tppagecycles', 04:27:45 INFO - '7'], 04:27:45 INFO - 'tests': ['tsvgm']}}, 04:27:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:27:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:27:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:27:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:27:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:27:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:27:45 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:27:45 INFO - 'talos_options': ['--disable-e10s'], 04:27:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:27:45 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:27:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:27:45 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:27:45 INFO - 'talos_options': ['--disable-e10s'], 04:27:45 INFO - 'tests': ['damp', 'tps']}, 04:27:45 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:27:45 INFO - 'tests': ['damp', 'tps']}, 04:27:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:27:45 INFO - 'tests': ['dromaeo_dom']}, 04:27:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:27:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:27:45 INFO - 'tests': ['basic_compositor_video']}, 04:27:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:27:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:27:45 INFO - 'tests': ['a11yr', 04:27:45 INFO - 'ts_paint', 04:27:45 INFO - 'tpaint', 04:27:45 INFO - 'sessionrestore', 04:27:45 INFO - 'sessionrestore_no_auto_restore', 04:27:45 INFO - 'tabpaint']}, 04:27:45 INFO - 'other-e10s': {'tests': ['a11yr', 04:27:45 INFO - 'ts_paint', 04:27:45 INFO - 'tpaint', 04:27:45 INFO - 'sessionrestore', 04:27:45 INFO - 'sessionrestore_no_auto_restore', 04:27:45 INFO - 'tabpaint']}, 04:27:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:27:45 INFO - 'ts_paint', 04:27:45 INFO - 'tpaint', 04:27:45 INFO - 'sessionrestore', 04:27:45 INFO - 'sessionrestore_no_auto_restore', 04:27:45 INFO - 'tabpaint']}, 04:27:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:27:45 INFO - 'ts_paint', 04:27:45 INFO - 'tpaint', 04:27:45 INFO - 'sessionrestore', 04:27:45 INFO - 'sessionrestore_no_auto_restore', 04:27:45 INFO - 'tabpaint']}, 04:27:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:27:45 INFO - 'tests': ['a11yr', 04:27:45 INFO - 'ts_paint', 04:27:45 INFO - 'tpaint', 04:27:45 INFO - 'sessionrestore', 04:27:45 INFO - 'sessionrestore_no_auto_restore', 04:27:45 INFO - 'tabpaint']}, 04:27:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:27:45 INFO - 'tests': ['a11yr', 04:27:45 INFO - 'ts_paint', 04:27:45 INFO - 'tpaint', 04:27:45 INFO - 'sessionrestore', 04:27:45 INFO - 'sessionrestore_no_auto_restore', 04:27:45 INFO - 'tabpaint']}, 04:27:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:27:45 INFO - 'tests': ['tsvgx', 04:27:45 INFO - 'tsvgr_opacity', 04:27:45 INFO - 'tart', 04:27:45 INFO - 'tscrollx', 04:27:45 INFO - 'cart', 04:27:45 INFO - 'tsvg_static']}, 04:27:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:27:45 INFO - 'tsvgr_opacity', 04:27:45 INFO - 'tart', 04:27:45 INFO - 'tscrollx', 04:27:45 INFO - 'cart', 04:27:45 INFO - 'tsvg_static']}, 04:27:45 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:27:45 INFO - 'talos_options': ['--disable-e10s'], 04:27:45 INFO - 'tests': ['tp5o']}, 04:27:45 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:27:45 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:27:45 INFO - 'talos_options': ['--disable-e10s', 04:27:45 INFO - '--xperf_path', 04:27:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:27:45 INFO - 'tests': ['tp5n']}, 04:27:45 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:27:45 INFO - 'talos_options': ['--xperf_path', 04:27:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:27:45 INFO - 'tests': ['tp5n']}}, 04:27:45 INFO - 'talos.zip': {'path': '', 04:27:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:27:45 INFO - Running post-action listener: _resource_record_post_action 04:27:45 INFO - [mozharness: 2017-05-16 11:27:45.399131Z] Finished populate-webroot step (success) 04:27:45 INFO - [mozharness: 2017-05-16 11:27:45.399267Z] Running create-virtualenv step. 04:27:45 INFO - Running pre-action listener: _resource_record_pre_action 04:27:45 INFO - Running main action method: create_virtualenv 04:27:45 INFO - Creating virtualenv /builds/slave/test/build/venv 04:27:45 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 04:27:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:27:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:27:45 INFO - Using real prefix '/tools/python27' 04:27:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:27:46 INFO - Installing distribute.............................................................................................................................................................................................done. 04:27:49 INFO - Installing pip.................done. 04:27:49 INFO - Return code: 0 04:27:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:27:49 INFO - Reading from file tmpfile_stdout 04:27:49 INFO - Output received: 04:27:49 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) 04:27:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:49 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 0x10a083b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a086768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6abd69fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e61d30>, '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 0x109d88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e56be8>, '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.NHaMyKLOxT/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.wEy2k3gS83/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 04:27:49 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 04:27:49 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 04:27:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NHaMyKLOxT/Render', 04:27:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:49 INFO - 'HOME': '/Users/cltbld', 04:27:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:49 INFO - 'LOGNAME': 'cltbld', 04:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:49 INFO - 'MOZ_NO_REMOTE': '1', 04:27:49 INFO - 'NO_EM_RESTART': '1', 04:27:49 INFO - 'PAGER': '/bin/cat', 04:27:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:49 INFO - 'PWD': '/builds/slave/test', 04:27:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:49 INFO - 'SHELL': '/bin/bash', 04:27:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEy2k3gS83/Listeners', 04:27:49 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:27:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:49 INFO - 'USER': 'cltbld', 04:27:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:49 INFO - 'XPC_FLAGS': '0x0', 04:27:49 INFO - 'XPC_SERVICE_NAME': '0', 04:27:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:49 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 04:27:49 INFO - Cleaning up... 04:27:49 INFO - Return code: 0 04:27:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:49 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 0x10a083b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a086768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6abd69fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e61d30>, '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 0x109d88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e56be8>, '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.NHaMyKLOxT/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.wEy2k3gS83/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 04:27:49 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 04:27:49 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 04:27:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NHaMyKLOxT/Render', 04:27:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:49 INFO - 'HOME': '/Users/cltbld', 04:27:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:49 INFO - 'LOGNAME': 'cltbld', 04:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:49 INFO - 'MOZ_NO_REMOTE': '1', 04:27:49 INFO - 'NO_EM_RESTART': '1', 04:27:49 INFO - 'PAGER': '/bin/cat', 04:27:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:49 INFO - 'PWD': '/builds/slave/test', 04:27:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:49 INFO - 'SHELL': '/bin/bash', 04:27:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEy2k3gS83/Listeners', 04:27:49 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:27:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:49 INFO - 'USER': 'cltbld', 04:27:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:49 INFO - 'XPC_FLAGS': '0x0', 04:27:49 INFO - 'XPC_SERVICE_NAME': '0', 04:27:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:49 INFO - Downloading/unpacking psutil>=3.1.1 04:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:27:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:27:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:27:51 INFO - Installing collected packages: psutil 04:27:51 INFO - Running setup.py install for psutil 04:27:51 INFO - building 'psutil._psutil_osx' extension 04:27:51 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 04:27:52 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 04:27:52 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 04:27:52 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 04:27:52 INFO - building 'psutil._psutil_posix' extension 04:27:52 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 04:27:52 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:27:52 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:27:52 INFO - ^ 04:27:52 INFO - 1 warning generated. 04:27:52 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 04:27:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:27:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:27:53 INFO - Successfully installed psutil 04:27:53 INFO - Cleaning up... 04:27:53 INFO - Return code: 0 04:27:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a083b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a086768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6abd69fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e61d30>, '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 0x109d88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e56be8>, '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.NHaMyKLOxT/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.wEy2k3gS83/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 04:27:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:27:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:27:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NHaMyKLOxT/Render', 04:27:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:53 INFO - 'HOME': '/Users/cltbld', 04:27:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:53 INFO - 'LOGNAME': 'cltbld', 04:27:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:53 INFO - 'MOZ_NO_REMOTE': '1', 04:27:53 INFO - 'NO_EM_RESTART': '1', 04:27:53 INFO - 'PAGER': '/bin/cat', 04:27:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:53 INFO - 'PWD': '/builds/slave/test', 04:27:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:53 INFO - 'SHELL': '/bin/bash', 04:27:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEy2k3gS83/Listeners', 04:27:53 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:27:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:53 INFO - 'USER': 'cltbld', 04:27:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:53 INFO - 'XPC_FLAGS': '0x0', 04:27:53 INFO - 'XPC_SERVICE_NAME': '0', 04:27:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:27:53 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 04:27:53 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 04:27:53 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 04:27:53 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 04:27:54 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:27:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:27:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:27:54 INFO - Installing collected packages: mozsystemmonitor 04:27:54 INFO - Running setup.py install for mozsystemmonitor 04:27:55 INFO - Successfully installed mozsystemmonitor 04:27:55 INFO - Cleaning up... 04:27:55 INFO - Return code: 0 04:27:55 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a083b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a086768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6abd69fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e61d30>, '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 0x109d88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e56be8>, '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.NHaMyKLOxT/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.wEy2k3gS83/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 04:27:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 04:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 04:27:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NHaMyKLOxT/Render', 04:27:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:55 INFO - 'HOME': '/Users/cltbld', 04:27:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:55 INFO - 'LOGNAME': 'cltbld', 04:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:55 INFO - 'MOZ_NO_REMOTE': '1', 04:27:55 INFO - 'NO_EM_RESTART': '1', 04:27:55 INFO - 'PAGER': '/bin/cat', 04:27:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:55 INFO - 'PWD': '/builds/slave/test', 04:27:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:55 INFO - 'SHELL': '/bin/bash', 04:27:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEy2k3gS83/Listeners', 04:27:55 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:27:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:55 INFO - 'USER': 'cltbld', 04:27:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:55 INFO - 'XPC_FLAGS': '0x0', 04:27:55 INFO - 'XPC_SERVICE_NAME': '0', 04:27:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:55 INFO - Downloading/unpacking jsonschema==2.5.1 04:27:55 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 04:27:55 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 04:27:55 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 04:27:55 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 04:27:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:27:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:27:56 INFO - Downloading functools32-3.2.3-2.tar.gz 04:27:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:27:57 INFO - warning: no files found matching '*.txt' 04:27:57 INFO - no previously-included directories found matching 'build' 04:27:57 INFO - no previously-included directories found matching 'dist' 04:27:57 INFO - no previously-included directories found matching '.git*' 04:27:57 INFO - Installing collected packages: jsonschema, functools32 04:27:57 INFO - Running setup.py install for functools32 04:27:57 INFO - warning: no files found matching '*.txt' 04:27:57 INFO - no previously-included directories found matching 'build' 04:27:57 INFO - no previously-included directories found matching 'dist' 04:27:57 INFO - no previously-included directories found matching '.git*' 04:27:57 INFO - Successfully installed jsonschema functools32 04:27:57 INFO - Cleaning up... 04:27:57 INFO - Return code: 0 04:27:57 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a083b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a086768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6abd69fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e61d30>, '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 0x109d88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e56be8>, '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.NHaMyKLOxT/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.wEy2k3gS83/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 04:27:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:27:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NHaMyKLOxT/Render', 04:27:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:57 INFO - 'HOME': '/Users/cltbld', 04:27:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:57 INFO - 'LOGNAME': 'cltbld', 04:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:57 INFO - 'MOZ_NO_REMOTE': '1', 04:27:57 INFO - 'NO_EM_RESTART': '1', 04:27:57 INFO - 'PAGER': '/bin/cat', 04:27:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:57 INFO - 'PWD': '/builds/slave/test', 04:27:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:57 INFO - 'SHELL': '/bin/bash', 04:27:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEy2k3gS83/Listeners', 04:27:57 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:27:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:57 INFO - 'USER': 'cltbld', 04:27:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:57 INFO - 'XPC_FLAGS': '0x0', 04:27:57 INFO - 'XPC_SERVICE_NAME': '0', 04:27:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:58 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:27:58 INFO - Cleaning up... 04:27:58 INFO - Return code: 0 04:27:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:27:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:27:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:27:58 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 0x10a083b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a086768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6abd69fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e61d30>, '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 0x109d88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e56be8>, '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.NHaMyKLOxT/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.wEy2k3gS83/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 04:27:58 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 04:27:58 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 04:27:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NHaMyKLOxT/Render', 04:27:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:27:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:27:58 INFO - 'HOME': '/Users/cltbld', 04:27:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:27:58 INFO - 'LOGNAME': 'cltbld', 04:27:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:27:58 INFO - 'MOZ_NO_REMOTE': '1', 04:27:58 INFO - 'NO_EM_RESTART': '1', 04:27:58 INFO - 'PAGER': '/bin/cat', 04:27:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:27:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:27:58 INFO - 'PWD': '/builds/slave/test', 04:27:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:27:58 INFO - 'SHELL': '/bin/bash', 04:27:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEy2k3gS83/Listeners', 04:27:58 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:27:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:27:58 INFO - 'USER': 'cltbld', 04:27:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:27:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:27:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:27:58 INFO - 'XPC_FLAGS': '0x0', 04:27:58 INFO - 'XPC_SERVICE_NAME': '0', 04:27:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:27:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:27:58 INFO - Downloading/unpacking blobuploader==1.2.4 04:27:58 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 04:27:58 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 04:27:58 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 04:27:58 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 04:28:00 INFO - Downloading blobuploader-1.2.4.tar.gz 04:28:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:28:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:28:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:28:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:28:01 INFO - Downloading docopt-0.6.1.tar.gz 04:28:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:28:01 INFO - Installing collected packages: blobuploader, requests, docopt 04:28:01 INFO - Running setup.py install for blobuploader 04:28:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:28:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:28:01 INFO - Running setup.py install for requests 04:28:02 INFO - Running setup.py install for docopt 04:28:02 INFO - Successfully installed blobuploader requests docopt 04:28:02 INFO - Cleaning up... 04:28:02 INFO - Return code: 0 04:28:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:28:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:02 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 0x10a083b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a086768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6abd69fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e61d30>, '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 0x109d88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e56be8>, '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.NHaMyKLOxT/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.wEy2k3gS83/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 04:28:02 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 04:28:02 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 04:28:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NHaMyKLOxT/Render', 04:28:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:02 INFO - 'HOME': '/Users/cltbld', 04:28:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:02 INFO - 'LOGNAME': 'cltbld', 04:28:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:02 INFO - 'MOZ_NO_REMOTE': '1', 04:28:02 INFO - 'NO_EM_RESTART': '1', 04:28:02 INFO - 'PAGER': '/bin/cat', 04:28:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:02 INFO - 'PWD': '/builds/slave/test', 04:28:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:02 INFO - 'SHELL': '/bin/bash', 04:28:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEy2k3gS83/Listeners', 04:28:02 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:28:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:02 INFO - 'USER': 'cltbld', 04:28:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:02 INFO - 'XPC_FLAGS': '0x0', 04:28:02 INFO - 'XPC_SERVICE_NAME': '0', 04:28:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:28:02 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-r2Z34a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:28:02 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-dbl82c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:28:02 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-05DNLO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:28:02 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-qsPqOu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:28:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-pDReH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:28:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-kekZL4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:28:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-mU02Zz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:28:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-DICZ3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:28:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-f2gwjo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:28:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-pkGSjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:28:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-2Sf9vI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:28:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-9Aj8Sy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:28:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Q0d0Sh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:28:03 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-kpYxMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:04 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-wiqAPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:28:04 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-iu4_4c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:28:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:28:04 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-EqXdUh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:28:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:28:04 INFO - Running setup.py install for manifestparser 04:28:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:28:04 INFO - Running setup.py install for mozcrash 04:28:04 INFO - Running setup.py install for mozdebug 04:28:04 INFO - Running setup.py install for mozdevice 04:28:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:28:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:28:05 INFO - Running setup.py install for mozfile 04:28:05 INFO - Running setup.py install for mozhttpd 04:28:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:28:05 INFO - Running setup.py install for mozinfo 04:28:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:28:05 INFO - Running setup.py install for mozInstall 04:28:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:28:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:28:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:28:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:28:05 INFO - Running setup.py install for mozleak 04:28:05 INFO - Running setup.py install for mozlog 04:28:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:28:05 INFO - Running setup.py install for moznetwork 04:28:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:28:06 INFO - Running setup.py install for mozprocess 04:28:06 INFO - Running setup.py install for mozprofile 04:28:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:28:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:28:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:28:06 INFO - Running setup.py install for mozrunner 04:28:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:28:06 INFO - Running setup.py install for mozscreenshot 04:28:06 INFO - Running setup.py install for moztest 04:28:06 INFO - Running setup.py install for mozversion 04:28:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:28:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:28:06 INFO - Cleaning up... 04:28:06 INFO - Return code: 0 04:28:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:28:06 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')]} 04:28:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28:06 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')]} 04:28:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:06 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 0x10a083b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a086768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6abd69fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e61d30>, '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 0x109d88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e56be8>, '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.NHaMyKLOxT/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.wEy2k3gS83/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 04:28:06 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 04:28:06 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 04:28:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NHaMyKLOxT/Render', 04:28:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:06 INFO - 'HOME': '/Users/cltbld', 04:28:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:06 INFO - 'LOGNAME': 'cltbld', 04:28:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:06 INFO - 'MOZ_NO_REMOTE': '1', 04:28:06 INFO - 'NO_EM_RESTART': '1', 04:28:06 INFO - 'PAGER': '/bin/cat', 04:28:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:06 INFO - 'PWD': '/builds/slave/test', 04:28:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:06 INFO - 'SHELL': '/bin/bash', 04:28:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEy2k3gS83/Listeners', 04:28:06 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:28:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:06 INFO - 'USER': 'cltbld', 04:28:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:06 INFO - 'XPC_FLAGS': '0x0', 04:28:06 INFO - 'XPC_SERVICE_NAME': '0', 04:28:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:28:07 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-bYoFTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:28:07 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)) 04:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:28:07 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-cOJYK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:28:07 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)) 04:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:28:07 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Awfzg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:28:07 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)) 04:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:28:07 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-9ZTIJp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:28:07 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)) 04:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:28:07 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-rxZx_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:28:07 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)) 04:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:28:07 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-rCynkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:28:07 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)) 04:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:28:07 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-C0hsbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:28:07 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)) 04:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:28:07 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-NtbcJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:28:08 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)) 04:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:28:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-6YuFMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:28:08 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)) 04:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:28:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-J4SRS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:28:08 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)) 04:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:28:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-oV34KR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:28:08 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)) 04:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:28:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-GcPJKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:28:08 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)) 04:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:28:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-imUDAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:28:08 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)) 04:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:28:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-vSkSYQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:28:08 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)) 04:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-HGEJoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:28:08 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)) 04:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:28:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-5LMxKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:28:08 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)) 04:28:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:28:08 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-e35av0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:28:09 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)) 04:28:09 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)) 04:28:09 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)) 04:28:09 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)) 04:28:09 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)) 04:28:09 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)) 04:28:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:28:09 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 04:28:09 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 04:28:09 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 04:28:09 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 04:28:10 INFO - Downloading blessings-1.6.tar.gz 04:28:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:28:10 INFO - Installing collected packages: blessings 04:28:10 INFO - Running setup.py install for blessings 04:28:10 INFO - Successfully installed blessings 04:28:10 INFO - Cleaning up... 04:28:10 INFO - Return code: 0 04:28:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:28:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:28:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:28:11 INFO - Reading from file tmpfile_stdout 04:28:11 INFO - Current package versions: 04:28:11 INFO - blessings == 1.6 04:28:11 INFO - blobuploader == 1.2.4 04:28:11 INFO - docopt == 0.6.1 04:28:11 INFO - functools32 == 3.2.3-2 04:28:11 INFO - jsonschema == 2.5.1 04:28:11 INFO - manifestparser == 1.1 04:28:11 INFO - mozInstall == 1.12 04:28:11 INFO - mozcrash == 1.0 04:28:11 INFO - mozdebug == 0.1 04:28:11 INFO - mozdevice == 0.49 04:28:11 INFO - mozfile == 1.2 04:28:11 INFO - mozhttpd == 0.7 04:28:11 INFO - mozinfo == 0.9 04:28:11 INFO - mozleak == 0.1 04:28:11 INFO - mozlog == 3.4 04:28:11 INFO - moznetwork == 0.27 04:28:11 INFO - mozprocess == 0.25 04:28:11 INFO - mozprofile == 0.28 04:28:11 INFO - mozrunner == 6.13 04:28:11 INFO - mozscreenshot == 0.1 04:28:11 INFO - mozsystemmonitor == 0.3 04:28:11 INFO - moztest == 0.8 04:28:11 INFO - mozversion == 1.4 04:28:11 INFO - psutil == 3.1.1 04:28:11 INFO - requests == 1.2.3 04:28:11 INFO - wsgiref == 0.1.2 04:28:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:28: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')]} 04:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28: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')]} 04:28:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28:11 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 0x10a083b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a086768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6abd69fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e61d30>, '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 0x109d88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e56be8>, '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.NHaMyKLOxT/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.wEy2k3gS83/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 04:28:11 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 04:28:11 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 04:28:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NHaMyKLOxT/Render', 04:28:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:11 INFO - 'HOME': '/Users/cltbld', 04:28:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:11 INFO - 'LOGNAME': 'cltbld', 04:28:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:11 INFO - 'MOZ_NO_REMOTE': '1', 04:28:11 INFO - 'NO_EM_RESTART': '1', 04:28:11 INFO - 'PAGER': '/bin/cat', 04:28:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:11 INFO - 'PWD': '/builds/slave/test', 04:28:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:11 INFO - 'SHELL': '/bin/bash', 04:28:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEy2k3gS83/Listeners', 04:28:11 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:28:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:11 INFO - 'USER': 'cltbld', 04:28:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:11 INFO - 'XPC_FLAGS': '0x0', 04:28:11 INFO - 'XPC_SERVICE_NAME': '0', 04:28:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:11 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)) 04:28:11 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)) 04:28:11 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)) 04:28:11 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)) 04:28:11 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)) 04:28:11 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)) 04:28:11 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)) 04:28:11 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)) 04:28:11 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)) 04:28:11 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)) 04:28:11 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)) 04:28:11 INFO - Cleaning up... 04:28:11 INFO - Return code: 0 04:28:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:28: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')]} 04:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:28: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')]} 04:28:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:28: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a083b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a086768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc6abd69fc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109e61d30>, '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 0x109d88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e56be8>, '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.NHaMyKLOxT/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.wEy2k3gS83/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 04:28: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', 'jsonschema'] in /builds/slave/test/build 04:28: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 jsonschema 04:28:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NHaMyKLOxT/Render', 04:28:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:11 INFO - 'HOME': '/Users/cltbld', 04:28:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:11 INFO - 'LOGNAME': 'cltbld', 04:28:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:11 INFO - 'MOZ_NO_REMOTE': '1', 04:28:11 INFO - 'NO_EM_RESTART': '1', 04:28:11 INFO - 'PAGER': '/bin/cat', 04:28:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:11 INFO - 'PWD': '/builds/slave/test', 04:28:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:11 INFO - 'SHELL': '/bin/bash', 04:28:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEy2k3gS83/Listeners', 04:28:11 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:28:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:11 INFO - 'USER': 'cltbld', 04:28:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:11 INFO - 'XPC_FLAGS': '0x0', 04:28:11 INFO - 'XPC_SERVICE_NAME': '0', 04:28:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:28:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:28:11 INFO - Cleaning up... 04:28:11 INFO - Return code: 0 04:28:11 INFO - Running post-action listener: _resource_record_post_action 04:28:11 INFO - Running post-action listener: _start_resource_monitoring 04:28:11 INFO - Starting resource monitoring. 04:28:11 INFO - [mozharness: 2017-05-16 11:28:11.592322Z] Finished create-virtualenv step (success) 04:28:11 INFO - [mozharness: 2017-05-16 11:28:11.593285Z] Running install step. 04:28:11 INFO - Running pre-action listener: _resource_record_pre_action 04:28:11 INFO - Running main action method: install 04:28:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:28:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:28:11 INFO - Reading from file tmpfile_stdout 04:28:11 INFO - Detecting whether we're running mozinstall >=1.0... 04:28:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:28:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:28:11 INFO - Reading from file tmpfile_stdout 04:28:11 INFO - Output received: 04:28:11 INFO - Usage: mozinstall [options] installer 04:28:11 INFO - Options: 04:28:11 INFO - -h, --help show this help message and exit 04:28:11 INFO - -d DEST, --destination=DEST 04:28:11 INFO - Directory to install application into. [default: 04:28:11 INFO - "/builds/slave/test"] 04:28:11 INFO - --app=APP Application being installed. [default: firefox] 04:28:11 INFO - mkdir: /builds/slave/test/build/application 04:28:11 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'] 04:28:11 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 04:28:26 INFO - Reading from file tmpfile_stdout 04:28:26 INFO - Output received: 04:28:26 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 04:28:26 INFO - Running post-action listener: _resource_record_post_action 04:28:26 INFO - [mozharness: 2017-05-16 11:28:26.474292Z] Finished install step (success) 04:28:26 INFO - [mozharness: 2017-05-16 11:28:26.474429Z] Running run-tests step. 04:28:26 INFO - Running pre-action listener: _resource_record_pre_action 04:28:26 INFO - Running main action method: run_tests 04:28:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:28:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:28:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:28:26 INFO - 2017-05-16 04:28:26.489 screenresolution[1621:7422] starting screenresolution argv=screenresolution get 04:28:26 INFO - 2017-05-16 04:28:26.491 screenresolution[1621:7422] Display 0: 1600x1200x32@60 04:28:26 INFO - 2017-05-16 04:28:26.497 screenresolution[1622:7424] starting screenresolution argv=screenresolution list 04:28:26 INFO - Available Modes on Display 0 04:28:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:28:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:28:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:28:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:28:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:28:26 INFO - Intel Iris: 04:28:26 INFO - Chipset Model: Intel Iris 04:28:26 INFO - Type: GPU 04:28:26 INFO - Bus: Built-In 04:28:26 INFO - VRAM (Dynamic, Max): 1536 MB 04:28:26 INFO - Vendor: Intel (0x8086) 04:28:26 INFO - Device ID: 0x0a2e 04:28:26 INFO - Revision ID: 0x0009 04:28:26 INFO - Displays: 04:28:26 INFO - Display: 04:28:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:28:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:28:26 INFO - Display Serial Number: 1600x1200 60 04:28:26 INFO - Main Display: Yes 04:28:26 INFO - Mirror: Off 04:28:26 INFO - Online: Yes 04:28:26 INFO - Rotation: Supported 04:28:26 INFO - Return code: 0 04:28:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:28:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:28:26 INFO - Python 2.7.3 04:28:26 INFO - Return code: 0 04:28:26 INFO - grabbing minidump binary from tooltool 04:28:26 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')]} 04:28:26 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 0x109e61d30>, '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 0x109d88db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109e56be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:28:26 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 04:28:26 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 04:28:26 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 04:28:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:28:26 INFO - Return code: 0 04:28:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:28:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:28:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:28:26 INFO - ENV: RUST_BACKTRACE is now 1 04:28:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:28:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:28:26 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/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--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 04:28:26 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/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0090 --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 04:28:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NHaMyKLOxT/Render', 04:28:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:28:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:28:26 INFO - 'HOME': '/Users/cltbld', 04:28:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:28:26 INFO - 'LOGNAME': 'cltbld', 04:28:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:28:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:28:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:28:26 INFO - 'MOZ_NO_REMOTE': '1', 04:28:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:28:26 INFO - 'NO_EM_RESTART': '1', 04:28:26 INFO - 'PAGER': '/bin/cat', 04:28:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:28:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:28:26 INFO - 'PWD': '/builds/slave/test', 04:28:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:28:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:28:26 INFO - 'RUST_BACKTRACE': '1', 04:28:26 INFO - 'SHELL': '/bin/bash', 04:28:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEy2k3gS83/Listeners', 04:28:26 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 04:28:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:28:26 INFO - 'USER': 'cltbld', 04:28:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:28:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:28:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:28:26 INFO - 'XPC_FLAGS': '0x0', 04:28:26 INFO - 'XPC_SERVICE_NAME': '0', 04:28:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:28:26 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/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--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 04:28:27 INFO - mozversion application_buildid: 20170516032124 04:28:27 INFO - mozversion application_changeset: 8bf3d5c1b4b0de2673a74d56c7399a80c5431243 04:28:27 INFO - mozversion application_display_name: Firefox 04:28:27 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:28:27 INFO - mozversion application_name: Firefox 04:28:27 INFO - mozversion application_remotingname: firefox 04:28:27 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:28:27 INFO - mozversion application_vendor: Mozilla 04:28:27 INFO - mozversion application_version: 54.0 04:28:27 INFO - mozversion platform_buildid: 20170516032124 04:28:27 INFO - mozversion platform_changeset: 8bf3d5c1b4b0de2673a74d56c7399a80c5431243 04:28:27 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:28:27 INFO - mozversion platform_version: 54.0 04:28:27 INFO - using testdate: 1494934107 04:28:27 INFO - actual date: 1494934107 04:28:27 INFO - starting webserver on 'localhost:49224' 04:28:27 INFO - SUITE-START | Running 2 tests 04:28:27 INFO - TEST-START | dromaeo_css 04:28:27 INFO - operating with platform_type : mac_ 04:28:27 INFO - Initialising browser for dromaeo_css test... 04:28:27 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpL3a9Ga/profile http://localhost:49224/getInfo.html) 04:28:29 INFO - PROCESS | 1632 | 2017-05-16 04:28:29.788 plugin-container[1635:7590] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4e43, name = 'com.apple.tsm.portname' 04:28:29 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:28:29 INFO - PROCESS | 1632 | 2017-05-16 04:28:29.789 plugin-container[1635:7590] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 04:28:29 INFO - PROCESS | 1632 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:28:29 INFO - PROCESS | 1632 | 2017-05-16 04:28:29.789 plugin-container[1635:7590] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:28:29 INFO - PROCESS | 1632 | __metrics Screen width/height:1600/1200 04:28:29 INFO - PROCESS | 1632 | colorDepth:24 04:28:29 INFO - PROCESS | 1632 | Browser inner width/height: 1024/689 04:28:29 INFO - PROCESS | 1632 | __metrics 04:28:29 ERROR - PROCESS | 1632 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 04:28:30 INFO - TEST-INFO | 1632: exit 0 04:28:30 INFO - Browser initialized. 04:28:30 INFO - Running cycle 1/1 for dromaeo_css test... 04:28:30 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpL3a9Ga/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:28:32 INFO - PROCESS | 1636 | 2017-05-16 04:28:32.760 plugin-container[1637:7732] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 04:28:32 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:28:32 INFO - PROCESS | 1636 | 2017-05-16 04:28:32.763 plugin-container[1637:7732] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 04:28:32 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:28:32 INFO - PROCESS | 1636 | 2017-05-16 04:28:32.763 plugin-container[1637:7732] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:28:42 INFO - PROCESS | 1636 | RSS: Main: 173883392 04:28:42 INFO - PROCESS | 1636 | 04:31:45 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 04:31:46 INFO - PROCESS | 1636 | RSS: Main: 191750144 04:31:46 INFO - PROCESS | 1636 | 04:34:49 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 04:34:49 INFO - PROCESS | 1636 | RSS: Main: 202342400 04:34:49 INFO - PROCESS | 1636 | 04:37:51 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 04:37:52 INFO - PROCESS | 1636 | RSS: Main: 205041664 04:37:52 INFO - PROCESS | 1636 | 04:40:54 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 04:40:55 INFO - PROCESS | 1636 | RSS: Main: 208220160 04:40:55 INFO - PROCESS | 1636 | 04:43:58 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 04:43:58 INFO - PROCESS | 1636 | RSS: Main: 211058688 04:43:58 INFO - PROCESS | 1636 | 04:47:01 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 04:47:01 INFO - PROCESS | 1636 | RSS: Main: 209199104 04:47:01 INFO - PROCESS | 1636 | 04:47:01 INFO - PROCESS | 1636 | __start_tp_report 04:47:01 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 04:47:01 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 04:47:01 INFO - PROCESS | 1636 | |i|pagename|runs| 04:47:01 INFO - PROCESS | 1636 | |0;dojo.html;3589.030961640357;3594.730395220359;3619.7647152935056;3631.5460567429072;3633.963660363396;5980.222571065756;6229.719662615182;6229.781957631484;6233.657148856817;6260.5930614510025;5046.772895219715;6192.256929168495;6222.160008398865;6240.500759939206;6276.497880169587;5575.470330318613;5612.887742245152;5632.042552766019;5666.5183459406;5734.79928202513;5704.600677952545;5708.571857110713;5710.257666503369;5716.514096301798;5719.599628026019;5493.642913210629;5499.202615620733;5610.186522954169;5698.373178950312;5737.913931291034;5036.748162591855;5400.351957765054;5594.468525490072;5613.719314034322;5620.690862002588;5485.87318138936;5639.774409023634;5644.209810626516;5672.858178545569;5700.287464066992;5114;6043.3956604339655;6086.300075491286;6178.38216178383;6275.497960163176;6470.2559205691;6488.416042556192;6508.54440189187;6549.541532092755;6650.634215118166;5649.971750141264;6752.729890804362;6778.69495872682;6784.219814721271;6798.898016529706;5201.9479805201845;6208.565400422018;6248;6258.123862659231;6293.779717709904;6156.52243461566;6174.845628859314;6559.868802623921;6859.5541289816;6898.586084834926;6169.753209871645;6375.362463753588;6447.806565803034;6483.157189565326;6489.1888513935755;6685.665716714145;6713.295104014106;6736.8989465158065;6740.662966851638;6751.493637977172;6646.468282537386;6650.733970641217;6654.633995130314;6654.966725166343;6659.467242620675;5933.910991335133;6105.626234097382;6351.936480635227;6359.96820015897;6604.8348791280605;5364.249005139283;5368.409474957752;5386.649867758583;5387.973060134675;5409.7295135243085;4207.389928460356;4690.179218636725;4729.6452766042685;4743.928841067387;4749.335093086971;6145.262568491809;6156.722947467375;6297.590656607306;6315.147455093595;6317.6209427434505;5408.432114627986;5506.531944784658;6602.438792702578;6630.569013014138;6632.50256230775;6282.096026324937;6288.151099601587;6293.118963345135;6294.181756371642;6301.6534090625455;5690.089585666274;5933.495652869468;5980.342162362137;5994.1608174855555;5997.460228579449;6678.964760462039;6679.231888332807;6725.663716814238;6766.7969960901255;6873.931260687529;6727.663616819042;6740.460763139035;6744.966275168789;6769.864602707819;6780.38976492118;6751.864962700817;6845.760398386033;6851.417629501549;6854.520183587101;6887.690053947485;6351.9201735705965;6408.58344207625;6459.903101453576;6486.772962946275;6637.103990961158;6706.8993965089585;6712.127423434922;6712.899306510309;6725.125733654593;6749.932500675127;6152.938470615416;6269.435750782452;6542.018697195459;6547.574407663486;6587.308332625005;6453.322401148;6463.515236357198;6467.482601391784;6498.3826536478955;6501.772437964649;5415.83752487426;6238.719257633326;6300.905486417799;6408.1349017881985;6409.198850143732;6528.118703974903;6546.0508226305965;6555.967220163869;6565.737370505126;6572.868542629216;5630.634008789416;5801.100829371454;6319.715612797343;6382.680865956777;6431 04:47:01 INFO - PROCESS | 1636 | |1;ext.html;711.1075600121846;740.5556666000399;852.5225873510834;995.6067353395409;995.8954309797466;38255;39430.40854387183;40327;41816.37275440863;41886.37170442442;29036.564451533242;39834.8049558513;39916.60083399165;40039;40162.19675606486;8340.83318333633;8483.66065357385;11282.830757538644;11558.826617600742;11792.292462452237;11432.028277596415;11534.365609891453;11543.826842597367;11555.653330400102;11561.132915031365;10676.466176691134;10728.141748660088;11531.711707207303;11544.826827597593;11591.710207244801;8508.829823403497;11451.484683189277;11467.541298348056;11542.07663386927;11779.293242405398;11202.215844890861;11253.887461125447;11268.32390056591;11274;11305.26515776472;27658;35151.4727279091;35178.82410587956;35410.291794163975;35564.75523356696;3900.960990390088;3939.665128464084;3940.2316548273097;3941.369380899071;3961.207758448322;21748.608373099996;26014.6097808533;26784.464310713676;27291.45417091667;27299.590506142424;3197;3223.2103134731906;3247.269364392993;3269.182704323919;3271.9672803272138;320940.395298022;326497;383375.9999999972;391316.04341978393;393027;44966.32550511745;45508;45623.54376456259;45659;45715.77142114268;46805.531944680115;46841.29738053932;46846.29730554044;46933;47034.76482617565;14417.85582144186;14433.639159020899;14507.984441089022;14527;14549;12394.81407778884;12619.495220191273;12652.304123273125;12667.176633518791;12672.303023333621;12218.450169742382;12230.960368368611;12281.69295767613;12292.446839892227;12378.81431778524;5856.531477481792;5861.736221870026;5902.8819423611285;5929.288485381696;5931.881362372815;1153.4405813180601;1174.3364998775664;1177.7997740384155;1185.82805493203;1224.2925949723299;23096.07615695387;27259.182224533295;27442.862785685942;27645.373689499465;27701;1292.4184117147322;1303.5958852755675;1324.6423465664216;1326.1976504215004;1334.8064530643003;13840.792388114187;14233.43066277358;14295.07082039664;14458.710825783635;14484.34820433062;398354;401947;402130.9786902152;404047;406576;55851;56165;56238.71880640653;56405.717971410704;56523.86952260932;1485.5469081929898;1525.3364786317986;1525.6643538421752;1534.9309281082155;1537.423466200175;24927.875360623082;26532.602010970237;26583;26592.601110982963;26682.332941676614;46878.29682554833;47575.52424475711;48684;50573.98852022865;50998.490015100855;518025;518245;518383;518730;521936;27657.7554010066;28271.01051463189;33738.83130584331;33904.98285051453;34065.14837129092;39807;39825;40133;40190.598094018686;40885.38671919983;27961.021364252152;28718.856405717837;29387.41225175527;29436.969706060187;29888;29224.41551168922;29653.087663739676;29794.70205297919;31003.534946976266;31299.68700312968;29189.562156568092;34799.826000869834;35189.64810351864;35272.82363588166;35689.82155089312;22885.970131343798;26614.46771064529;26731.19806405811;27450.725492744816;27925;19014.809851901857;21092.367228983156;21092.47268818292;24031.279061628178;24428.511429770948 04:47:01 INFO - PROCESS | 1636 | |2;jquery.html;409.5740429952849;418.2555052007428;419.4274814877693;435.1275692237064;457.93301607255097;18272.994985275804;21280.14879404824;22209.888950555265;22236.555268894634;23101.768982310172;23267;23283.534329313403;23890.76109238907;24005.039798408085;24327.87836060817;5447.727613619303;6977.33715297046;7244.8188795280275;7259.673314700824;7267.055282813227;7131.0729605151255;7176.892346614805;7246.420286377077;7257.818554536152;7270.163931147933;6897;7130.8573828523395;7220.819479513002;7230.276972302754;7233.746818861341;6884.001819736135;7061.929380706179;7115.430765538745;7132.215456299803;7196.568205907636;6946.6179360135175;6954.65226738661;6964.199117101548;6965.059716938199;6978.720851165947;28151.718482815115;28296.575551366746;28329.57505637417;28425.43149137006;28585.285367865763;2990.7607391408637;3090.165655273086;3105.270261488571;3151.6848315168527;3269.2807582331848;16422;17184.05487698176;17199.742003869953;17238;17644.911775441167;2069.1102825784906;2123.5540536487356;2124.426404870692;2126.2983215538834;2137.8717276963275;135929;181961.27058094006;188077;188670;190317.04841475704;16339.101349425659;22041;22378.440538986655;22522.986465609087;22582.77417225807;33583.16042098967;34048.48927266093;34231.4865277021;34273.82863085669;34332.97001089971;9703.320767546345;10982.35397572932;11013.953674400918;11374.545018199346;11675.649730508097;9559.474228917337;9583.377080489747;9606.471644059504;9630.759231019141;9638.95180524107;9884.55519501624;9983.450910199863;9998.250131240186;10006.949965250127;10108.898911010943;3941.0491806555365;4177.164239180677;4192.559781222989;4203.5376108628025;4211.284081706117;921.3412410126791;1062.7555662197738;1062.825925006208;1102.9448527573588;1131.575659127827;23281.650775238384;23412.181875308408;23597.882010589838;23616;23650.881745591505;1102.9613963511238;1104.6078642082107;1127.9830802537967;1130.8077626803463;1144.473542170594;11820.881791181979;11959.820602690967;12036;12071.758564828655;12093.153479256544;716625;744742;766776;803108;811887;126355.36822316014;149342;171452;173123;173799;1279.9040071994639;1317.2952470428274;1367.3231750283474;1370.6641872741243;1403.8596140385675;19542.60914781725;20118.59762804765;21578;21772.56454870862;21902;65129.67435162794;65700.67149664222;66478.33521664915;68176;68423.65788171027;83933.58033209795;87354.56322718346;93450.06549934414;96018.51990740001;96566.0343396557;9426.387284826464;11835.171537992257;12170.087243456599;12238.510459581874;12303.384830758247;13448.327583620976;13566.525171618947;13848;13928.930355348564;14024.22866742339;9878.802423951336;10050.69847904564;11331.943340283246;11866.991305738764;12117;8647.005594356478;8805.779855503664;9301.813963720822;9960.854501732394;10841.970012848768;10171.38971661673;10247.231457640557;10253.328034969143;10320.793584128425;11987.34069626144;10066.194704423775;11520.827187592025;12302.384880756106;12544.247345158954;12569.811452828031;9559.18746906493;11752.58865939688;11849.763004740029;11980.682124966072;11991.22057066288 04:47:01 INFO - PROCESS | 1636 | |3;mootools.html;1537.6924615076987;1628.9167703477187;1633.616100216448;1654.0902503623004;1662.9750553741692;11180.273282236649;11536.480858361372;11896.286222826626;11919.880801191985;11931.164818462701;11604.535818567247;11647.242929209595;11776.293422394663;11796.292222466636;11854.75525069866;2432.075811191749;2482.391814005562;2485.5028994201143;2494.052260141144;2495.6007038873786;2401.9159329423474;2435.464197876473;2463.556559819232;2473.332200305916;2479.4049428137296;2400.3399065257017;2423.2730180945646;2423.6001059825107;2472.7279999200073;2477.355887469262;2364.1252736487436;2405.1702162753822;2413.0227257960555;2419.322589674894;2422.903083876643;2396.2451827674226;2414.806815454777;2423.890924908366;2438.402591365109;2441.56051910656;11569.016633586156;11826.704332391759;11835.171537992344;11837;11851.229670071418;5765.596408251424;5966.224390829165;5968.462838344526;5993.310769261547;5993.850153746148;3569.1434055826676;3643.9271214575824;3670.247599242149;3685.1155722626377;3696.7597106188014;1259.741752940645;1263.949442022318;1315.4672357695054;1327.4026687990442;1336.53889408155;11679.708007299885;13576.999999999902;14330.99683022189;14636;16107.91946040274;4608.28571571407;4619;4777.689450185727;4782.569568738831;4791.808327666854;12260.693482663004;12336.50653973849;12337.69155771095;12361.6291511253;12364.1963272387;5923.585349025527;5927.140564618193;5965.135055417027;5967.045272756425;5969.761209551656;5238.895222095537;5275;5321.494458026481;5363.839084827461;5388.2725832012175;3284.474484082535;3838.5393752749846;3933.92132157361;3972.324704800191;4009.919801603952;844.3329769482164;1001.5943542865151;1017.31839667423;1037.896210378971;1039.6828967164936;843.7890527368158;846.1284876577013;867.4299517872671;886.2998231397253;890.8841850559386;6882.414994725505;6916;6950.721971121098;7027.543209691354;7156.355927966508;894.8791913091779;986.3490096536252;1013.8506366503061;1034.5499707627207;1046.947652617366;4151.4395556599475;4386.1008493258805;4392.956070439254;4403.559644035635;4514.774261286922;13734.381952811947;14363.56909292723;14373.6406589834;14391.856081439051;14400.135991840523;13511.189328640314;14044.016918815585;14295.92852035733;14344.641383965485;14496.275186240646;9017.954910225539;10446.68659940203;10669.466526673636;10739.248252622398;10796.460176991119;5732.598718089694;6125.877482450237;6693.230278518033;6822.863542729018;6866.279040700854;12299.75400492003;12307.753844922872;12317.322547259986;12374.690632734253;12395.946344560813;12984.740305194033;12996.895263902295;13101.213927164401;13144.60566183016;13158.539451119164;9336.299777516577;9625.278104142357;9640.987696291788;9653.420794752337;9674.951625241829;10164.593416263267;10465.110465610203;10491.737706557396;10712.14302855754;10782.946085269523;9100.499472529074;9196.402233855046;9242.953785231031;9247.86128208064;9388.906110938804;7108.395786358011;8904;9006.369554131148;9064.410813297114;9138.497382644018;8193.959030204811;8222.958885205535;8228.794280143044;8254;8277.379196560161;5979.222701048836;5985.162077309094;6002.579819412774;6120.265568131853;6206;6014.578979471395;6065.362352164802;6273.749050037947;6293.40212679813;6342.175517182736 04:47:01 INFO - PROCESS | 1636 | |4;prototype.html;396.13839898221363;400.37941191153703;401.02150752164727;402.5210000099881;406.723428068913;4674.761188285107;4845.110406941287;5080.466551012141;5506.146547285172;5545.77816887325;4432.4237849079655;5335.386430560487;5352.5182733553975;5413.350397952251;5473.618975745553;4065.939010914824;4172.812223449944;4210.726302790309;4211.136716973028;4226.45056142701;3779.4897688811934;3832.5209348831395;3894.6494815466604;4018.276710192164;4249.596288352602;4007;4049.473568436099;4125.236831186226;4206.47419072616;4208.789560521977;3714.832832522543;4073;4105.712600117993;4193.483909654214;4194.580541945811;3689.2252626948375;3693.575238847513;3713.4615480755137;3724.4599533067553;3756.887293381203;4512.646206138178;4793.472718001017;5006.924896126561;5274.835098940644;5286.735663216824;3996.800159991989;4065.6137666921595;4699.67102302839;4818.783154758009;4879;5362.865928351784;5721.0846264597885;5737.3688894221605;5755.712214389264;5767.942320576783;4125.64931980782;4137.731047481904;4147.357159640261;4168.687348448879;4188.895277618053;5128.794848206067;5236.869078273074;5560.220729366604;5706.343770466367;5926.525877929756;4264.57354264571;4592.471865735483;4794.472608013116;5023.066119544002;5073.619478539124;4218.430511880857;5152.433232344439;5162.805807531784;5181.948180518222;5208.354152093474;4960.702357858468;5056.241563765465;5362.758675859595;5378.714046530209;5379.435159308295;5704.229928959538;5705.429457054344;5714.999001398076;5721.914171287434;5728.914066289009;1676.0362791394753;1745.677049745801;1753.587906841891;1758.8303777987494;1785.973210401845;1077.8144041554228;1105.739457018999;1145.7364806094645;1206.8474606750533;1367.974019485386;5049.84850454487;5144.8713782155;5332.786893439257;5352.4915133062295;5361.544268737201;5110.821121260738;5907.616004959664;5937.376575459601;5959.493443057302;5984.611000284967;4517.841875534341;4747.620190384761;5183.6932565008965;5323.334583177103;5338.786448542092;2854.0735003872305;2929.604503392015;3058.785884988074;3077.8768849246226;3094.783365164417;5210.583153347723;5782.335031471351;5822.650640961471;5902.3507414184405;6015.368386319461;5204.71374074422;5276.7310056676;5687.232223649837;5880.411958804086;6056.545759068087;5293.920591191135;5522.641028333226;5854.8875713615125;6083.513318934474;6106.725197366218;5208.270842082187;5517.393086760373;5631.2960879890015;5900.144479050427;5929.16991621182;5138.614603904647;5611.691356975405;5837.416258374129;5850.356460789225;5852.068817290199;5184.326037615086;5329.760160792851;5397.298351214474;6064.090386442069;6087.4521293083835;4359.847405340798;4648.8842677757775;4677.742724150068;4699.660596729916;4713.575778179843;5639.436056394327;5890.322612899571;5897.587168898136;5953.291252480025;5997.670128142994;5692.6299790513485;5743.167240750151;5743.483086522233;5744.167095771173;5793.478586927196;4968.975155124202;5264.420913699567;5303.984485895335;5402.919416116871;5834.5203288603025;4612.469565999953;4790.850195953016;5194.792208311776;5620.971895140498;5650.802221922378;5114.644619175847;5235.267062611161;5667.716614169358;5768.586696259444;5794.072948328248;4999.77501012448;5425.918611220914;5613.46672066153;5661;5709.65742055478 04:47:01 INFO - PROCESS | 1636 | |5;yui.html;606.9453749162575;676.3540818518312;770.4645271536281;802.1617409806752;905.1536813079769;15486.845131548682;16089.678206435865;16336.8564200506;16690.749638755413;17272.56818579536;13566.728665426686;15654.982426142293;15824.683506329866;15902.522924312289;16125.83874161258;1021.7956408718248;1054.29889123733;1059.1685526861415;1059.8569193158903;1060.125396547851;991.900809919006;1037.4968140451874;1045.3518818332645;1047.235518071808;1049.5172220778413;1077.5097330714507;1081.32957566309;1082.4912291223152;1084.996378350027;1085.093946554624;1078.6440474643348;1078.724925144091;1079.3308148947663;1081.7620123572801;1081.789051135029;795.1650766694947;802.8233788566506;802.8594995875699;805.2832978649008;808.3573559020592;16380.344786208538;16450.83549164505;16981.830181698147;18212;18484.72272915894;14651.853481465156;14718.55844324672;14845.851541484555;14941.477048303366;15123.092614443058;11798.351090690008;12180.695482612917;12181;12266.509339626404;12272.140950133495;3783.9621603783885;3792.241551689673;3799.411091280856;3840.1935593525395;3965.40518922164;67231;84016.31967360618;101018.49490752499;107653;121451.3927430366;10867.945660271647;11254.268472549258;11334.263272887236;11397.088232941345;11424.828627570592;22213.111475540798;22342.888285558794;22349.77650223477;22387.440313991956;22494.887525562266;7685.03937007874;7826.530408175414;7905.960470197612;7944.880826787603;7976.760697179094;4482.238019536687;4498.527654596286;4532.524084971096;4570.4743954445;4660.951285960699;2559.8976040958155;2933.5013047781927;3520.0143959691327;3530.470429435605;3564.1446052947626;637.1939496536885;637.8979363301942;643.9098526206335;650.7039297119799;652.0935899100255;593.2110293309887;653.4968074582545;659.643792352134;682.8975653651992;689.131694065471;8804.691835785718;9162.221211197122;9443.057030202408;9769.853452198222;10325.32885362449;696.4776417686735;701.7333413303014;709.095902724031;709.3509438863382;718.6899026546927;4592.448906131285;4857.417109946829;4868;4882.170031094723;4905.3623029006;103208.45187322196;104912.47543762233;105090;106728;109372;50171.4948551544;65921.67039164774;82179.58910205531;90273;93620.53189734144;58921.11618325728;67203.66398168076;71189.64405177941;72520.99999999894;78706.81939770909;10867.402292873969;11116.110711142928;11390.202685811913;11445.885541144815;11458.77082458363;40112;48469.27296090491;50529.49470505248;51124;52481.21278180754;64991;66886;66996.33003670097;67269.32730672826;67295.99056014261;13040.087193896336;13109.868901310865;13143.868561314264;13145.93427032897;13332.2667253302;14936;14963.027403218755;15073.321700523284;15337.386504539694;15961.122138282506;13358.332083395986;13529.797053044409;13739.862601373858;13742.106763060368;14218.324825709542;13429.127106738031;13787.699571286801;14157.716845663235;14205.218712970885;14477;13079.869201307865;13287.66780830487;13508.121972071784;14027.228502432465;14122.929385353007;9942.353747006422;10080.193584513368;10114.494275286354;10148.188144948239;10354.378737275787;10104.949475252577;11176.095490505038;11246;11431.59989400367;11513.194076414906 04:47:01 INFO - PROCESS | 1636 | __end_tp_report 04:47:01 INFO - PROCESS | 1636 | __start_cc_report 04:47:01 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,400 04:47:01 INFO - PROCESS | 1636 | __end_cc_report 04:47:01 INFO - PROCESS | 1636 | __startTimestamp1494935221755__endTimestamp 04:47:01 INFO - PROCESS | 1636 | 04:47:01 INFO - PROCESS | 1636 | ------- Summary: start ------- 04:47:01 INFO - PROCESS | 1636 | Number of tests: 6 04:47:01 INFO - PROCESS | 1636 | 04:47:01 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:6101.47 Median:6293.12 stddev:692.63 (11.0%) stddev-sans-first:666.12 04:47:01 INFO - PROCESS | 1636 | Values: 3589.0 3594.7 3619.8 3631.5 3634.0 5980.2 6229.7 6229.8 6233.7 6260.6 5046.8 6192.3 6222.2 6240.5 6276.5 5575.5 5612.9 5632.0 5666.5 5734.8 5704.6 5708.6 5710.3 5716.5 5719.6 5493.6 5499.2 5610.2 5698.4 5737.9 5036.7 5400.4 5594.5 5613.7 5620.7 5485.9 5639.8 5644.2 5672.9 5700.3 5114.0 6043.4 6086.3 6178.4 6275.5 6470.3 6488.4 6508.5 6549.5 6650.6 5650.0 6752.7 6778.7 6784.2 6798.9 5201.9 6208.6 6248.0 6258.1 6293.8 6156.5 6174.8 6559.9 6859.6 6898.6 6169.8 6375.4 6447.8 6483.2 6489.2 6685.7 6713.3 6736.9 6740.7 6751.5 6646.5 6650.7 6654.6 6655.0 6659.5 5933.9 6105.6 6351.9 6360.0 6604.8 5364.2 5368.4 5386.6 5388.0 5409.7 4207.4 4690.2 4729.6 4743.9 4749.3 6145.3 6156.7 6297.6 6315.1 6317.6 5408.4 5506.5 6602.4 6630.6 6632.5 6282.1 6288.2 6293.1 6294.2 6301.7 5690.1 5933.5 5980.3 5994.2 5997.5 6679.0 6679.2 6725.7 6766.8 6873.9 6727.7 6740.5 6745.0 6769.9 6780.4 6751.9 6845.8 6851.4 6854.5 6887.7 6351.9 6408.6 6459.9 6486.8 6637.1 6706.9 6712.1 6712.9 6725.1 6749.9 6152.9 6269.4 6542.0 6547.6 6587.3 6453.3 6463.5 6467.5 6498.4 6501.8 5415.8 6238.7 6300.9 6408.1 6409.2 6528.1 6546.1 6556.0 6565.7 6572.9 5630.6 5801.1 6319.7 6382.7 6431.0 04:47:01 INFO - PROCESS | 1636 | 04:47:01 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:55916.81 Median:29412.19 stddev:115316.41 (392.1%) stddev-sans-first:115565.41 04:47:01 INFO - PROCESS | 1636 | Values: 711.1 740.6 852.5 995.6 995.9 38255.0 39430.4 40327.0 41816.4 41886.4 29036.6 39834.8 39916.6 40039.0 40162.2 8340.8 8483.7 11282.8 11558.8 11792.3 11432.0 11534.4 11543.8 11555.7 11561.1 10676.5 10728.1 11531.7 11544.8 11591.7 8508.8 11451.5 11467.5 11542.1 11779.3 11202.2 11253.9 11268.3 11274.0 11305.3 27658.0 35151.5 35178.8 35410.3 35564.8 3901.0 3939.7 3940.2 3941.4 3961.2 21748.6 26014.6 26784.5 27291.5 27299.6 3197.0 3223.2 3247.3 3269.2 3272.0 320940.4 326497.0 383376.0 391316.0 393027.0 44966.3 45508.0 45623.5 45659.0 45715.8 46805.5 46841.3 46846.3 46933.0 47034.8 14417.9 14433.6 14508.0 14527.0 14549.0 12394.8 12619.5 12652.3 12667.2 12672.3 12218.5 12231.0 12281.7 12292.4 12378.8 5856.5 5861.7 5902.9 5929.3 5931.9 1153.4 1174.3 1177.8 1185.8 1224.3 23096.1 27259.2 27442.9 27645.4 27701.0 1292.4 1303.6 1324.6 1326.2 1334.8 13840.8 14233.4 14295.1 14458.7 14484.3 398354.0 401947.0 402131.0 404047.0 406576.0 55851.0 56165.0 56238.7 56405.7 56523.9 1485.5 1525.3 1525.7 1534.9 1537.4 24927.9 26532.6 26583.0 26592.6 26682.3 46878.3 47575.5 48684.0 50574.0 50998.5 518025.0 518245.0 518383.0 518730.0 521936.0 27657.8 28271.0 33738.8 33905.0 34065.1 39807.0 39825.0 40133.0 40190.6 40885.4 27961.0 28718.9 29387.4 29437.0 29888.0 29224.4 29653.1 29794.7 31003.5 31299.7 29189.6 34799.8 35189.6 35272.8 35689.8 22886.0 26614.5 26731.2 27450.7 27925.0 19014.8 21092.4 21092.5 24031.3 24428.5 04:47:01 INFO - PROCESS | 1636 | 04:47:01 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:45110.41 Median:23347.86 stddev:128959.45 (552.3%) stddev-sans-first:129277.54 04:47:01 INFO - PROCESS | 1636 | Values: 409.6 418.3 419.4 435.1 457.9 18273.0 21280.1 22209.9 22236.6 23101.8 23267.0 23283.5 23890.8 24005.0 24327.9 5447.7 6977.3 7244.8 7259.7 7267.1 7131.1 7176.9 7246.4 7257.8 7270.2 6897.0 7130.9 7220.8 7230.3 7233.7 6884.0 7061.9 7115.4 7132.2 7196.6 6946.6 6954.7 6964.2 6965.1 6978.7 28151.7 28296.6 28329.6 28425.4 28585.3 2990.8 3090.2 3105.3 3151.7 3269.3 16422.0 17184.1 17199.7 17238.0 17644.9 2069.1 2123.6 2124.4 2126.3 2137.9 135929.0 181961.3 188077.0 188670.0 190317.0 16339.1 22041.0 22378.4 22523.0 22582.8 33583.2 34048.5 34231.5 34273.8 34333.0 9703.3 10982.4 11014.0 11374.5 11675.6 9559.5 9583.4 9606.5 9630.8 9639.0 9884.6 9983.5 9998.3 10006.9 10108.9 3941.0 4177.2 4192.6 4203.5 4211.3 921.3 1062.8 1062.8 1102.9 1131.6 23281.7 23412.2 23597.9 23616.0 23650.9 1103.0 1104.6 1128.0 1130.8 1144.5 11820.9 11959.8 12036.0 12071.8 12093.2 716625.0 744742.0 766776.0 803108.0 811887.0 126355.4 149342.0 171452.0 173123.0 173799.0 1279.9 1317.3 1367.3 1370.7 1403.9 19542.6 20118.6 21578.0 21772.6 21902.0 65129.7 65700.7 66478.3 68176.0 68423.7 83933.6 87354.6 93450.1 96018.5 96566.0 9426.4 11835.2 12170.1 12238.5 12303.4 13448.3 13566.5 13848.0 13928.9 14024.2 9878.8 10050.7 11331.9 11867.0 12117.0 8647.0 8805.8 9301.8 9960.9 10842.0 10171.4 10247.2 10253.3 10320.8 11987.3 10066.2 11520.8 12302.4 12544.2 12569.8 9559.2 11752.6 11849.8 11980.7 11991.2 04:47:01 INFO - PROCESS | 1636 | 04:47:01 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:6927.52 Median:2478.38 stddev:4337.24 (175.0%) stddev-sans-first:4330.50 04:47:01 INFO - PROCESS | 1636 | Values: 1537.7 1628.9 1633.6 1654.1 1663.0 11180.3 11536.5 11896.3 11919.9 11931.2 11604.5 11647.2 11776.3 11796.3 11854.8 2432.1 2482.4 2485.5 2494.1 2495.6 2401.9 2435.5 2463.6 2473.3 2479.4 2400.3 2423.3 2423.6 2472.7 2477.4 2364.1 2405.2 2413.0 2419.3 2422.9 2396.2 2414.8 2423.9 2438.4 2441.6 11569.0 11826.7 11835.2 11837.0 11851.2 5765.6 5966.2 5968.5 5993.3 5993.9 3569.1 3643.9 3670.2 3685.1 3696.8 1259.7 1263.9 1315.5 1327.4 1336.5 11679.7 13577.0 14331.0 14636.0 16107.9 4608.3 4619.0 4777.7 4782.6 4791.8 12260.7 12336.5 12337.7 12361.6 12364.2 5923.6 5927.1 5965.1 5967.0 5969.8 5238.9 5275.0 5321.5 5363.8 5388.3 3284.5 3838.5 3933.9 3972.3 4009.9 844.3 1001.6 1017.3 1037.9 1039.7 843.8 846.1 867.4 886.3 890.9 6882.4 6916.0 6950.7 7027.5 7156.4 894.9 986.3 1013.9 1034.5 1046.9 4151.4 4386.1 4393.0 4403.6 4514.8 13734.4 14363.6 14373.6 14391.9 14400.1 13511.2 14044.0 14295.9 14344.6 14496.3 9018.0 10446.7 10669.5 10739.2 10796.5 5732.6 6125.9 6693.2 6822.9 6866.3 12299.8 12307.8 12317.3 12374.7 12395.9 12984.7 12996.9 13101.2 13144.6 13158.5 9336.3 9625.3 9641.0 9653.4 9675.0 10164.6 10465.1 10491.7 10712.1 10782.9 9100.5 9196.4 9243.0 9247.9 9388.9 7108.4 8904.0 9006.4 9064.4 9138.5 8194.0 8223.0 8228.8 8254.0 8277.4 5979.2 5985.2 6002.6 6120.3 6206.0 6014.6 6065.4 6273.7 6293.4 6342.2 04:47:01 INFO - PROCESS | 1636 | 04:47:01 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4722.99 Median:5184.01 stddev:1324.37 (25.5%) stddev-sans-first:1287.65 04:47:01 INFO - PROCESS | 1636 | Values: 396.1 400.4 401.0 402.5 406.7 4674.8 4845.1 5080.5 5506.1 5545.8 4432.4 5335.4 5352.5 5413.4 5473.6 4065.9 4172.8 4210.7 4211.1 4226.5 3779.5 3832.5 3894.6 4018.3 4249.6 4007.0 4049.5 4125.2 4206.5 4208.8 3714.8 4073.0 4105.7 4193.5 4194.6 3689.2 3693.6 3713.5 3724.5 3756.9 4512.6 4793.5 5006.9 5274.8 5286.7 3996.8 4065.6 4699.7 4818.8 4879.0 5362.9 5721.1 5737.4 5755.7 5767.9 4125.6 4137.7 4147.4 4168.7 4188.9 5128.8 5236.9 5560.2 5706.3 5926.5 4264.6 4592.5 4794.5 5023.1 5073.6 4218.4 5152.4 5162.8 5181.9 5208.4 4960.7 5056.2 5362.8 5378.7 5379.4 5704.2 5705.4 5715.0 5721.9 5728.9 1676.0 1745.7 1753.6 1758.8 1786.0 1077.8 1105.7 1145.7 1206.8 1368.0 5049.8 5144.9 5332.8 5352.5 5361.5 5110.8 5907.6 5937.4 5959.5 5984.6 4517.8 4747.6 5183.7 5323.3 5338.8 2854.1 2929.6 3058.8 3077.9 3094.8 5210.6 5782.3 5822.7 5902.4 6015.4 5204.7 5276.7 5687.2 5880.4 6056.5 5293.9 5522.6 5854.9 6083.5 6106.7 5208.3 5517.4 5631.3 5900.1 5929.2 5138.6 5611.7 5837.4 5850.4 5852.1 5184.3 5329.8 5397.3 6064.1 6087.5 4359.8 4648.9 4677.7 4699.7 4713.6 5639.4 5890.3 5897.6 5953.3 5997.7 5692.6 5743.2 5743.5 5744.2 5793.5 4969.0 5264.4 5304.0 5402.9 5834.5 4612.5 4790.9 5194.8 5621.0 5650.8 5114.6 5235.3 5667.7 5768.6 5794.1 4999.8 5425.9 5613.5 5661.0 5709.7 04:47:01 INFO - PROCESS | 1636 | 04:47:01 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:19917.71 Median:16415.59 stddev:27838.89 (169.6%) stddev-sans-first:27879.22 04:47:01 INFO - PROCESS | 1636 | Values: 606.9 676.4 770.5 802.2 905.2 15486.8 16089.7 16336.9 16690.7 17272.6 13566.7 15655.0 15824.7 15902.5 16125.8 1021.8 1054.3 1059.2 1059.9 1060.1 991.9 1037.5 1045.4 1047.2 1049.5 1077.5 1081.3 1082.5 1085.0 1085.1 1078.6 1078.7 1079.3 1081.8 1081.8 795.2 802.8 802.9 805.3 808.4 16380.3 16450.8 16981.8 18212.0 18484.7 14651.9 14718.6 14845.9 14941.5 15123.1 11798.4 12180.7 12181.0 12266.5 12272.1 3784.0 3792.2 3799.4 3840.2 3965.4 67231.0 84016.3 101018.5 107653.0 121451.4 10867.9 11254.3 11334.3 11397.1 11424.8 22213.1 22342.9 22349.8 22387.4 22494.9 7685.0 7826.5 7906.0 7944.9 7976.8 4482.2 4498.5 4532.5 4570.5 4661.0 2559.9 2933.5 3520.0 3530.5 3564.1 637.2 637.9 643.9 650.7 652.1 593.2 653.5 659.6 682.9 689.1 8804.7 9162.2 9443.1 9769.9 10325.3 696.5 701.7 709.1 709.4 718.7 4592.4 4857.4 4868.0 4882.2 4905.4 103208.5 104912.5 105090.0 106728.0 109372.0 50171.5 65921.7 82179.6 90273.0 93620.5 58921.1 67203.7 71189.6 72521.0 78706.8 10867.4 11116.1 11390.2 11445.9 11458.8 40112.0 48469.3 50529.5 51124.0 52481.2 64991.0 66886.0 66996.3 67269.3 67296.0 13040.1 13109.9 13143.9 13145.9 13332.3 14936.0 14963.0 15073.3 15337.4 15961.1 13358.3 13529.8 13739.9 13742.1 14218.3 13429.1 13787.7 14157.7 14205.2 14477.0 13079.9 13287.7 13508.1 14027.2 14122.9 9942.4 10080.2 10114.5 10148.2 10354.4 10104.9 11176.1 11246.0 11431.6 11513.2 04:47:01 INFO - PROCESS | 1636 | -------- Summary: end -------- 04:47:01 INFO - PROCESS | 1636 | 04:47:02 INFO - TEST-INFO | 1636: exit 0 04:47:02 INFO - TEST-OK | dromaeo_css | took 1115252ms 04:47:02 INFO - TEST-START | kraken 04:47:02 INFO - operating with platform_type : mac_ 04:47:02 INFO - Initialising browser for kraken test... 04:47:02 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmp7SXXJt/profile http://localhost:49224/getInfo.html) 04:47:04 INFO - PROCESS | 1648 | 2017-05-16 04:47:04.832 plugin-container[1650:10179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8537, name = 'com.apple.tsm.portname' 04:47:04 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:47:04 INFO - PROCESS | 1648 | 2017-05-16 04:47:04.833 plugin-container[1650:10179] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870b, name = 'com.apple.CFPasteboardClient' 04:47:04 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:47:04 INFO - PROCESS | 1648 | 2017-05-16 04:47:04.833 plugin-container[1650:10179] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:47:04 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 04:47:04 INFO - PROCESS | 1648 | colorDepth:24 04:47:04 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 04:47:04 INFO - PROCESS | 1648 | __metrics 04:47:04 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 04:47:05 INFO - TEST-INFO | 1648: exit 0 04:47:05 INFO - Browser initialized. 04:47:05 INFO - Running cycle 1/1 for kraken test... 04:47:05 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmp7SXXJt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:47:07 INFO - PROCESS | 1651 | 2017-05-16 04:47:07.906 plugin-container[1652:10310] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 04:47:07 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:47:07 INFO - PROCESS | 1651 | 2017-05-16 04:47:07.906 plugin-container[1652:10310] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x881b, name = 'com.apple.CFPasteboardClient' 04:47:07 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:47:07 INFO - PROCESS | 1651 | 2017-05-16 04:47:07.906 plugin-container[1652:10310] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:47:17 INFO - PROCESS | 1651 | RSS: Main: 174243840 04:47:17 INFO - PROCESS | 1651 | 04:47:20 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 04:47:21 INFO - PROCESS | 1651 | RSS: Main: 187666432 04:47:21 INFO - PROCESS | 1651 | 04:47:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 04:47:24 INFO - PROCESS | 1651 | RSS: Main: 195829760 04:47:24 INFO - PROCESS | 1651 | 04:47:28 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 04:47:28 INFO - PROCESS | 1651 | RSS: Main: 199409664 04:47:28 INFO - PROCESS | 1651 | 04:47:30 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 04:47:31 INFO - PROCESS | 1651 | RSS: Main: 200761344 04:47:31 INFO - PROCESS | 1651 | 04:47:33 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:47:33 INFO - PROCESS | 1651 | RSS: Main: 200843264 04:47:33 INFO - PROCESS | 1651 | 04:47:37 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 04:47:37 INFO - PROCESS | 1651 | RSS: Main: 204460032 04:47:37 INFO - PROCESS | 1651 | 04:47:41 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 04:47:41 INFO - PROCESS | 1651 | RSS: Main: 203632640 04:47:41 INFO - PROCESS | 1651 | 04:47:44 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 04:47:45 INFO - PROCESS | 1651 | RSS: Main: 204107776 04:47:45 INFO - PROCESS | 1651 | 04:47:47 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:47:47 INFO - PROCESS | 1651 | RSS: Main: 204312576 04:47:47 INFO - PROCESS | 1651 | 04:47:49 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:47:50 INFO - PROCESS | 1651 | RSS: Main: 203780096 04:47:50 INFO - PROCESS | 1651 | 04:47:52 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:47:53 INFO - PROCESS | 1651 | RSS: Main: 203927552 04:47:53 INFO - PROCESS | 1651 | 04:47:55 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:47:56 INFO - PROCESS | 1651 | RSS: Main: 204312576 04:47:56 INFO - PROCESS | 1651 | 04:47:59 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:48:00 INFO - PROCESS | 1651 | RSS: Main: 204009472 04:48:00 INFO - PROCESS | 1651 | 04:48:01 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 04:48:02 INFO - PROCESS | 1651 | RSS: Main: 206020608 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | __start_tp_report 04:48:02 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 04:48:02 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 04:48:02 INFO - PROCESS | 1651 | |i|pagename|runs| 04:48:02 INFO - PROCESS | 1651 | |0;ai-astar;109;121;121;121;122;121;120;122;121;120 04:48:02 INFO - PROCESS | 1651 | |1;audio-beat-detection;152;145;139;152;108;109;114;108;129;111 04:48:02 INFO - PROCESS | 1651 | |2;audio-dft;199;199;199;202;199;189;285;253;200;201 04:48:02 INFO - PROCESS | 1651 | |3;audio-fft;82;60;59;84;60;62;70;60;65;76 04:48:02 INFO - PROCESS | 1651 | |4;audio-oscillator;84;72;71;74;93;99;113;102;76;83 04:48:02 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;118;89;97;137;93;130;137;91;82;136 04:48:02 INFO - PROCESS | 1651 | |6;imaging-darkroom;96;96;117;153;134;149;127;111;140;127 04:48:02 INFO - PROCESS | 1651 | |7;imaging-desaturate;92;93;89;85;90;90;77;141;143;92 04:48:02 INFO - PROCESS | 1651 | |8;json-parse-financial;66;64;64;67;69;68;67;68;70;68 04:48:02 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;56;63;65;60;57;57;57;57;59;58 04:48:02 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;89;105;104;100;74;81;82;83;86;83 04:48:02 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;138;138;134;137;137;134;131;164;201;167 04:48:02 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;196;164;164;147;186;165;161;164;167;163 04:48:02 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;68;66;86;83;86;84;68;65;63;71 04:48:02 INFO - PROCESS | 1651 | __end_tp_report 04:48:02 INFO - PROCESS | 1651 | __start_cc_report 04:48:02 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,982 04:48:02 INFO - PROCESS | 1651 | __end_cc_report 04:48:02 INFO - PROCESS | 1651 | __startTimestamp1494935282138__endTimestamp 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | ------- Summary: start ------- 04:48:02 INFO - PROCESS | 1651 | Number of tests: 14 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:119.80 Median:121.00 stddev:3.85 (3.2%) stddev-sans-first:0.71 04:48:02 INFO - PROCESS | 1651 | Values: 109.0 121.0 121.0 121.0 122.0 121.0 120.0 122.0 121.0 120.0 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:126.70 Median:134.00 stddev:18.83 (14.1%) stddev-sans-first:17.61 04:48:02 INFO - PROCESS | 1651 | Values: 152.0 145.0 139.0 152.0 108.0 109.0 114.0 108.0 129.0 111.0 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:212.60 Median:200.50 stddev:30.87 (15.4%) stddev-sans-first:32.35 04:48:02 INFO - PROCESS | 1651 | Values: 199.0 199.0 199.0 202.0 199.0 189.0 285.0 253.0 200.0 201.0 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:67.80 Median:67.50 stddev:9.65 (14.3%) stddev-sans-first:8.76 04:48:02 INFO - PROCESS | 1651 | Values: 82.0 60.0 59.0 84.0 60.0 62.0 70.0 60.0 65.0 76.0 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:79.50 stddev:14.44 (18.2%) stddev-sans-first:15.28 04:48:02 INFO - PROCESS | 1651 | Values: 84.0 72.0 71.0 74.0 93.0 99.0 113.0 102.0 76.0 83.0 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:111.00 Median:85.50 stddev:22.69 (26.5%) stddev-sans-first:23.92 04:48:02 INFO - PROCESS | 1651 | Values: 118.0 89.0 97.0 137.0 93.0 130.0 137.0 91.0 82.0 136.0 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:125.00 Median:144.50 stddev:20.04 (13.9%) stddev-sans-first:18.31 04:48:02 INFO - PROCESS | 1651 | Values: 96.0 96.0 117.0 153.0 134.0 149.0 127.0 111.0 140.0 127.0 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:99.20 Median:90.00 stddev:23.04 (25.6%) stddev-sans-first:24.28 04:48:02 INFO - PROCESS | 1651 | Values: 92.0 93.0 89.0 85.0 90.0 90.0 77.0 141.0 143.0 92.0 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:67.10 Median:68.00 stddev:1.97 (2.9%) stddev-sans-first:2.05 04:48:02 INFO - PROCESS | 1651 | Values: 66.0 64.0 64.0 67.0 69.0 68.0 67.0 68.0 70.0 68.0 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:58.90 Median:58.50 stddev:2.96 (5.1%) stddev-sans-first:2.95 04:48:02 INFO - PROCESS | 1651 | Values: 56.0 63.0 65.0 60.0 57.0 57.0 57.0 57.0 59.0 58.0 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:88.70 Median:82.50 stddev:10.65 (12.9%) stddev-sans-first:11.29 04:48:02 INFO - PROCESS | 1651 | Values: 89.0 105.0 104.0 100.0 74.0 81.0 82.0 83.0 86.0 83.0 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:138.00 stddev:22.48 (16.3%) stddev-sans-first:23.55 04:48:02 INFO - PROCESS | 1651 | Values: 138.0 138.0 134.0 137.0 137.0 134.0 131.0 164.0 201.0 167.0 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.70 Median:164.50 stddev:13.66 (8.3%) stddev-sans-first:9.94 04:48:02 INFO - PROCESS | 1651 | Values: 196.0 164.0 164.0 147.0 186.0 165.0 161.0 164.0 167.0 163.0 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.00 Median:77.00 stddev:9.52 (12.4%) stddev-sans-first:9.85 04:48:02 INFO - PROCESS | 1651 | Values: 68.0 66.0 86.0 83.0 86.0 84.0 68.0 65.0 63.0 71.0 04:48:02 INFO - PROCESS | 1651 | -------- Summary: end -------- 04:48:02 INFO - PROCESS | 1651 | 04:48:02 INFO - TEST-INFO | 1651: exit 0 04:48:02 INFO - TEST-OK | kraken | took 60606ms 04:48:02 INFO - SUITE-END | took 1175s 04:48:03 INFO - Completed test suite (00:19:36) 04:48:03 INFO - javascript benchmark 04:48:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7913.233220591814, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3589.030961640357, 3594.730395220359, 3619.7647152935056, 3631.5460567429072, 3633.963660363396, 5980.222571065756, 6229.719662615182, 6229.781957631484, 6233.657148856817, 6260.5930614510025, 5046.772895219715, 6192.256929168495, 6222.160008398865, 6240.500759939206, 6276.497880169587, 5575.470330318613, 5612.887742245152, 5632.042552766019, 5666.5183459406, 5734.79928202513, 5704.600677952545, 5708.571857110713, 5710.257666503369, 5716.514096301798, 5719.599628026019, 5493.642913210629, 5499.202615620733, 5610.186522954169, 5698.373178950312, 5737.913931291034, 5036.748162591855, 5400.351957765054, 5594.468525490072, 5613.719314034322, 5620.690862002588, 5485.87318138936, 5639.774409023634, 5644.209810626516, 5672.858178545569, 5700.287464066992, 5114.0, 6043.3956604339655, 6086.300075491286, 6178.38216178383, 6275.497960163176, 6470.2559205691, 6488.416042556192, 6508.54440189187, 6549.541532092755, 6650.634215118166, 5649.971750141264, 6752.729890804362, 6778.69495872682, 6784.219814721271, 6798.898016529706, 5201.9479805201845, 6208.565400422018, 6248.0, 6258.123862659231, 6293.779717709904, 6156.52243461566, 6174.845628859314, 6559.868802623921, 6859.5541289816, 6898.586084834926, 6169.753209871645, 6375.362463753588, 6447.806565803034, 6483.157189565326, 6489.1888513935755, 6685.665716714145, 6713.295104014106, 6736.8989465158065, 6740.662966851638, 6751.493637977172, 6646.468282537386, 6650.733970641217, 6654.633995130314, 6654.966725166343, 6659.467242620675, 5933.910991335133, 6105.626234097382, 6351.936480635227, 6359.96820015897, 6604.8348791280605, 5364.249005139283, 5368.409474957752, 5386.649867758583, 5387.973060134675, 5409.7295135243085, 4207.389928460356, 4690.179218636725, 4729.6452766042685, 4743.928841067387, 4749.335093086971, 6145.262568491809, 6156.722947467375, 6297.590656607306, 6315.147455093595, 6317.6209427434505, 5408.432114627986, 5506.531944784658, 6602.438792702578, 6630.569013014138, 6632.50256230775, 6282.096026324937, 6288.151099601587, 6293.118963345135, 6294.181756371642, 6301.6534090625455, 5690.089585666274, 5933.495652869468, 5980.342162362137, 5994.1608174855555, 5997.460228579449, 6678.964760462039, 6679.231888332807, 6725.663716814238, 6766.7969960901255, 6873.931260687529, 6727.663616819042, 6740.460763139035, 6744.966275168789, 6769.864602707819, 6780.38976492118, 6751.864962700817, 6845.760398386033, 6851.417629501549, 6854.520183587101, 6887.690053947485, 6351.9201735705965, 6408.58344207625, 6459.903101453576, 6486.772962946275, 6637.103990961158, 6706.8993965089585, 6712.127423434922, 6712.899306510309, 6725.125733654593, 6749.932500675127, 6152.938470615416, 6269.435750782452, 6542.018697195459, 6547.574407663486, 6587.308332625005, 6453.322401148, 6463.515236357198, 6467.482601391784, 6498.3826536478955, 6501.772437964649, 5415.83752487426, 6238.719257633326, 6300.905486417799, 6408.1349017881985, 6409.198850143732, 6528.118703974903, 6546.0508226305965, 6555.967220163869, 6565.737370505126, 6572.868542629216, 5630.634008789416, 5801.100829371454, 6319.715612797343, 6382.680865956777, 6431.0], "value": 6059.8980709192665, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.1075600121846, 740.5556666000399, 852.5225873510834, 995.6067353395409, 995.8954309797466, 38255.0, 39430.40854387183, 40327.0, 41816.37275440863, 41886.37170442442, 29036.564451533242, 39834.8049558513, 39916.60083399165, 40039.0, 40162.19675606486, 8340.83318333633, 8483.66065357385, 11282.830757538644, 11558.826617600742, 11792.292462452237, 11432.028277596415, 11534.365609891453, 11543.826842597367, 11555.653330400102, 11561.132915031365, 10676.466176691134, 10728.141748660088, 11531.711707207303, 11544.826827597593, 11591.710207244801, 8508.829823403497, 11451.484683189277, 11467.541298348056, 11542.07663386927, 11779.293242405398, 11202.215844890861, 11253.887461125447, 11268.32390056591, 11274.0, 11305.26515776472, 27658.0, 35151.4727279091, 35178.82410587956, 35410.291794163975, 35564.75523356696, 3900.960990390088, 3939.665128464084, 3940.2316548273097, 3941.369380899071, 3961.207758448322, 21748.608373099996, 26014.6097808533, 26784.464310713676, 27291.45417091667, 27299.590506142424, 3197.0, 3223.2103134731906, 3247.269364392993, 3269.182704323919, 3271.9672803272138, 320940.395298022, 326497.0, 383375.9999999972, 391316.04341978393, 393027.0, 44966.32550511745, 45508.0, 45623.54376456259, 45659.0, 45715.77142114268, 46805.531944680115, 46841.29738053932, 46846.29730554044, 46933.0, 47034.76482617565, 14417.85582144186, 14433.639159020899, 14507.984441089022, 14527.0, 14549.0, 12394.81407778884, 12619.495220191273, 12652.304123273125, 12667.176633518791, 12672.303023333621, 12218.450169742382, 12230.960368368611, 12281.69295767613, 12292.446839892227, 12378.81431778524, 5856.531477481792, 5861.736221870026, 5902.8819423611285, 5929.288485381696, 5931.881362372815, 1153.4405813180601, 1174.3364998775664, 1177.7997740384155, 1185.82805493203, 1224.2925949723299, 23096.07615695387, 27259.182224533295, 27442.862785685942, 27645.373689499465, 27701.0, 1292.4184117147322, 1303.5958852755675, 1324.6423465664216, 1326.1976504215004, 1334.8064530643003, 13840.792388114187, 14233.43066277358, 14295.07082039664, 14458.710825783635, 14484.34820433062, 398354.0, 401947.0, 402130.9786902152, 404047.0, 406576.0, 55851.0, 56165.0, 56238.71880640653, 56405.717971410704, 56523.86952260932, 1485.5469081929898, 1525.3364786317986, 1525.6643538421752, 1534.9309281082155, 1537.423466200175, 24927.875360623082, 26532.602010970237, 26583.0, 26592.601110982963, 26682.332941676614, 46878.29682554833, 47575.52424475711, 48684.0, 50573.98852022865, 50998.490015100855, 518025.0, 518245.0, 518383.0, 518730.0, 521936.0, 27657.7554010066, 28271.01051463189, 33738.83130584331, 33904.98285051453, 34065.14837129092, 39807.0, 39825.0, 40133.0, 40190.598094018686, 40885.38671919983, 27961.021364252152, 28718.856405717837, 29387.41225175527, 29436.969706060187, 29888.0, 29224.41551168922, 29653.087663739676, 29794.70205297919, 31003.534946976266, 31299.68700312968, 29189.562156568092, 34799.826000869834, 35189.64810351864, 35272.82363588166, 35689.82155089312, 22885.970131343798, 26614.46771064529, 26731.19806405811, 27450.725492744816, 27925.0, 19014.809851901857, 21092.367228983156, 21092.47268818292, 24031.279061628178, 24428.511429770948], "value": 18792.589980445468, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.5740429952849, 418.2555052007428, 419.4274814877693, 435.1275692237064, 457.93301607255097, 18272.994985275804, 21280.14879404824, 22209.888950555265, 22236.555268894634, 23101.768982310172, 23267.0, 23283.534329313403, 23890.76109238907, 24005.039798408085, 24327.87836060817, 5447.727613619303, 6977.33715297046, 7244.8188795280275, 7259.673314700824, 7267.055282813227, 7131.0729605151255, 7176.892346614805, 7246.420286377077, 7257.818554536152, 7270.163931147933, 6897.0, 7130.8573828523395, 7220.819479513002, 7230.276972302754, 7233.746818861341, 6884.001819736135, 7061.929380706179, 7115.430765538745, 7132.215456299803, 7196.568205907636, 6946.6179360135175, 6954.65226738661, 6964.199117101548, 6965.059716938199, 6978.720851165947, 28151.718482815115, 28296.575551366746, 28329.57505637417, 28425.43149137006, 28585.285367865763, 2990.7607391408637, 3090.165655273086, 3105.270261488571, 3151.6848315168527, 3269.2807582331848, 16422.0, 17184.05487698176, 17199.742003869953, 17238.0, 17644.911775441167, 2069.1102825784906, 2123.5540536487356, 2124.426404870692, 2126.2983215538834, 2137.8717276963275, 135929.0, 181961.27058094006, 188077.0, 188670.0, 190317.04841475704, 16339.101349425659, 22041.0, 22378.440538986655, 22522.986465609087, 22582.77417225807, 33583.16042098967, 34048.48927266093, 34231.4865277021, 34273.82863085669, 34332.97001089971, 9703.320767546345, 10982.35397572932, 11013.953674400918, 11374.545018199346, 11675.649730508097, 9559.474228917337, 9583.377080489747, 9606.471644059504, 9630.759231019141, 9638.95180524107, 9884.55519501624, 9983.450910199863, 9998.250131240186, 10006.949965250127, 10108.898911010943, 3941.0491806555365, 4177.164239180677, 4192.559781222989, 4203.5376108628025, 4211.284081706117, 921.3412410126791, 1062.7555662197738, 1062.825925006208, 1102.9448527573588, 1131.575659127827, 23281.650775238384, 23412.181875308408, 23597.882010589838, 23616.0, 23650.881745591505, 1102.9613963511238, 1104.6078642082107, 1127.9830802537967, 1130.8077626803463, 1144.473542170594, 11820.881791181979, 11959.820602690967, 12036.0, 12071.758564828655, 12093.153479256544, 716625.0, 744742.0, 766776.0, 803108.0, 811887.0, 126355.36822316014, 149342.0, 171452.0, 173123.0, 173799.0, 1279.9040071994639, 1317.2952470428274, 1367.3231750283474, 1370.6641872741243, 1403.8596140385675, 19542.60914781725, 20118.59762804765, 21578.0, 21772.56454870862, 21902.0, 65129.67435162794, 65700.67149664222, 66478.33521664915, 68176.0, 68423.65788171027, 83933.58033209795, 87354.56322718346, 93450.06549934414, 96018.51990740001, 96566.0343396557, 9426.387284826464, 11835.171537992257, 12170.087243456599, 12238.510459581874, 12303.384830758247, 13448.327583620976, 13566.525171618947, 13848.0, 13928.930355348564, 14024.22866742339, 9878.802423951336, 10050.69847904564, 11331.943340283246, 11866.991305738764, 12117.0, 8647.005594356478, 8805.779855503664, 9301.813963720822, 9960.854501732394, 10841.970012848768, 10171.38971661673, 10247.231457640557, 10253.328034969143, 10320.793584128425, 11987.34069626144, 10066.194704423775, 11520.827187592025, 12302.384880756106, 12544.247345158954, 12569.811452828031, 9559.18746906493, 11752.58865939688, 11849.763004740029, 11980.682124966072, 11991.22057066288], "value": 12161.873055737113, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1537.6924615076987, 1628.9167703477187, 1633.616100216448, 1654.0902503623004, 1662.9750553741692, 11180.273282236649, 11536.480858361372, 11896.286222826626, 11919.880801191985, 11931.164818462701, 11604.535818567247, 11647.242929209595, 11776.293422394663, 11796.292222466636, 11854.75525069866, 2432.075811191749, 2482.391814005562, 2485.5028994201143, 2494.052260141144, 2495.6007038873786, 2401.9159329423474, 2435.464197876473, 2463.556559819232, 2473.332200305916, 2479.4049428137296, 2400.3399065257017, 2423.2730180945646, 2423.6001059825107, 2472.7279999200073, 2477.355887469262, 2364.1252736487436, 2405.1702162753822, 2413.0227257960555, 2419.322589674894, 2422.903083876643, 2396.2451827674226, 2414.806815454777, 2423.890924908366, 2438.402591365109, 2441.56051910656, 11569.016633586156, 11826.704332391759, 11835.171537992344, 11837.0, 11851.229670071418, 5765.596408251424, 5966.224390829165, 5968.462838344526, 5993.310769261547, 5993.850153746148, 3569.1434055826676, 3643.9271214575824, 3670.247599242149, 3685.1155722626377, 3696.7597106188014, 1259.741752940645, 1263.949442022318, 1315.4672357695054, 1327.4026687990442, 1336.53889408155, 11679.708007299885, 13576.999999999902, 14330.99683022189, 14636.0, 16107.91946040274, 4608.28571571407, 4619.0, 4777.689450185727, 4782.569568738831, 4791.808327666854, 12260.693482663004, 12336.50653973849, 12337.69155771095, 12361.6291511253, 12364.1963272387, 5923.585349025527, 5927.140564618193, 5965.135055417027, 5967.045272756425, 5969.761209551656, 5238.895222095537, 5275.0, 5321.494458026481, 5363.839084827461, 5388.2725832012175, 3284.474484082535, 3838.5393752749846, 3933.92132157361, 3972.324704800191, 4009.919801603952, 844.3329769482164, 1001.5943542865151, 1017.31839667423, 1037.896210378971, 1039.6828967164936, 843.7890527368158, 846.1284876577013, 867.4299517872671, 886.2998231397253, 890.8841850559386, 6882.414994725505, 6916.0, 6950.721971121098, 7027.543209691354, 7156.355927966508, 894.8791913091779, 986.3490096536252, 1013.8506366503061, 1034.5499707627207, 1046.947652617366, 4151.4395556599475, 4386.1008493258805, 4392.956070439254, 4403.559644035635, 4514.774261286922, 13734.381952811947, 14363.56909292723, 14373.6406589834, 14391.856081439051, 14400.135991840523, 13511.189328640314, 14044.016918815585, 14295.92852035733, 14344.641383965485, 14496.275186240646, 9017.954910225539, 10446.68659940203, 10669.466526673636, 10739.248252622398, 10796.460176991119, 5732.598718089694, 6125.877482450237, 6693.230278518033, 6822.863542729018, 6866.279040700854, 12299.75400492003, 12307.753844922872, 12317.322547259986, 12374.690632734253, 12395.946344560813, 12984.740305194033, 12996.895263902295, 13101.213927164401, 13144.60566183016, 13158.539451119164, 9336.299777516577, 9625.278104142357, 9640.987696291788, 9653.420794752337, 9674.951625241829, 10164.593416263267, 10465.110465610203, 10491.737706557396, 10712.14302855754, 10782.946085269523, 9100.499472529074, 9196.402233855046, 9242.953785231031, 9247.86128208064, 9388.906110938804, 7108.395786358011, 8904.0, 9006.369554131148, 9064.410813297114, 9138.497382644018, 8193.959030204811, 8222.958885205535, 8228.794280143044, 8254.0, 8277.379196560161, 5979.222701048836, 5985.162077309094, 6002.579819412774, 6120.265568131853, 6206.0, 6014.578979471395, 6065.362352164802, 6273.749050037947, 6293.40212679813, 6342.175517182736], "value": 5257.613117169268, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.13839898221363, 400.37941191153703, 401.02150752164727, 402.5210000099881, 406.723428068913, 4674.761188285107, 4845.110406941287, 5080.466551012141, 5506.146547285172, 5545.77816887325, 4432.4237849079655, 5335.386430560487, 5352.5182733553975, 5413.350397952251, 5473.618975745553, 4065.939010914824, 4172.812223449944, 4210.726302790309, 4211.136716973028, 4226.45056142701, 3779.4897688811934, 3832.5209348831395, 3894.6494815466604, 4018.276710192164, 4249.596288352602, 4007.0, 4049.473568436099, 4125.236831186226, 4206.47419072616, 4208.789560521977, 3714.832832522543, 4073.0, 4105.712600117993, 4193.483909654214, 4194.580541945811, 3689.2252626948375, 3693.575238847513, 3713.4615480755137, 3724.4599533067553, 3756.887293381203, 4512.646206138178, 4793.472718001017, 5006.924896126561, 5274.835098940644, 5286.735663216824, 3996.800159991989, 4065.6137666921595, 4699.67102302839, 4818.783154758009, 4879.0, 5362.865928351784, 5721.0846264597885, 5737.3688894221605, 5755.712214389264, 5767.942320576783, 4125.64931980782, 4137.731047481904, 4147.357159640261, 4168.687348448879, 4188.895277618053, 5128.794848206067, 5236.869078273074, 5560.220729366604, 5706.343770466367, 5926.525877929756, 4264.57354264571, 4592.471865735483, 4794.472608013116, 5023.066119544002, 5073.619478539124, 4218.430511880857, 5152.433232344439, 5162.805807531784, 5181.948180518222, 5208.354152093474, 4960.702357858468, 5056.241563765465, 5362.758675859595, 5378.714046530209, 5379.435159308295, 5704.229928959538, 5705.429457054344, 5714.999001398076, 5721.914171287434, 5728.914066289009, 1676.0362791394753, 1745.677049745801, 1753.587906841891, 1758.8303777987494, 1785.973210401845, 1077.8144041554228, 1105.739457018999, 1145.7364806094645, 1206.8474606750533, 1367.974019485386, 5049.84850454487, 5144.8713782155, 5332.786893439257, 5352.4915133062295, 5361.544268737201, 5110.821121260738, 5907.616004959664, 5937.376575459601, 5959.493443057302, 5984.611000284967, 4517.841875534341, 4747.620190384761, 5183.6932565008965, 5323.334583177103, 5338.786448542092, 2854.0735003872305, 2929.604503392015, 3058.785884988074, 3077.8768849246226, 3094.783365164417, 5210.583153347723, 5782.335031471351, 5822.650640961471, 5902.3507414184405, 6015.368386319461, 5204.71374074422, 5276.7310056676, 5687.232223649837, 5880.411958804086, 6056.545759068087, 5293.920591191135, 5522.641028333226, 5854.8875713615125, 6083.513318934474, 6106.725197366218, 5208.270842082187, 5517.393086760373, 5631.2960879890015, 5900.144479050427, 5929.16991621182, 5138.614603904647, 5611.691356975405, 5837.416258374129, 5850.356460789225, 5852.068817290199, 5184.326037615086, 5329.760160792851, 5397.298351214474, 6064.090386442069, 6087.4521293083835, 4359.847405340798, 4648.8842677757775, 4677.742724150068, 4699.660596729916, 4713.575778179843, 5639.436056394327, 5890.322612899571, 5897.587168898136, 5953.291252480025, 5997.670128142994, 5692.6299790513485, 5743.167240750151, 5743.483086522233, 5744.167095771173, 5793.478586927196, 4968.975155124202, 5264.420913699567, 5303.984485895335, 5402.919416116871, 5834.5203288603025, 4612.469565999953, 4790.850195953016, 5194.792208311776, 5620.971895140498, 5650.802221922378, 5114.644619175847, 5235.267062611161, 5667.716614169358, 5768.586696259444, 5794.072948328248, 4999.77501012448, 5425.918611220914, 5613.46672066153, 5661.0, 5709.65742055478], "value": 4344.980810007547, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.9453749162575, 676.3540818518312, 770.4645271536281, 802.1617409806752, 905.1536813079769, 15486.845131548682, 16089.678206435865, 16336.8564200506, 16690.749638755413, 17272.56818579536, 13566.728665426686, 15654.982426142293, 15824.683506329866, 15902.522924312289, 16125.83874161258, 1021.7956408718248, 1054.29889123733, 1059.1685526861415, 1059.8569193158903, 1060.125396547851, 991.900809919006, 1037.4968140451874, 1045.3518818332645, 1047.235518071808, 1049.5172220778413, 1077.5097330714507, 1081.32957566309, 1082.4912291223152, 1084.996378350027, 1085.093946554624, 1078.6440474643348, 1078.724925144091, 1079.3308148947663, 1081.7620123572801, 1081.789051135029, 795.1650766694947, 802.8233788566506, 802.8594995875699, 805.2832978649008, 808.3573559020592, 16380.344786208538, 16450.83549164505, 16981.830181698147, 18212.0, 18484.72272915894, 14651.853481465156, 14718.55844324672, 14845.851541484555, 14941.477048303366, 15123.092614443058, 11798.351090690008, 12180.695482612917, 12181.0, 12266.509339626404, 12272.140950133495, 3783.9621603783885, 3792.241551689673, 3799.411091280856, 3840.1935593525395, 3965.40518922164, 67231.0, 84016.31967360618, 101018.49490752499, 107653.0, 121451.3927430366, 10867.945660271647, 11254.268472549258, 11334.263272887236, 11397.088232941345, 11424.828627570592, 22213.111475540798, 22342.888285558794, 22349.77650223477, 22387.440313991956, 22494.887525562266, 7685.03937007874, 7826.530408175414, 7905.960470197612, 7944.880826787603, 7976.760697179094, 4482.238019536687, 4498.527654596286, 4532.524084971096, 4570.4743954445, 4660.951285960699, 2559.8976040958155, 2933.5013047781927, 3520.0143959691327, 3530.470429435605, 3564.1446052947626, 637.1939496536885, 637.8979363301942, 643.9098526206335, 650.7039297119799, 652.0935899100255, 593.2110293309887, 653.4968074582545, 659.643792352134, 682.8975653651992, 689.131694065471, 8804.691835785718, 9162.221211197122, 9443.057030202408, 9769.853452198222, 10325.32885362449, 696.4776417686735, 701.7333413303014, 709.095902724031, 709.3509438863382, 718.6899026546927, 4592.448906131285, 4857.417109946829, 4868.0, 4882.170031094723, 4905.3623029006, 103208.45187322196, 104912.47543762233, 105090.0, 106728.0, 109372.0, 50171.4948551544, 65921.67039164774, 82179.58910205531, 90273.0, 93620.53189734144, 58921.11618325728, 67203.66398168076, 71189.64405177941, 72520.99999999894, 78706.81939770909, 10867.402292873969, 11116.110711142928, 11390.202685811913, 11445.885541144815, 11458.77082458363, 40112.0, 48469.27296090491, 50529.49470505248, 51124.0, 52481.21278180754, 64991.0, 66886.0, 66996.33003670097, 67269.32730672826, 67295.99056014261, 13040.087193896336, 13109.868901310865, 13143.868561314264, 13145.93427032897, 13332.2667253302, 14936.0, 14963.027403218755, 15073.321700523284, 15337.386504539694, 15961.122138282506, 13358.332083395986, 13529.797053044409, 13739.862601373858, 13742.106763060368, 14218.324825709542, 13429.127106738031, 13787.699571286801, 14157.716845663235, 14205.218712970885, 14477.0, 13079.869201307865, 13287.66780830487, 13508.121972071784, 14027.228502432465, 14122.929385353007, 9942.353747006422, 10080.193584513368, 10114.494275286354, 10148.188144948239, 10354.378737275787, 10104.949475252577, 11176.095490505038, 11246.0, 11431.59989400367, 11513.194076414906], "value": 7759.883032592222, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1553.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 121.0, 121.0, 121.0, 122.0, 121.0, 120.0, 122.0, 121.0, 120.0], "value": 119.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 145.0, 139.0, 152.0, 108.0, 109.0, 114.0, 108.0, 129.0, 111.0], "value": 126.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 199.0, 199.0, 202.0, 199.0, 189.0, 285.0, 253.0, 200.0, 201.0], "value": 212.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 59.0, 84.0, 60.0, 62.0, 70.0, 60.0, 65.0, 76.0], "value": 67.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 71.0, 74.0, 93.0, 99.0, 113.0, 102.0, 76.0, 83.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 89.0, 97.0, 137.0, 93.0, 130.0, 137.0, 91.0, 82.0, 136.0], "value": 111.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 117.0, 153.0, 134.0, 149.0, 127.0, 111.0, 140.0, 127.0], "value": 125.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 89.0, 85.0, 90.0, 90.0, 77.0, 141.0, 143.0, 92.0], "value": 99.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 67.0, 69.0, 68.0, 67.0, 68.0, 70.0, 68.0], "value": 67.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 63.0, 65.0, 60.0, 57.0, 57.0, 57.0, 57.0, 59.0, 58.0], "value": 58.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 105.0, 104.0, 100.0, 74.0, 81.0, 82.0, 83.0, 86.0, 83.0], "value": 88.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 138.0, 134.0, 137.0, 137.0, 134.0, 131.0, 164.0, 201.0, 167.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 164.0, 164.0, 147.0, 186.0, 165.0, 161.0, 164.0, 167.0, 163.0], "value": 167.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 86.0, 83.0, 86.0, 84.0, 68.0, 65.0, 63.0, 71.0], "value": 74.0, "unit": "score"}]}]} 04:48:03 INFO - Return code: 0 04:48:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:48:03 ERROR - # TBPL SUCCESS # 04:48:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:48:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:48:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:48:03 INFO - 2017-05-16 04:48:03.454 screenresolution[1656:10695] starting screenresolution argv=screenresolution get 04:48:03 INFO - 2017-05-16 04:48:03.457 screenresolution[1656:10695] Display 0: 1600x1200x32@60 04:48:03 INFO - 2017-05-16 04:48:03.463 screenresolution[1657:10698] starting screenresolution argv=screenresolution list 04:48:03 INFO - Available Modes on Display 0 04:48:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:48:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:48:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:48:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:48:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:48:03 INFO - Intel Iris: 04:48:03 INFO - Chipset Model: Intel Iris 04:48:03 INFO - Type: GPU 04:48:03 INFO - Bus: Built-In 04:48:03 INFO - VRAM (Dynamic, Max): 1536 MB 04:48:03 INFO - Vendor: Intel (0x8086) 04:48:03 INFO - Device ID: 0x0a2e 04:48:03 INFO - Revision ID: 0x0009 04:48:03 INFO - Displays: 04:48:03 INFO - Display: 04:48:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:48:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:48:03 INFO - Display Serial Number: 1600x1200 60 04:48:03 INFO - Main Display: Yes 04:48:03 INFO - Mirror: Off 04:48:03 INFO - Online: Yes 04:48:03 INFO - Rotation: Supported 04:48:03 INFO - Return code: 0 04:48:03 INFO - Running post-action listener: _resource_record_post_action 04:48:03 INFO - [mozharness: 2017-05-16 11:48:03.700862Z] Finished run-tests step (success) 04:48:03 INFO - Running post-run listener: _resource_record_post_run 04:48:03 INFO - Total resource usage - Wall time: 1191s; CPU: 55.0%; Read bytes: 40810496; Write bytes: 426498048; Read time: 396; Write time: 1609 04:48:03 INFO - TinderboxPrint: CPU usage
54.6% 04:48:03 INFO - TinderboxPrint: I/O read bytes / time
40,810,496 / 396 04:48:03 INFO - TinderboxPrint: I/O write bytes / time
426,498,048 / 1,609 04:48:03 INFO - TinderboxPrint: CPU idle
2,163.6 (45.4%) 04:48:03 INFO - TinderboxPrint: CPU system
955.0 (20.0%) 04:48:03 INFO - TinderboxPrint: CPU user
1,647.2 (34.6%) 04:48:03 INFO - TinderboxPrint: Swap in / out
163,037,184 / 0 04:48:03 INFO - install - Wall time: 15s; CPU: 53.0%; Read bytes: 110723584; Write bytes: 103108608; Read time: 9936; Write time: 188 04:48:03 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 39888896; Write bytes: 303601664; Read time: 380; Write time: 1363 04:48:04 INFO - Running post-run listener: _upload_blobber_files 04:48:04 INFO - Blob upload gear active. 04:48:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:48:04 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 04:48:04 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'] 04:48:04 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 04:48:04 INFO - (blobuploader) - INFO - Open directory for files ... 04:48:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:48:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:48:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:06 INFO - (blobuploader) - INFO - Done attempting. 04:48:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:48:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:48:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:07 INFO - (blobuploader) - INFO - Done attempting. 04:48:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:48:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:48:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:08 INFO - (blobuploader) - INFO - Done attempting. 04:48:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:48:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:48:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:48:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:48:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:48:11 INFO - (blobuploader) - INFO - Done attempting. 04:48:11 INFO - (blobuploader) - INFO - Iteration through files over. 04:48:11 INFO - Return code: 0 04:48:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:48:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:48:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c6e394514d5882795e1ad995c320d3fdcc86faade437d61449dbb97d385497a456fa7cff8ef2b7757fc72ecd62e882051188886e5728be8321f010f9cc91d6a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b90b34fe47173ad302bc203e41d3e1babb2d2a999ab4e44d62fe3ed02da077dcf2c6042fcb9f595e827e5d84551c2af2eed9a85fe86a6ba7511aa359476c9ac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/742933fa7949aea86744ed3c5756d0c704451df56a032db3a2b033b229c66823530f0eea80882a72ccac9324ce1bba5535c6223fcf8e4f3b42727deab4375952", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c86cc9c15059e2257a692c20301f5fec93b6d731c73cd02554b7bbb23c47ce8af68e80a692430294179db4d52d29df87f51e11e17f728bb10be473e1488ae30a"} 04:48:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:48:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:48:11 INFO - Contents: 04:48:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0c6e394514d5882795e1ad995c320d3fdcc86faade437d61449dbb97d385497a456fa7cff8ef2b7757fc72ecd62e882051188886e5728be8321f010f9cc91d6a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b90b34fe47173ad302bc203e41d3e1babb2d2a999ab4e44d62fe3ed02da077dcf2c6042fcb9f595e827e5d84551c2af2eed9a85fe86a6ba7511aa359476c9ac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/742933fa7949aea86744ed3c5756d0c704451df56a032db3a2b033b229c66823530f0eea80882a72ccac9324ce1bba5535c6223fcf8e4f3b42727deab4375952", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c86cc9c15059e2257a692c20301f5fec93b6d731c73cd02554b7bbb23c47ce8af68e80a692430294179db4d52d29df87f51e11e17f728bb10be473e1488ae30a"} 04:48:11 INFO - Running post-run listener: copy_logs_to_upload_dir 04:48:11 INFO - Copying logs to upload dir... 04:48:11 INFO - mkdir: /builds/slave/test/build/upload/logs 04:48:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.805920 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-16 04:48:11.295920) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 04:48:11.299642) ========= 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.NHaMyKLOxT/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.wEy2k3gS83/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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/0c6e394514d5882795e1ad995c320d3fdcc86faade437d61449dbb97d385497a456fa7cff8ef2b7757fc72ecd62e882051188886e5728be8321f010f9cc91d6a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b90b34fe47173ad302bc203e41d3e1babb2d2a999ab4e44d62fe3ed02da077dcf2c6042fcb9f595e827e5d84551c2af2eed9a85fe86a6ba7511aa359476c9ac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/742933fa7949aea86744ed3c5756d0c704451df56a032db3a2b033b229c66823530f0eea80882a72ccac9324ce1bba5535c6223fcf8e4f3b42727deab4375952", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c86cc9c15059e2257a692c20301f5fec93b6d731c73cd02554b7bbb23c47ce8af68e80a692430294179db4d52d29df87f51e11e17f728bb10be473e1488ae30a"} build_url:https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011195 build_url: 'https://queue.taskcluster.net/v1/task/ZYSUeE3KR2uRZ0gpXs_JWg/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/0c6e394514d5882795e1ad995c320d3fdcc86faade437d61449dbb97d385497a456fa7cff8ef2b7757fc72ecd62e882051188886e5728be8321f010f9cc91d6a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7b90b34fe47173ad302bc203e41d3e1babb2d2a999ab4e44d62fe3ed02da077dcf2c6042fcb9f595e827e5d84551c2af2eed9a85fe86a6ba7511aa359476c9ac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/742933fa7949aea86744ed3c5756d0c704451df56a032db3a2b033b229c66823530f0eea80882a72ccac9324ce1bba5535c6223fcf8e4f3b42727deab4375952", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c86cc9c15059e2257a692c20301f5fec93b6d731c73cd02554b7bbb23c47ce8af68e80a692430294179db4d52d29df87f51e11e17f728bb10be473e1488ae30a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-16 04:48:11.332630) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 04:48:11.333013) ========= 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.NHaMyKLOxT/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.wEy2k3gS83/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.004989 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-16 04:48:11.381822) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 04:48:11.382132) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-16 04:48:11.382441) ========= ========= Total master_lag: 0.16 =========