builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0386 starttime: 1495729999.3 results: success (0) buildid: 20170525080444 builduid: 1a835487f771469794903d56594ae782 revision: 38c9c1ad194a58d08440919f0c4618993917315b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 09:33:19.303216) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 09:33:19.303766) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 09:33:19.304050) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 09:33:19.334976) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:33:19.335372) ========= 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.xEjuExtI1p/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.q0ZSv3K879/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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-25 09:33:19-- 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% 18.8M=0.001s 2017-05-25 09:33:19 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.136861 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:33:19.495894) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:33:19.496216) ========= 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.xEjuExtI1p/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.q0ZSv3K879/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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.039436 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:33:19.557375) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:33:19.557953) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 38c9c1ad194a58d08440919f0c4618993917315b --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 38c9c1ad194a58d08440919f0c4618993917315b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xEjuExtI1p/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.q0ZSv3K879/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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-25 09:33:19,660 truncating revision to first 12 chars 2017-05-25 09:33:19,660 Setting DEBUG logging. 2017-05-25 09:33:19,660 attempt 1/10 2017-05-25 09:33:19,660 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/38c9c1ad194a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 09:33:20,311 unpacking tar archive at: mozilla-beta-38c9c1ad194a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.931537 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:33:20.513727) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 09:33:20.514145) ========= script_repo_revision: 38c9c1ad194a58d08440919f0c4618993917315b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 09:33:20.514664) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 09:33:20.515079) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 09:33:20.535840) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-25 09:33:20.536250) ========= /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.xEjuExtI1p/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.q0ZSv3K879/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 09:33:20 INFO - MultiFileLogger online at 20170525 09:33:20 in /builds/slave/test 09:33:20 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 09:33:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:33:20 INFO - {'append_to_log': False, 09:33:20 INFO - 'base_work_dir': '/builds/slave/test', 09:33:20 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:33:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:33:20 INFO - 'branch': 'Mozilla-Beta', 09:33:20 INFO - 'buildbot_json_path': 'buildprops.json', 09:33:20 INFO - 'config_files': ('talos/mac_config.py',), 09:33:20 INFO - 'default_actions': ('clobber', 09:33:20 INFO - 'read-buildbot-config', 09:33:20 INFO - 'download-and-extract', 09:33:20 INFO - 'populate-webroot', 09:33:20 INFO - 'create-virtualenv', 09:33:20 INFO - 'install', 09:33:20 INFO - 'run-tests'), 09:33:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:33:20 INFO - 'download_minidump_stackwalk': True, 09:33:20 INFO - 'download_symbols': 'ondemand', 09:33:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:33:20 INFO - 'tooltool.py': '/tools/tooltool.py', 09:33:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:33:20 INFO - '/tools/misc-python/virtualenv.py')}, 09:33:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:33:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:33:20 INFO - 'gecko_profile': False, 09:33:20 INFO - 'gecko_profile_interval': 0, 09:33:20 INFO - 'installer_path': 'installer.exe', 09:33:20 INFO - 'log_level': 'info', 09:33:20 INFO - 'log_name': 'talos', 09:33:20 INFO - 'log_to_console': True, 09:33:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:33:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:33:20 INFO - 'opt_config_files': (), 09:33:20 INFO - 'pip_index': False, 09:33:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:33:20 INFO - 'cmd': ('bash', 09:33:20 INFO - '-c', 09:33:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:33:20 INFO - 'enabled': True, 09:33:20 INFO - 'halt_on_failure': False, 09:33:20 INFO - 'name': 'check_screen_resolution'},), 09:33:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:33:20 INFO - 'cmd': ('bash', 09:33:20 INFO - '-c', 09:33:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:33:20 INFO - 'enabled': True, 09:33:20 INFO - 'halt_on_failure': False, 09:33:20 INFO - 'name': 'check_screen_resolution'},), 09:33:20 INFO - 'run_cmd_checks_enabled': True, 09:33:20 INFO - 'suite': 'dromaeojs-e10s', 09:33:20 INFO - 'system_bits': '32', 09:33:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:33:20 INFO - 'title': 't-yosemite-r7-0386', 09:33:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:33:20 INFO - 'use_talos_json': True, 09:33:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:33:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:33:20 INFO - 'work_dir': 'build'} 09:33:20 INFO - [mozharness: 2017-05-25 16:33:20.747135Z] Running clobber step. 09:33:20 INFO - Running pre-action listener: _resource_record_pre_action 09:33:20 INFO - Running main action method: clobber 09:33:20 INFO - rmtree: /builds/slave/test/build 09:33:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:33:23 INFO - Running post-action listener: _resource_record_post_action 09:33:23 INFO - [mozharness: 2017-05-25 16:33:23.272956Z] Finished clobber step (success) 09:33:23 INFO - [mozharness: 2017-05-25 16:33:23.273123Z] Running read-buildbot-config step. 09:33:23 INFO - Running pre-action listener: _resource_record_pre_action 09:33:23 INFO - Running main action method: read_buildbot_config 09:33:23 INFO - Using buildbot properties: 09:33:23 INFO - { 09:33:23 INFO - "project": "", 09:33:23 INFO - "product": "firefox", 09:33:23 INFO - "script_repo_revision": "production", 09:33:23 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:33:23 INFO - "repository": "", 09:33:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:33:23 INFO - "buildid": "20170525080444", 09:33:23 INFO - "pgo_build": "False", 09:33:23 INFO - "basedir": "/builds/slave/test", 09:33:23 INFO - "buildnumber": 3, 09:33:23 INFO - "slavename": "t-yosemite-r7-0386", 09:33:23 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:33:23 INFO - "platform": "yosemite_r7", 09:33:23 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:33:23 INFO - "branch": "mozilla-beta", 09:33:23 INFO - "repo_path": "releases/mozilla-beta", 09:33:23 INFO - "revision": "38c9c1ad194a58d08440919f0c4618993917315b", 09:33:23 INFO - "stage_platform": "macosx64", 09:33:23 INFO - "builduid": "1a835487f771469794903d56594ae782", 09:33:23 INFO - "slavebuilddir": "test" 09:33:23 INFO - } 09:33:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 09:33:23 INFO - Running post-action listener: _resource_record_post_action 09:33:23 INFO - [mozharness: 2017-05-25 16:33:23.276521Z] Finished read-buildbot-config step (success) 09:33:23 INFO - [mozharness: 2017-05-25 16:33:23.276655Z] Running download-and-extract step. 09:33:23 INFO - Running pre-action listener: _resource_record_pre_action 09:33:23 INFO - Running main action method: download_and_extract 09:33:23 INFO - mkdir: /builds/slave/test/build/tests 09:33:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:33:23 INFO - trying https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 09:33:23 INFO - Downloading https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/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 09:33:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/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 09:33:24 INFO - Downloaded 1287 bytes. 09:33:24 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:33:24 INFO - Using the following test package requirements: 09:33:24 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:33:24 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 09:33:24 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:33:24 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:33:24 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 09:33:24 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:33:24 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 09:33:24 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:33:24 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:33:24 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 09:33:24 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:33:24 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:33:24 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 09:33:24 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:33:24 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 09:33:24 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:33:24 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 09:33:24 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:33:24 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 09:33:24 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 09:33:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:33:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:33:24 INFO - Fetch https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:33:25 INFO - Expected file size: 51393269 09:33:25 INFO - Obtained file size: 51393269 09:33:42 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 09:33:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:33:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:33:42 INFO - Fetch https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:33:44 INFO - Expected file size: 51393269 09:33:44 INFO - Obtained file size: 51393269 09:33:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 09:33:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 09:33:59 INFO - Fetch https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 09:34:00 INFO - Expected file size: 14404449 09:34:00 INFO - Obtained file size: 14404449 09:34:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:00 INFO - trying https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:34:00 INFO - Downloading https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 09:34:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/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 09:34:02 INFO - Downloaded 59648164 bytes. 09:34:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:34:02 INFO - mkdir: /builds/slave/test/properties 09:34:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:34:02 INFO - Writing to file /builds/slave/test/properties/build_url 09:34:02 INFO - Contents: 09:34:02 INFO - build_url:https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:34:03 INFO - Running post-action listener: _resource_record_post_action 09:34:03 INFO - Running post-action listener: set_extra_try_arguments 09:34:03 INFO - [mozharness: 2017-05-25 16:34:03.062988Z] Finished download-and-extract step (success) 09:34:03 INFO - [mozharness: 2017-05-25 16:34:03.063119Z] Running populate-webroot step. 09:34:03 INFO - Running pre-action listener: _resource_record_pre_action 09:34:03 INFO - Running main action method: populate_webroot 09:34:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:34:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:34:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:34:03 INFO - '--tppagecycles', 09:34:03 INFO - '7'], 09:34:03 INFO - 'tests': ['tsvgm']}}, 09:34:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:34:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:34:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:34:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:34:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:34:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:34:03 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:34:03 INFO - 'talos_options': ['--disable-e10s'], 09:34:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:34:03 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:34:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:34:03 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:34:03 INFO - 'talos_options': ['--disable-e10s'], 09:34:03 INFO - 'tests': ['damp', 'tps']}, 09:34:03 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:34:03 INFO - 'tests': ['damp', 'tps']}, 09:34:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:34:03 INFO - 'tests': ['dromaeo_dom']}, 09:34:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:34:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:34:03 INFO - 'tests': ['basic_compositor_video']}, 09:34:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:34:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:34:03 INFO - 'tests': ['a11yr', 09:34:03 INFO - 'ts_paint', 09:34:03 INFO - 'tpaint', 09:34:03 INFO - 'sessionrestore', 09:34:03 INFO - 'sessionrestore_no_auto_restore', 09:34:03 INFO - 'tabpaint']}, 09:34:03 INFO - 'other-e10s': {'tests': ['a11yr', 09:34:03 INFO - 'ts_paint', 09:34:03 INFO - 'tpaint', 09:34:03 INFO - 'sessionrestore', 09:34:03 INFO - 'sessionrestore_no_auto_restore', 09:34:03 INFO - 'tabpaint']}, 09:34:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:34:03 INFO - 'ts_paint', 09:34:03 INFO - 'tpaint', 09:34:03 INFO - 'sessionrestore', 09:34:03 INFO - 'sessionrestore_no_auto_restore', 09:34:03 INFO - 'tabpaint']}, 09:34:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:34:03 INFO - 'ts_paint', 09:34:03 INFO - 'tpaint', 09:34:03 INFO - 'sessionrestore', 09:34:03 INFO - 'sessionrestore_no_auto_restore', 09:34:03 INFO - 'tabpaint']}, 09:34:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:34:03 INFO - 'tests': ['a11yr', 09:34:03 INFO - 'ts_paint', 09:34:03 INFO - 'tpaint', 09:34:03 INFO - 'sessionrestore', 09:34:03 INFO - 'sessionrestore_no_auto_restore', 09:34:03 INFO - 'tabpaint']}, 09:34:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:34:03 INFO - 'tests': ['a11yr', 09:34:03 INFO - 'ts_paint', 09:34:03 INFO - 'tpaint', 09:34:03 INFO - 'sessionrestore', 09:34:03 INFO - 'sessionrestore_no_auto_restore', 09:34:03 INFO - 'tabpaint']}, 09:34:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:34:03 INFO - 'tests': ['tsvgx', 09:34:03 INFO - 'tsvgr_opacity', 09:34:03 INFO - 'tart', 09:34:03 INFO - 'tscrollx', 09:34:03 INFO - 'cart', 09:34:03 INFO - 'tsvg_static']}, 09:34:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:34:03 INFO - 'tsvgr_opacity', 09:34:03 INFO - 'tart', 09:34:03 INFO - 'tscrollx', 09:34:03 INFO - 'cart', 09:34:03 INFO - 'tsvg_static']}, 09:34:03 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:34:03 INFO - 'talos_options': ['--disable-e10s'], 09:34:03 INFO - 'tests': ['tp5o']}, 09:34:03 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:34:03 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:34:03 INFO - 'talos_options': ['--disable-e10s', 09:34:03 INFO - '--xperf_path', 09:34:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:34:03 INFO - 'tests': ['tp5n']}, 09:34:03 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:34:03 INFO - 'talos_options': ['--xperf_path', 09:34:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:34:03 INFO - 'tests': ['tp5n']}}, 09:34:03 INFO - 'talos.zip': {'path': '', 09:34:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:34:03 INFO - Running post-action listener: _resource_record_post_action 09:34:03 INFO - [mozharness: 2017-05-25 16:34:03.081941Z] Finished populate-webroot step (success) 09:34:03 INFO - [mozharness: 2017-05-25 16:34:03.082036Z] Running create-virtualenv step. 09:34:03 INFO - Running pre-action listener: _resource_record_pre_action 09:34:03 INFO - Running main action method: create_virtualenv 09:34:03 INFO - Creating virtualenv /builds/slave/test/build/venv 09:34:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:34:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:34:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:34:03 INFO - Using real prefix '/tools/python27' 09:34:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:34:03 INFO - Installing distribute.............................................................................................................................................................................................done. 09:34:06 INFO - Installing pip.................done. 09:34:06 INFO - Return code: 0 09:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:34:07 INFO - Reading from file tmpfile_stdout 09:34:07 INFO - Output received: 09:34:07 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:34:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:34:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e145b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e148768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c69d92700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df23d30>, '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 0x10de4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df18be8>, '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.xEjuExtI1p/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.q0ZSv3K879/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 09:34:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:34:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:34:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEjuExtI1p/Render', 09:34:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:07 INFO - 'HOME': '/Users/cltbld', 09:34:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:07 INFO - 'LOGNAME': 'cltbld', 09:34:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:07 INFO - 'MOZ_NO_REMOTE': '1', 09:34:07 INFO - 'NO_EM_RESTART': '1', 09:34:07 INFO - 'PAGER': '/bin/cat', 09:34:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:07 INFO - 'PWD': '/builds/slave/test', 09:34:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:07 INFO - 'SHELL': '/bin/bash', 09:34:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0ZSv3K879/Listeners', 09:34:07 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:34:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:07 INFO - 'USER': 'cltbld', 09:34:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:07 INFO - 'XPC_FLAGS': '0x0', 09:34:07 INFO - 'XPC_SERVICE_NAME': '0', 09:34:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:07 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 09:34:07 INFO - Cleaning up... 09:34:07 INFO - Return code: 0 09:34:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:34:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e145b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e148768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c69d92700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df23d30>, '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 0x10de4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df18be8>, '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.xEjuExtI1p/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.q0ZSv3K879/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 09:34:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:34:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:34:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEjuExtI1p/Render', 09:34:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:07 INFO - 'HOME': '/Users/cltbld', 09:34:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:07 INFO - 'LOGNAME': 'cltbld', 09:34:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:07 INFO - 'MOZ_NO_REMOTE': '1', 09:34:07 INFO - 'NO_EM_RESTART': '1', 09:34:07 INFO - 'PAGER': '/bin/cat', 09:34:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:07 INFO - 'PWD': '/builds/slave/test', 09:34:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:07 INFO - 'SHELL': '/bin/bash', 09:34:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0ZSv3K879/Listeners', 09:34:07 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:34:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:07 INFO - 'USER': 'cltbld', 09:34:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:07 INFO - 'XPC_FLAGS': '0x0', 09:34:07 INFO - 'XPC_SERVICE_NAME': '0', 09:34:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:07 INFO - Downloading/unpacking psutil>=3.1.1 09:34:07 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 09:34:07 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 09:34:07 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 09:34:07 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 09:34:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:34:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:34:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:34:09 INFO - Installing collected packages: psutil 09:34:09 INFO - Running setup.py install for psutil 09:34:09 INFO - building 'psutil._psutil_osx' extension 09:34:09 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 09:34:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:34:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:34:10 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:34:10 INFO - building 'psutil._psutil_posix' extension 09:34:10 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 09:34:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:34:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:34:10 INFO - ^ 09:34:10 INFO - 1 warning generated. 09:34:10 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 09:34:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:34:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:34:10 INFO - Successfully installed psutil 09:34:10 INFO - Cleaning up... 09:34:10 INFO - Return code: 0 09:34:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:34:10 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')]} 09:34:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:10 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')]} 09:34:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:10 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 0x10e145b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e148768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c69d92700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df23d30>, '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 0x10de4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df18be8>, '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.xEjuExtI1p/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.q0ZSv3K879/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 09:34:10 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 09:34:10 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 09:34:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEjuExtI1p/Render', 09:34:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:10 INFO - 'HOME': '/Users/cltbld', 09:34:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:10 INFO - 'LOGNAME': 'cltbld', 09:34:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:10 INFO - 'MOZ_NO_REMOTE': '1', 09:34:10 INFO - 'NO_EM_RESTART': '1', 09:34:10 INFO - 'PAGER': '/bin/cat', 09:34:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:10 INFO - 'PWD': '/builds/slave/test', 09:34:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:10 INFO - 'SHELL': '/bin/bash', 09:34:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0ZSv3K879/Listeners', 09:34:10 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:34:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:10 INFO - 'USER': 'cltbld', 09:34:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:10 INFO - 'XPC_FLAGS': '0x0', 09:34:10 INFO - 'XPC_SERVICE_NAME': '0', 09:34:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:10 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:34:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:34:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:34:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:34:12 INFO - Installing collected packages: mozsystemmonitor 09:34:12 INFO - Running setup.py install for mozsystemmonitor 09:34:12 INFO - Successfully installed mozsystemmonitor 09:34:12 INFO - Cleaning up... 09:34:12 INFO - Return code: 0 09:34:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e145b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e148768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c69d92700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df23d30>, '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 0x10de4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df18be8>, '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.xEjuExtI1p/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.q0ZSv3K879/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 09:34:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:34:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:34:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEjuExtI1p/Render', 09:34:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:12 INFO - 'HOME': '/Users/cltbld', 09:34:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:12 INFO - 'LOGNAME': 'cltbld', 09:34:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:12 INFO - 'MOZ_NO_REMOTE': '1', 09:34:12 INFO - 'NO_EM_RESTART': '1', 09:34:12 INFO - 'PAGER': '/bin/cat', 09:34:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:12 INFO - 'PWD': '/builds/slave/test', 09:34:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:12 INFO - 'SHELL': '/bin/bash', 09:34:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0ZSv3K879/Listeners', 09:34:12 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:34:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:12 INFO - 'USER': 'cltbld', 09:34:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:12 INFO - 'XPC_FLAGS': '0x0', 09:34:12 INFO - 'XPC_SERVICE_NAME': '0', 09:34:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:12 INFO - Downloading/unpacking jsonschema==2.5.1 09:34:12 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 09:34:12 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 09:34:12 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 09:34:12 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 09:34:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:34:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:14 INFO - Downloading functools32-3.2.3-2.tar.gz 09:34:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:34:14 INFO - warning: no files found matching '*.txt' 09:34:14 INFO - no previously-included directories found matching 'build' 09:34:14 INFO - no previously-included directories found matching 'dist' 09:34:14 INFO - no previously-included directories found matching '.git*' 09:34:14 INFO - Installing collected packages: jsonschema, functools32 09:34:14 INFO - Running setup.py install for functools32 09:34:14 INFO - warning: no files found matching '*.txt' 09:34:14 INFO - no previously-included directories found matching 'build' 09:34:14 INFO - no previously-included directories found matching 'dist' 09:34:14 INFO - no previously-included directories found matching '.git*' 09:34:14 INFO - Successfully installed jsonschema functools32 09:34:14 INFO - Cleaning up... 09:34:14 INFO - Return code: 0 09:34:14 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:34:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e145b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e148768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c69d92700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df23d30>, '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 0x10de4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df18be8>, '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.xEjuExtI1p/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.q0ZSv3K879/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 09:34:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:34:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:34:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEjuExtI1p/Render', 09:34:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:14 INFO - 'HOME': '/Users/cltbld', 09:34:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:14 INFO - 'LOGNAME': 'cltbld', 09:34:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:14 INFO - 'MOZ_NO_REMOTE': '1', 09:34:14 INFO - 'NO_EM_RESTART': '1', 09:34:14 INFO - 'PAGER': '/bin/cat', 09:34:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:14 INFO - 'PWD': '/builds/slave/test', 09:34:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:14 INFO - 'SHELL': '/bin/bash', 09:34:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0ZSv3K879/Listeners', 09:34:14 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:34:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:14 INFO - 'USER': 'cltbld', 09:34:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:14 INFO - 'XPC_FLAGS': '0x0', 09:34:14 INFO - 'XPC_SERVICE_NAME': '0', 09:34:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:34:15 INFO - Cleaning up... 09:34:15 INFO - Return code: 0 09:34:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:34:15 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')]} 09:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:15 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')]} 09:34:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:15 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 0x10e145b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e148768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c69d92700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df23d30>, '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 0x10de4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df18be8>, '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.xEjuExtI1p/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.q0ZSv3K879/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 09:34:15 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 09:34:15 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 09:34:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEjuExtI1p/Render', 09:34:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:15 INFO - 'HOME': '/Users/cltbld', 09:34:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:15 INFO - 'LOGNAME': 'cltbld', 09:34:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:15 INFO - 'MOZ_NO_REMOTE': '1', 09:34:15 INFO - 'NO_EM_RESTART': '1', 09:34:15 INFO - 'PAGER': '/bin/cat', 09:34:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:15 INFO - 'PWD': '/builds/slave/test', 09:34:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:15 INFO - 'SHELL': '/bin/bash', 09:34:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0ZSv3K879/Listeners', 09:34:15 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:34:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:15 INFO - 'USER': 'cltbld', 09:34:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:15 INFO - 'XPC_FLAGS': '0x0', 09:34:15 INFO - 'XPC_SERVICE_NAME': '0', 09:34:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:16 INFO - Downloading/unpacking blobuploader==1.2.4 09:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:17 INFO - Downloading blobuploader-1.2.4.tar.gz 09:34:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:34:17 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:34:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:34:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:34:18 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:34:18 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 09:34:18 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 09:34:18 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 09:34:18 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 09:34:18 INFO - Downloading docopt-0.6.1.tar.gz 09:34:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:34:18 INFO - Installing collected packages: blobuploader, requests, docopt 09:34:18 INFO - Running setup.py install for blobuploader 09:34:18 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:34:18 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:34:18 INFO - Running setup.py install for requests 09:34:19 INFO - Running setup.py install for docopt 09:34:19 INFO - Successfully installed blobuploader requests docopt 09:34:19 INFO - Cleaning up... 09:34:19 INFO - Return code: 0 09:34:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:19 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')]} 09:34:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e145b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e148768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c69d92700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df23d30>, '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 0x10de4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df18be8>, '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.xEjuExtI1p/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.q0ZSv3K879/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 09:34:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:34:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:34:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEjuExtI1p/Render', 09:34:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:20 INFO - 'HOME': '/Users/cltbld', 09:34:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:20 INFO - 'LOGNAME': 'cltbld', 09:34:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:20 INFO - 'MOZ_NO_REMOTE': '1', 09:34:20 INFO - 'NO_EM_RESTART': '1', 09:34:20 INFO - 'PAGER': '/bin/cat', 09:34:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:20 INFO - 'PWD': '/builds/slave/test', 09:34:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:20 INFO - 'SHELL': '/bin/bash', 09:34:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0ZSv3K879/Listeners', 09:34:20 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:34:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:20 INFO - 'USER': 'cltbld', 09:34:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:20 INFO - 'XPC_FLAGS': '0x0', 09:34:20 INFO - 'XPC_SERVICE_NAME': '0', 09:34:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:20 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-3kXZF8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:20 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-tWX1rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:20 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-hCQ9jb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:20 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-H0CqB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:20 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-oCM4Rq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:20 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-KANzHP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:20 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-G6sZqZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:21 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Tl66Vm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:21 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-f6qmMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:21 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-LKFAD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:21 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Z2dkyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:21 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-Zy_Oo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:21 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-8hD1UQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:21 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-CHYQgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:21 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-RSJZ_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:21 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-F_sy5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:21 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-FKh7md-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:34:22 INFO - Running setup.py install for manifestparser 09:34:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:34:22 INFO - Running setup.py install for mozcrash 09:34:22 INFO - Running setup.py install for mozdebug 09:34:22 INFO - Running setup.py install for mozdevice 09:34:22 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:34:22 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:34:22 INFO - Running setup.py install for mozfile 09:34:22 INFO - Running setup.py install for mozhttpd 09:34:22 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:34:22 INFO - Running setup.py install for mozinfo 09:34:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:34:23 INFO - Running setup.py install for mozInstall 09:34:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:34:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:34:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:34:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:34:23 INFO - Running setup.py install for mozleak 09:34:23 INFO - Running setup.py install for mozlog 09:34:23 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:34:23 INFO - Running setup.py install for moznetwork 09:34:23 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:34:23 INFO - Running setup.py install for mozprocess 09:34:23 INFO - Running setup.py install for mozprofile 09:34:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:34:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:34:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:34:24 INFO - Running setup.py install for mozrunner 09:34:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:34:24 INFO - Running setup.py install for mozscreenshot 09:34:24 INFO - Running setup.py install for moztest 09:34:24 INFO - Running setup.py install for mozversion 09:34:24 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:34:24 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:34:24 INFO - Cleaning up... 09:34:24 INFO - Return code: 0 09:34:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:24 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')]} 09:34:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:24 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')]} 09:34:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:24 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 0x10e145b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e148768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c69d92700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df23d30>, '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 0x10de4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df18be8>, '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.xEjuExtI1p/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.q0ZSv3K879/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 09:34:24 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 09:34:24 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 09:34:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEjuExtI1p/Render', 09:34:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:24 INFO - 'HOME': '/Users/cltbld', 09:34:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:24 INFO - 'LOGNAME': 'cltbld', 09:34:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:24 INFO - 'MOZ_NO_REMOTE': '1', 09:34:24 INFO - 'NO_EM_RESTART': '1', 09:34:24 INFO - 'PAGER': '/bin/cat', 09:34:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:24 INFO - 'PWD': '/builds/slave/test', 09:34:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:24 INFO - 'SHELL': '/bin/bash', 09:34:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0ZSv3K879/Listeners', 09:34:24 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:34:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:24 INFO - 'USER': 'cltbld', 09:34:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:24 INFO - 'XPC_FLAGS': '0x0', 09:34:24 INFO - 'XPC_SERVICE_NAME': '0', 09:34:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:34:24 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-pEsBV8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:34:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-rkz51V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:34:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-sn7mwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:34:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:34:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-rU3JQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:34:25 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)) 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:34:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-8PqvBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:34:25 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)) 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:34:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-9vTQa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:34:25 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)) 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:34:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-2DyMfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:34:25 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)) 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:34:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-7SEWHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:34:25 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)) 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:34:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-B8zmQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:34:25 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)) 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:34:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-evYm2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:34:25 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)) 09:34:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:34:25 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-aLgdMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:34:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:34:26 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-ZGUyHV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:34:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:34:26 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-sniUP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:34:26 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)) 09:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:34:26 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-XBlZ_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:34:26 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)) 09:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:26 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-rRhMV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:34:26 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)) 09:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:34:26 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-5jNyrl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:34:26 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)) 09:34:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:34:26 INFO - Running setup.py (path:/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/pip-nEUYm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:34:26 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)) 09:34:26 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)) 09:34:26 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)) 09:34:26 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)) 09:34:26 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)) 09:34:26 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)) 09:34:26 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:34:26 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 09:34:26 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 09:34:26 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 09:34:26 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 09:34:28 INFO - Downloading blessings-1.6.tar.gz 09:34:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:34:28 INFO - Installing collected packages: blessings 09:34:28 INFO - Running setup.py install for blessings 09:34:28 INFO - Successfully installed blessings 09:34:28 INFO - Cleaning up... 09:34:28 INFO - Return code: 0 09:34:28 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:34:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:28 INFO - Reading from file tmpfile_stdout 09:34:28 INFO - Current package versions: 09:34:28 INFO - blessings == 1.6 09:34:28 INFO - blobuploader == 1.2.4 09:34:28 INFO - docopt == 0.6.1 09:34:28 INFO - functools32 == 3.2.3-2 09:34:28 INFO - jsonschema == 2.5.1 09:34:28 INFO - manifestparser == 1.1 09:34:28 INFO - mozInstall == 1.12 09:34:28 INFO - mozcrash == 1.0 09:34:28 INFO - mozdebug == 0.1 09:34:28 INFO - mozdevice == 0.49 09:34:28 INFO - mozfile == 1.2 09:34:28 INFO - mozhttpd == 0.7 09:34:28 INFO - mozinfo == 0.9 09:34:28 INFO - mozleak == 0.1 09:34:28 INFO - mozlog == 3.4 09:34:28 INFO - moznetwork == 0.27 09:34:28 INFO - mozprocess == 0.25 09:34:28 INFO - mozprofile == 0.28 09:34:28 INFO - mozrunner == 6.13 09:34:28 INFO - mozscreenshot == 0.1 09:34:28 INFO - mozsystemmonitor == 0.3 09:34:28 INFO - moztest == 0.8 09:34:28 INFO - mozversion == 1.4 09:34:28 INFO - psutil == 3.1.1 09:34:28 INFO - requests == 1.2.3 09:34:28 INFO - wsgiref == 0.1.2 09:34:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:28 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 0x10e145b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e148768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c69d92700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df23d30>, '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 0x10de4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df18be8>, '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.xEjuExtI1p/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.q0ZSv3K879/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 09:34:28 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 09:34:28 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 09:34:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEjuExtI1p/Render', 09:34:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:28 INFO - 'HOME': '/Users/cltbld', 09:34:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:28 INFO - 'LOGNAME': 'cltbld', 09:34:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:28 INFO - 'MOZ_NO_REMOTE': '1', 09:34:28 INFO - 'NO_EM_RESTART': '1', 09:34:28 INFO - 'PAGER': '/bin/cat', 09:34:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:28 INFO - 'PWD': '/builds/slave/test', 09:34:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:28 INFO - 'SHELL': '/bin/bash', 09:34:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0ZSv3K879/Listeners', 09:34:28 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:34:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:28 INFO - 'USER': 'cltbld', 09:34:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:28 INFO - 'XPC_FLAGS': '0x0', 09:34:28 INFO - 'XPC_SERVICE_NAME': '0', 09:34:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:28 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)) 09:34:28 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)) 09:34:28 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)) 09:34:28 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)) 09:34:28 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)) 09:34:28 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)) 09:34:28 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)) 09:34:28 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)) 09:34:28 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)) 09:34:28 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)) 09:34:28 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)) 09:34:28 INFO - Cleaning up... 09:34:29 INFO - Return code: 0 09:34:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:34:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:34:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:34:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e145b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e148768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8c69d92700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10df23d30>, '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 0x10de4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df18be8>, '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.xEjuExtI1p/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.q0ZSv3K879/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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 09:34:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:34:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:34:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEjuExtI1p/Render', 09:34:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:29 INFO - 'HOME': '/Users/cltbld', 09:34:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:29 INFO - 'LOGNAME': 'cltbld', 09:34:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:29 INFO - 'MOZ_NO_REMOTE': '1', 09:34:29 INFO - 'NO_EM_RESTART': '1', 09:34:29 INFO - 'PAGER': '/bin/cat', 09:34:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:29 INFO - 'PWD': '/builds/slave/test', 09:34:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:29 INFO - 'SHELL': '/bin/bash', 09:34:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0ZSv3K879/Listeners', 09:34:29 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:34:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:29 INFO - 'USER': 'cltbld', 09:34:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:29 INFO - 'XPC_FLAGS': '0x0', 09:34:29 INFO - 'XPC_SERVICE_NAME': '0', 09:34:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:34:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:34:29 INFO - Cleaning up... 09:34:29 INFO - Return code: 0 09:34:29 INFO - Running post-action listener: _resource_record_post_action 09:34:29 INFO - Running post-action listener: _start_resource_monitoring 09:34:29 INFO - Starting resource monitoring. 09:34:29 INFO - [mozharness: 2017-05-25 16:34:29.300174Z] Finished create-virtualenv step (success) 09:34:29 INFO - [mozharness: 2017-05-25 16:34:29.301120Z] Running install step. 09:34:29 INFO - Running pre-action listener: _resource_record_pre_action 09:34:29 INFO - Running main action method: install 09:34:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:34:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:34:29 INFO - Reading from file tmpfile_stdout 09:34:29 INFO - Detecting whether we're running mozinstall >=1.0... 09:34:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:34:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:34:29 INFO - Reading from file tmpfile_stdout 09:34:29 INFO - Output received: 09:34:29 INFO - Usage: mozinstall [options] installer 09:34:29 INFO - Options: 09:34:29 INFO - -h, --help show this help message and exit 09:34:29 INFO - -d DEST, --destination=DEST 09:34:29 INFO - Directory to install application into. [default: 09:34:29 INFO - "/builds/slave/test"] 09:34:29 INFO - --app=APP Application being installed. [default: firefox] 09:34:29 INFO - mkdir: /builds/slave/test/build/application 09:34:29 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'] 09:34:29 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 09:34:44 INFO - Reading from file tmpfile_stdout 09:34:44 INFO - Output received: 09:34:44 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:34:44 INFO - Running post-action listener: _resource_record_post_action 09:34:44 INFO - [mozharness: 2017-05-25 16:34:44.402976Z] Finished install step (success) 09:34:44 INFO - [mozharness: 2017-05-25 16:34:44.403126Z] Running run-tests step. 09:34:44 INFO - Running pre-action listener: _resource_record_pre_action 09:34:44 INFO - Running main action method: run_tests 09:34:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:34:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:34:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:34:44 INFO - 2017-05-25 09:34:44.418 screenresolution[1623:7466] starting screenresolution argv=screenresolution get 09:34:44 INFO - 2017-05-25 09:34:44.420 screenresolution[1623:7466] Display 0: 1600x1200x32@60 09:34:44 INFO - 2017-05-25 09:34:44.426 screenresolution[1624:7468] starting screenresolution argv=screenresolution list 09:34:44 INFO - Available Modes on Display 0 09:34:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:34:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:34:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:34:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:34:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:34:44 INFO - Intel Iris: 09:34:44 INFO - Chipset Model: Intel Iris 09:34:44 INFO - Type: GPU 09:34:44 INFO - Bus: Built-In 09:34:44 INFO - VRAM (Dynamic, Max): 1536 MB 09:34:44 INFO - Vendor: Intel (0x8086) 09:34:44 INFO - Device ID: 0x0a2e 09:34:44 INFO - Revision ID: 0x0009 09:34:44 INFO - Displays: 09:34:44 INFO - Display: 09:34:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:34:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:34:44 INFO - Display Serial Number: 1600x1200 60 09:34:44 INFO - Main Display: Yes 09:34:44 INFO - Mirror: Off 09:34:44 INFO - Online: Yes 09:34:44 INFO - Rotation: Supported 09:34:44 INFO - Return code: 0 09:34:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:34:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:34:44 INFO - Python 2.7.3 09:34:44 INFO - Return code: 0 09:34:44 INFO - grabbing minidump binary from tooltool 09:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:44 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 0x10df23d30>, '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 0x10de4adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10df18be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:34:44 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 09:34:44 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 09:34:44 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 09:34:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:34:44 INFO - Return code: 0 09:34:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:34:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:34:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:34:44 INFO - ENV: RUST_BACKTRACE is now 1 09:34:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:34:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:34:44 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/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0386', '--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 09:34:44 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/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0386 --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 09:34:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xEjuExtI1p/Render', 09:34:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:34:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:34:44 INFO - 'HOME': '/Users/cltbld', 09:34:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:34:44 INFO - 'LOGNAME': 'cltbld', 09:34:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:34:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:34:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:34:44 INFO - 'MOZ_NO_REMOTE': '1', 09:34:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:34:44 INFO - 'NO_EM_RESTART': '1', 09:34:44 INFO - 'PAGER': '/bin/cat', 09:34:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:34:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:34:44 INFO - 'PWD': '/builds/slave/test', 09:34:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:34:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:34:44 INFO - 'RUST_BACKTRACE': '1', 09:34:44 INFO - 'SHELL': '/bin/bash', 09:34:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.q0ZSv3K879/Listeners', 09:34:44 INFO - 'TMPDIR': '/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/', 09:34:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:34:44 INFO - 'USER': 'cltbld', 09:34:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:34:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:34:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:34:44 INFO - 'XPC_FLAGS': '0x0', 09:34:44 INFO - 'XPC_SERVICE_NAME': '0', 09:34:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:34:44 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/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0386', '--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 09:34:45 INFO - mozversion application_buildid: 20170525080444 09:34:45 INFO - mozversion application_changeset: 38c9c1ad194a58d08440919f0c4618993917315b 09:34:45 INFO - mozversion application_display_name: Firefox 09:34:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:34:45 INFO - mozversion application_name: Firefox 09:34:45 INFO - mozversion application_remotingname: firefox 09:34:45 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:34:45 INFO - mozversion application_vendor: Mozilla 09:34:45 INFO - mozversion application_version: 54.0 09:34:45 INFO - mozversion platform_buildid: 20170525080444 09:34:45 INFO - mozversion platform_changeset: 38c9c1ad194a58d08440919f0c4618993917315b 09:34:45 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:34:45 INFO - mozversion platform_version: 54.0 09:34:45 INFO - using testdate: 1495730085 09:34:45 INFO - actual date: 1495730085 09:34:45 INFO - starting webserver on 'localhost:49226' 09:34:45 INFO - SUITE-START | Running 2 tests 09:34:45 INFO - TEST-START | dromaeo_css 09:34:45 INFO - operating with platform_type : mac_ 09:34:45 INFO - Initialising browser for dromaeo_css test... 09:34:45 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmpFOQQjO/profile http://localhost:49226/getInfo.html) 09:34:47 INFO - PROCESS | 1634 | 2017-05-25 09:34:47.760 plugin-container[1637:7633] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4b, name = 'com.apple.tsm.portname' 09:34:47 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:34:47 INFO - PROCESS | 1634 | 2017-05-25 09:34:47.760 plugin-container[1637:7633] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a23, name = 'com.apple.CFPasteboardClient' 09:34:47 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:34:47 INFO - PROCESS | 1634 | 2017-05-25 09:34:47.760 plugin-container[1637:7633] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:34:47 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 09:34:47 INFO - PROCESS | 1634 | colorDepth:24 09:34:47 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 09:34:47 INFO - PROCESS | 1634 | __metrics 09:34:47 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 09:34:48 INFO - TEST-INFO | 1634: exit 0 09:34:48 INFO - Browser initialized. 09:34:48 INFO - Running cycle 1/1 for dromaeo_css test... 09:34:48 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmpFOQQjO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:34:50 INFO - PROCESS | 1638 | 2017-05-25 09:34:50.710 plugin-container[1639:7768] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 09:34:50 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:34:50 INFO - PROCESS | 1638 | 2017-05-25 09:34:50.710 plugin-container[1639:7768] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8617, name = 'com.apple.CFPasteboardClient' 09:34:50 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:34:50 INFO - PROCESS | 1638 | 2017-05-25 09:34:50.710 plugin-container[1639:7768] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:35:00 INFO - PROCESS | 1638 | RSS: Main: 174649344 09:35:00 INFO - PROCESS | 1638 | 09:38:03 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 09:38:05 INFO - PROCESS | 1638 | RSS: Main: 189345792 09:38:05 INFO - PROCESS | 1638 | 09:41:07 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 09:41:08 INFO - PROCESS | 1638 | RSS: Main: 203071488 09:41:08 INFO - PROCESS | 1638 | 09:44:10 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 09:44:10 INFO - PROCESS | 1638 | RSS: Main: 204824576 09:44:10 INFO - PROCESS | 1638 | 09:47:12 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 09:47:13 INFO - PROCESS | 1638 | RSS: Main: 211054592 09:47:13 INFO - PROCESS | 1638 | 09:50:16 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 09:50:16 INFO - PROCESS | 1638 | RSS: Main: 213565440 09:50:16 INFO - PROCESS | 1638 | 09:53:19 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 09:53:19 INFO - PROCESS | 1638 | RSS: Main: 211636224 09:53:19 INFO - PROCESS | 1638 | 09:53:19 INFO - PROCESS | 1638 | __start_tp_report 09:53:19 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 09:53:19 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 09:53:19 INFO - PROCESS | 1638 | |i|pagename|runs| 09:53:19 INFO - PROCESS | 1638 | |0;dojo.html;3539.292141571686;3544.184837487378;3579.141006158523;3579.9105022374447;3620.0587847159745;5996.52027837773;6052.122442245883;6061.545384096199;6094.786682466114;6096.237970253718;4533;5785.942140578593;6109.938900610992;6132.417420345071;6149.815505534841;5459.481349271813;5504.422035686255;5532.723363831812;5534.169874518814;5684.204211410406;5592.4407559243955;5594.832155035356;5612.41069687683;5615.157726341061;5619.522340601054;4704.376989938482;5328.254044433782;5412.404635490093;5609.971950140243;5611.7755289788565;5550.72246387679;5554.583406244527;5565.972170139144;5584.246126772874;5588.524975377098;4697.436307643105;5427.3215848019;5491.148871924858;5547.001539722848;5588.469095435927;5043.36957880265;5936.762529498815;6204.534659900525;6223.471004964538;6223.533235007348;6394.328595497499;6417.903731444032;6443.3234510376205;6466.7736629218225;6579.736810527573;6708.7651932182625;6719.395254427125;6727.024581435664;6731.798046058626;6795.1845778506895;6195.783147589903;6218.657973811484;6266.185395898504;6281.152044473984;6295.433410992987;5364.892702145935;6543.9345606544275;6569.638669873203;6762.492813039042;6829.863402732017;6513.641749703717;6527.314631963575;6534.869302614016;6539.247986481616;6569.375909288706;6645.401913827779;6654.700538475683;6690.063391125246;6717.294684058202;6721.66391680414;6215.036669316263;6330.272018717907;6560.475161987125;6609.6695165241545;6616.206055273302;6279.434850863353;6486.67566621667;6503.967480162569;6628.370304821035;6629.038789375513;4901.632377571696;5207.635465517377;5238.057149713012;5268.894622107537;5272.20916862466;4461.353103799996;4664.160451118763;4667.276572131257;4668.649851261169;4713.033828065239;5058.4435712071645;6113.354685976905;6255.967765318672;6268.717907694165;6325.114483972247;6510.576812507172;6580.078788969548;6634.502412319095;6639.933600664028;6674.132362792806;6246.4378205960775;6259.217597800275;6259.937400625936;6261.8747625047245;6273.7176827042895;5580.888382232332;5829.7376618052285;5836.387179346192;5844.1818145459665;5893.410658934073;6717.1267735194115;6746.763863264664;6753.696083676148;6826.385625293748;6848.623325717133;6713.899291510534;6744.966275168593;6746.089277947611;6757.22291936414;6770.32296770319;6795.490338224752;6871.484638652021;6918.827029324307;6938.098047253825;6952.826179345355;6308.243010838729;6329.5252856036905;6465.515086368635;6485.156929599122;6512.641804700787;6670.299618540177;6672.766453174116;6674.733010679713;6680.098186744727;6692.866142677217;6435.646039467874;6445.6454894981225;6469.77355792545;6484.610923344614;6522.804315870531;6229.906551401823;6279.058141278845;6309.526785491198;6322.715477803602;6370.331115232834;6219.471344935731;6294.590851594632;6311.211098612674;6321.431071203614;6323.588966717149;6404.231492220963;6460.483161347175;6466.676666166767;6478.028295755675;6482.448991835747;6108.22065524731;6178.62928224308;6244.68776561161;6248;6332.208473940757 09:53:19 INFO - PROCESS | 1638 | |1;ext.html;769.3883362726632;823.213830791594;842.7935155886806;982.0130768577587;982.469466488096;39564.80217598911;40342.1931561369;42099;42178.7891060545;42269.36595951056;39681.206375872534;39866.80066599663;39906.80279591617;39919.00202494939;40005.59994400063;8162.999999999985;8304.169583041677;10104.53484244784;11049.944750276278;11390.772184556305;9828.459434731087;10519.263651544396;11074.944625276863;11280.605178818743;11311.886881131166;9546.904530954705;9548.665796697116;11232.66302010941;11259.155563332743;11273.887261127407;11249.325040497597;11252.212345135844;11263.324200547951;11275.661730148067;11292.548298068068;11027.889721102767;11060.502277397536;11061.336319820837;11066.059384952288;11080.224384293102;29865.55201671977;33528;33666;33739.98780036603;33816.492752608734;3918.294706952747;3948.348522493785;3980.601939805996;3994.980025099856;4061.8578349757913;26075.869620651963;26282.73717262822;26324.60513092305;27682.861585692142;27883;3005.0534081764395;3242.7243684286864;3289.3092450585405;3308.602967643874;3315.436375816117;307042.4647876769;314342;338077.3096134528;345682;346408;44214.67355979326;44414.77792611016;44497;44580;44702.32946505805;45706.771466143124;45736;45895.770521147846;46040.7697961508;46064.84837879013;14059.226742529054;14174;14230.928845355915;14235.43058277678;14244.85755142456;12466.626001219978;12474.251544907334;12490.625281241577;12495.187812792139;12516.937415312865;12003.519859205533;12023.398830058637;12028.21816581937;12042.698932526582;12171.817422738666;5599.740058486808;5674.375818659944;5730.484256416859;5735.71321433935;5747.885042299131;967.2133596967932;985.7979114281555;1104.5416152296707;1188.4176753390777;1196.6768972377408;26312.605310920353;26513.46973060528;27046;27703.30741731473;27906;1065.4832406282947;1238.3003602964297;1241.273854794937;1263.7236391244908;1267.3283159925254;14108.365123569463;14125.869930405543;14210.289485525682;14446;14481.78277325841;371307.14346428093;396387;397964;398859.00570496963;399224;56163;56529.43470565324;56563.71718141383;56603.43396566064;57823;1461.9342129604192;1470.8455612160783;1506.623344163959;1506.7438535449;1519.7340465418508;24651;25226;26948.326291842077;26951.46097078008;27104.593431098154;44240.55759442364;44299.11401772011;44342.556574433846;44378;44498.3325250115;324159;325147;325522;325649;326500;24444.266671999867;27307.678847297408;30230;31076;31106.688933110378;41041.973950651474;44748.32877506775;44893.102137956404;45607.77196113998;45809.541904580525;26700.866495667397;30653.693463065083;30800.38399231958;31394.901178458644;31442.842785785924;30634.84682576662;30753.846230768704;30872.38255234838;31634;31802.681973180897;37069.81465092657;37080.62919370772;37678.62321376751;37699;37805.43291850569;30543.694563054087;30914.536281955337;31860.5220921691;31962.52056219112;32124.518132227568;20625.896870515553;24268.271951841474;24933.002679893325;24979.500409992062;25162.119325823507 09:53:19 INFO - PROCESS | 1638 | |2;jquery.html;417.12612077697213;429.66700806874667;435.14928315143896;440.14172363890424;443.03639583905;17692.20385082671;21025.684614730773;21100.57798844024;21660.675089873665;21784.455388615293;19964.101615427342;20953.161873525078;22320.330390088282;22689.773102268973;22794;6080.939190608104;6853.8629227415295;7059.682314295869;7080.327368899946;7115.3951914087365;6219.782307619234;6343.365663433666;6991.126109236346;7047.43620510358;7058.717651293942;6707.98760371889;6981.930180698178;7039.366457018868;7062.223155452896;7070.9292907070785;6945.097137372135;6980.301969802979;7007.964960175217;7011.579305241702;7023.754168604074;7031.156261248631;7384.446166537477;7424.183339832614;7429.8514029719645;7450.627468626547;26315;26837.865810671017;26873.79067941947;27216.59175112375;27669.584956225674;3030.7878448508613;3065.080475857234;3066.2487690515745;3482.94775578364;3501.4747787831775;14355.78466323006;15349.539513814603;15492.147931863743;16987.53796521362;17560.91219543894;2146.356093172042;2168.5120847809276;2169.0673010605433;2171.2400659769114;2218.2901471529117;128790;163242;168869;171568.28431715773;173782.99999999875;19752;20507;20590.794092059186;20735.481612959495;20795.480112996993;31504.842475787933;32021.519677204862;32210.19474513156;32291.35417291641;32400;10740.892591074145;10807.67576972692;10953;10979.945100274606;11038.724031899106;7619.1999840016;9067.410618309788;9071.546422678839;9097.590608422637;9251.352405331561;9439.67693084303;9750.751316663755;9779.070988256104;9846;9904.504774761233;3674.9448758268645;3941.586133455946;3964.9207015860097;4341.544102993354;4379.562043795595;802.4291901040393;914.2366124286162;922.5802259971697;959.4818797849223;977.2524019125375;19071.237150513825;22459.101635934705;22780;22957.426064348525;23066.423339416648;874.4884242717955;953.2122558695474;969.5443141723379;976.1312431935582;978.2369751593768;9257.814843703089;11329;11421.714457138638;11555.480003399867;11914.927656510956;526562;667857;801975;806300;817831;119978;161300.19349903177;165027;165848;166579;1176.505867535637;1226.7362517058875;1239.6776838022179;1239.702471406856;1271.434211775751;19171.88362414087;20054.89972550128;20060.896650683768;20079.899600501903;20092.89953550223;64664;64831.02753458796;64847.67576162089;64900.35099648943;66375;79196.20803791888;89787;90669.54665226632;92337;94609;11419.200655954004;11526.1931664786;11965.581204657796;12007.75984480288;12017.819732703842;13197;13199.538016169388;13308.334583270991;13399.196048236747;13565.38955746974;9612.375195612263;10380.688579342632;10709.821919588994;11051.447427628425;11271.830922536004;9492.620295188115;10387.324823886423;10537.209709271989;10678.519466623866;10809.243352965532;11637.47631356608;11697.356645384585;11890.16768826174;11921;11993.280403175837;11647.30116193031;11653.417329133339;11818.290902545874;11984.40077996114;12018.158728888893;8977.685780997634;9203.907960920305;9280.675176368795;11783.175177737476;11856.051515878882 09:53:19 INFO - PROCESS | 1638 | |3;mootools.html;1590.3320605345755;1604.6469776649133;1662.8835981481293;1673.364121633779;1689.054129687375;10828.78342433152;11314.038306743936;11443.427828608577;11455.427228638555;11791.70520736982;11352.943235283832;11497.195196336259;11520.193586448951;11557.711057223552;11606.013488853436;2518.987405062972;2563.205406324036;2572.9742702573017;2576.549103906809;2577.1624222127884;2164.5454454564483;2189.3760278320683;2493.987530062347;2543.9109631162914;2544.9236522904343;2496.3384703053707;2498.088197807796;2512.359348366164;2544.9618255726177;2556.8465892046443;2450.791682706972;2488.1540276305964;2493.326801763523;2499.737527559598;2513.8743062846875;2458.3362492127026;2480.8015358771436;2485.962710559343;2495.388629785696;2499.5875680512695;11669.183157179;11725;11740.002099821526;11756;11774.175807693466;6044.607100538495;6126.877462450726;6195.81412557624;6203.348648391899;6209.59637623553;3159.8578063987175;3519.5248641433336;3611.693006094485;3674.136577904217;3752.699784017272;1260.7730608490467;1273.8662440443804;1316.9012324075732;1326.5025615394227;1334;14014.22921739304;14456.494022709154;14899.404023838924;15843.12862792546;15949.681006379924;4851.2723091535845;4866.659333846596;4972.4033116025585;4994.175960966472;5013.849584512471;12035.338056406981;12129.5148194073;12137.271763694207;12166.270023798601;12171.0871684624;6071.9696401518595;6077.392260773887;6079.300880398722;6100.664463454553;6112.083187521907;5443.673379597237;5539;5549.056660367747;5579.721013949286;5676.545876329884;4047.8095376633337;4117.21772863155;4132.772697501667;4150.149219410014;4174.707770456039;1099.4612639806596;1104.972375690598;1105.983410248847;1117.8882111788755;1120.5573798349606;940.3887473142375;964.5611246882652;978.9510524473748;989.3717489394287;1018.5365658625308;6620.933790662128;6661.16735408074;6688;6845.178578570504;6933.168019837652;990.762217067913;995.929375920888;997.8054279415444;1014.6144465103362;1029.444100185906;4142.813573389205;4199.958000419957;4264.513334694074;4292.59220374064;4326.480822301344;12359.62921112368;13833.239171845646;14280.786133178795;14427.278636068364;14611.634709132144;14279.071860329046;14309.427622894967;14347.92826035863;14356.712865742627;14394.136351818925;7863.449558530962;10218.846717299246;10408.47957602117;10617.734556636145;10641.946790266;6242.375762423721;6344.429001389897;6629.171353580802;6770.661466926733;6939.236683964659;11335.829962550404;12604.369781511072;12711.80932286035;12715.427805748579;12751.043671724472;12903.870961290268;12973;13035.608931732064;13040.021998350352;13162.40769165409;9565.808683826424;9621;9703.369280996712;9753.122219000325;9781.923988361132;10560.894391055992;10639.521221544894;10656.200784941315;10709.464526773474;10720.035196832323;9043.23132533742;9082.228010619172;9119.407238529475;9173.35786494939;9243.445393276155;8868.565300285914;8969.910300897169;9029.63881444735;9046.592903319235;9161.358704890594;8175.918240817754;8191.795205119682;8221.588920554068;8257.79355516117;8312.625931833203;5868.618539794899;6026.689197584588;6110.938890611214;6132.938670613236;6213.565050446426;5866.911996319973;5997.790077347272;6096.634201947897;6110.572259941761;6156.26124865019 09:53:19 INFO - PROCESS | 1638 | |4;prototype.html;329.46626465126496;375.5230856811849;394.65664871561745;401.22763679916153;402.31606269342126;4521;5220.321358223426;5402.459754024596;5504.3394792624895;5558.082916318813;4631.406412409045;4762.880927976802;4793.424789025313;5157.097507936105;5326.201069839526;3775.8678446254385;4167.083241686839;4615.630749540029;4636.536346365353;4648.604868586174;3972.55001924297;4139.172165566883;4411.750007496168;4508.82770479676;4673.485916549177;4050;4544.500104988449;4586.862394128165;4593.565410332529;4603.3785438965815;4167.978845182919;4549.294859296814;4619.083080521579;4652.906941861161;4676.392069031004;4144.818726662887;4369.208624463955;4379.934300985487;4395.670324725658;4408.977955110236;4674.649401294882;4917.606591472673;5138.126518491828;5283.233931080011;5336.626436149471;4548.43079137697;4617.445906491209;5111.1566591512355;5447.727613619343;5449.828286918292;4942.851714448537;5218.347706536683;5454.127339625641;5689.7439615217;5713.171590119451;3892.3966785148064;4692.290025263871;4782.41319010742;4951.430585482643;4987.501249875019;5396.757145928404;5647.576431767593;5848.8651642150735;5853.5902486825935;5894.882102357972;4704.3649107370065;5019.749012549358;5074.898502029939;5087.09234037236;5090.796368145307;4450;4998.850034498971;5108.412532558729;5154.845354639367;5179.015986962465;4375.431193944767;4676;4785.784639691222;4987.775550100254;5403.648762830403;5098.7368084426;5119.411267704188;5188.740562971836;5361.758720857571;5777.422257774189;1422.8008078868966;1443.2495102546827;1645.3254165791968;1657.7596248543894;1672.3227093027344;1112.6995711157942;1114.3091283404342;1140.9446262207425;1234.975300493985;1243.4466662335174;4812.759362031885;4954.5540901319055;5042.974785126051;5215.58275337972;5485.149801780731;5422.945770542323;5441.891162176814;5533.335999680065;6105.725242364104;6190.41191086846;5153.587712982952;5216.739163041832;5400.30268067185;5442.605253688364;5452.28018826876;2982.686817884134;3042.034016833614;3063.463893818617;3077.261457250244;3148.418296996441;5469.108922757355;5546.3200091856115;5657.529042448993;5658.74535645897;6233.470155036782;5455.34535855692;5520.54335820564;5766.88466230673;6199.752009919643;6248.31268560458;5402.594805389612;5501;5682.1248987833915;6187.752489900444;6362.395572420613;5351.438098999704;5564.666120032811;5605.234351179365;5873.3832947541605;6090.482309003784;5597;5667.688277144632;5723.027085395576;5974.29732526228;6007.219061522149;6224.37756224374;6250.749970001149;6262.906056409066;6294.496440284858;6297.811065668037;4697.365855609586;4835.250536166759;4838.983813399225;4848.9757551212015;4858.95141048585;5270.341207349082;5735.025045742317;5886.793962211302;5974.534769833573;6068.271807383142;5048.495150484922;5124.79500819978;5434.864128396822;5768.183022347947;5853.385394533512;4944.426201777162;5643.689597072036;5831.550748685478;5852.244326702057;5916.189809840292;5051.045138593964;5266.420693723764;5356.410794812646;5483.040467918018;5611.352620796515;5333.866653333697;5751.856203594776;5849.678267695318;5877.35349111606;5971.343152253162;4723.740618973035;4899.755012249302;5348.545373643284;5664.518515926193;5702.7718891243885 09:53:19 INFO - PROCESS | 1638 | |5;yui.html;761.5659074327635;778.1634742651651;805.8630032894407;870.4907629037011;917.646706018183;16510.091944943022;17376.218070186842;17445.651086978254;17466.65066698667;17560.47318580443;16153.949993250431;16229.594260143502;16348.019118852877;16361.181940902938;16388.344466221366;959.141568296376;988.0860204311019;988.3378136648463;1024.073213741565;1032.143321043532;1036.528379587286;1036.9992956796689;1039.1790485516433;1039.2777019971122;1039.5893622019298;1086.483920137932;1086.6033897627387;1087.6573879228058;1088.8638920134983;1089.1722291058813;1061.5276202090074;1071.9517621707041;1078.3799315393642;1080.3409919948824;1080.9405482698448;800.8398320335898;800.8558459477292;802.4022103532847;802.6869520886859;803.5178892664413;16609.83390166095;17136.742948855775;17326.133693315285;17340.046297453635;17534;14529.770629481423;14634.707305853823;14646.267686615627;14834.258287085711;14924.776128358084;9821.508924553744;10779.892201078046;11891.345975971319;12183.939080304543;12710.555130570385;3546.5212196353677;3865.4588357629955;3877.3020856245867;3891.3384724596604;3896.980515097406;106296;106980.39529406988;109642.3553646696;109806;110026.89973100327;11256.774864502664;11309.547618095348;11336.6032188873;11478.081753459703;11503.277067484229;23330.183443579393;23709.17017904365;23715.932783024462;23720;23783.286501404986;8068;8101.675932962733;8133.674653013813;8190.959045204736;8333.041700204432;4593.54064593538;4636.304554316879;4656.138614356355;4672.813087476463;4720.126776546282;3450.706689931384;3491.30173965208;3538.8938331850086;3547.7693949893173;3574.1779390740276;639.4245179338633;644.0339490763854;649.5842660697158;649.6232185332495;658.1871388834785;670.3531092495765;685.7874059041714;698.3575110897884;716.298027932629;734.3904559215838;10645.946770266253;10654.414007229521;10903.945480272549;10906.745724241813;10942;698.9196242432083;725.354434553248;739.9778006659809;744.6165224909176;750.3159486919201;5163.173892177234;5165.9225111623355;5278.947210527922;5282.867928301746;5290.418054014055;90178;91434;91623.70940726402;92089;92317;57396.852062958504;60989;74823;82533;85449;58163.70918145382;73678;75939.24060759322;77138;77218;7850.862537591061;10215;11172.329660220414;11535.307881527135;11582.6525204244;48718.538443845326;49261.261081083096;49338.50661493483;50644.98710025705;52535.73732131468;59028.704856475444;65489.01766473602;66146.33853661401;66346.66826666029;66378.66810665916;13228.537001204912;13307.201567905957;13329.133606315558;13401.86598134006;13657.180569165881;14984.565062858344;15003.099814011195;15157.696846062796;15476.148811815458;15485.303161357991;13707.520236791665;13929.23389213603;14120.082194657314;14122.50571230002;14209.857901420854;13827.654308642364;13973.371198295898;14220;14483.565493035227;14821.629459263604;12441.440135193758;13249.933750331187;14370.928145359207;14398.784018239525;14554.126752394466;10229.181665466596;10273.383596984206;10312.27814053039;10478.947605261925;10675.145988321072;10093.173114750996;10481.213908956706;11329.376884271443;11576.36330001858;11579.5947141853 09:53:19 INFO - PROCESS | 1638 | __end_tp_report 09:53:19 INFO - PROCESS | 1638 | __start_cc_report 09:53:19 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,395 09:53:19 INFO - PROCESS | 1638 | __end_cc_report 09:53:19 INFO - PROCESS | 1638 | __startTimestamp1495731199809__endTimestamp 09:53:19 INFO - PROCESS | 1638 | 09:53:19 INFO - PROCESS | 1638 | ------- Summary: start ------- 09:53:19 INFO - PROCESS | 1638 | Number of tests: 6 09:53:19 INFO - PROCESS | 1638 | 09:53:19 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:6076.06 Median:6281.15 stddev:723.02 (11.5%) stddev-sans-first:697.31 09:53:19 INFO - PROCESS | 1638 | Values: 3539.3 3544.2 3579.1 3579.9 3620.1 5996.5 6052.1 6061.5 6094.8 6096.2 4533.0 5785.9 6109.9 6132.4 6149.8 5459.5 5504.4 5532.7 5534.2 5684.2 5592.4 5594.8 5612.4 5615.2 5619.5 4704.4 5328.3 5412.4 5610.0 5611.8 5550.7 5554.6 5566.0 5584.2 5588.5 4697.4 5427.3 5491.1 5547.0 5588.5 5043.4 5936.8 6204.5 6223.5 6223.5 6394.3 6417.9 6443.3 6466.8 6579.7 6708.8 6719.4 6727.0 6731.8 6795.2 6195.8 6218.7 6266.2 6281.2 6295.4 5364.9 6543.9 6569.6 6762.5 6829.9 6513.6 6527.3 6534.9 6539.2 6569.4 6645.4 6654.7 6690.1 6717.3 6721.7 6215.0 6330.3 6560.5 6609.7 6616.2 6279.4 6486.7 6504.0 6628.4 6629.0 4901.6 5207.6 5238.1 5268.9 5272.2 4461.4 4664.2 4667.3 4668.6 4713.0 5058.4 6113.4 6256.0 6268.7 6325.1 6510.6 6580.1 6634.5 6639.9 6674.1 6246.4 6259.2 6259.9 6261.9 6273.7 5580.9 5829.7 5836.4 5844.2 5893.4 6717.1 6746.8 6753.7 6826.4 6848.6 6713.9 6745.0 6746.1 6757.2 6770.3 6795.5 6871.5 6918.8 6938.1 6952.8 6308.2 6329.5 6465.5 6485.2 6512.6 6670.3 6672.8 6674.7 6680.1 6692.9 6435.6 6445.6 6469.8 6484.6 6522.8 6229.9 6279.1 6309.5 6322.7 6370.3 6219.5 6294.6 6311.2 6321.4 6323.6 6404.2 6460.5 6466.7 6478.0 6482.4 6108.2 6178.6 6244.7 6248.0 6332.2 09:53:19 INFO - PROCESS | 1638 | 09:53:19 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:49604.31 Median:172892.42 stddev:92431.55 (53.5%) stddev-sans-first:92618.12 09:53:19 INFO - PROCESS | 1638 | Values: 769.4 823.2 842.8 982.0 982.5 39564.8 40342.2 42099.0 42178.8 42269.4 39681.2 39866.8 39906.8 39919.0 40005.6 8163.0 8304.2 10104.5 11049.9 11390.8 9828.5 10519.3 11074.9 11280.6 11311.9 9546.9 9548.7 11232.7 11259.2 11273.9 11249.3 11252.2 11263.3 11275.7 11292.5 11027.9 11060.5 11061.3 11066.1 11080.2 29865.6 33528.0 33666.0 33740.0 33816.5 3918.3 3948.3 3980.6 3995.0 4061.9 26075.9 26282.7 26324.6 27682.9 27883.0 3005.1 3242.7 3289.3 3308.6 3315.4 307042.5 314342.0 338077.3 345682.0 346408.0 44214.7 44414.8 44497.0 44580.0 44702.3 45706.8 45736.0 45895.8 46040.8 46064.8 14059.2 14174.0 14230.9 14235.4 14244.9 12466.6 12474.3 12490.6 12495.2 12516.9 12003.5 12023.4 12028.2 12042.7 12171.8 5599.7 5674.4 5730.5 5735.7 5747.9 967.2 985.8 1104.5 1188.4 1196.7 26312.6 26513.5 27046.0 27703.3 27906.0 1065.5 1238.3 1241.3 1263.7 1267.3 14108.4 14125.9 14210.3 14446.0 14481.8 371307.1 396387.0 397964.0 398859.0 399224.0 56163.0 56529.4 56563.7 56603.4 57823.0 1461.9 1470.8 1506.6 1506.7 1519.7 24651.0 25226.0 26948.3 26951.5 27104.6 44240.6 44299.1 44342.6 44378.0 44498.3 324159.0 325147.0 325522.0 325649.0 326500.0 24444.3 27307.7 30230.0 31076.0 31106.7 41042.0 44748.3 44893.1 45607.8 45809.5 26700.9 30653.7 30800.4 31394.9 31442.8 30634.8 30753.8 30872.4 31634.0 31802.7 37069.8 37080.6 37678.6 37699.0 37805.4 30543.7 30914.5 31860.5 31962.5 32124.5 20625.9 24268.3 24933.0 24979.5 25162.1 09:53:19 INFO - PROCESS | 1638 | 09:53:19 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:42883.72 Median:26855.83 stddev:122796.43 (457.2%) stddev-sans-first:123099.51 09:53:19 INFO - PROCESS | 1638 | Values: 417.1 429.7 435.1 440.1 443.0 17692.2 21025.7 21100.6 21660.7 21784.5 19964.1 20953.2 22320.3 22689.8 22794.0 6080.9 6853.9 7059.7 7080.3 7115.4 6219.8 6343.4 6991.1 7047.4 7058.7 6708.0 6981.9 7039.4 7062.2 7070.9 6945.1 6980.3 7008.0 7011.6 7023.8 7031.2 7384.4 7424.2 7429.9 7450.6 26315.0 26837.9 26873.8 27216.6 27669.6 3030.8 3065.1 3066.2 3482.9 3501.5 14355.8 15349.5 15492.1 16987.5 17560.9 2146.4 2168.5 2169.1 2171.2 2218.3 128790.0 163242.0 168869.0 171568.3 173783.0 19752.0 20507.0 20590.8 20735.5 20795.5 31504.8 32021.5 32210.2 32291.4 32400.0 10740.9 10807.7 10953.0 10979.9 11038.7 7619.2 9067.4 9071.5 9097.6 9251.4 9439.7 9750.8 9779.1 9846.0 9904.5 3674.9 3941.6 3964.9 4341.5 4379.6 802.4 914.2 922.6 959.5 977.3 19071.2 22459.1 22780.0 22957.4 23066.4 874.5 953.2 969.5 976.1 978.2 9257.8 11329.0 11421.7 11555.5 11914.9 526562.0 667857.0 801975.0 806300.0 817831.0 119978.0 161300.2 165027.0 165848.0 166579.0 1176.5 1226.7 1239.7 1239.7 1271.4 19171.9 20054.9 20060.9 20079.9 20092.9 64664.0 64831.0 64847.7 64900.4 66375.0 79196.2 89787.0 90669.5 92337.0 94609.0 11419.2 11526.2 11965.6 12007.8 12017.8 13197.0 13199.5 13308.3 13399.2 13565.4 9612.4 10380.7 10709.8 11051.4 11271.8 9492.6 10387.3 10537.2 10678.5 10809.2 11637.5 11697.4 11890.2 11921.0 11993.3 11647.3 11653.4 11818.3 11984.4 12018.2 8977.7 9203.9 9280.7 11783.2 11856.1 09:53:19 INFO - PROCESS | 1638 | 09:53:19 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:6964.33 Median:2544.94 stddev:4324.88 (169.9%) stddev-sans-first:4318.16 09:53:19 INFO - PROCESS | 1638 | Values: 1590.3 1604.6 1662.9 1673.4 1689.1 10828.8 11314.0 11443.4 11455.4 11791.7 11352.9 11497.2 11520.2 11557.7 11606.0 2519.0 2563.2 2573.0 2576.5 2577.2 2164.5 2189.4 2494.0 2543.9 2544.9 2496.3 2498.1 2512.4 2545.0 2556.8 2450.8 2488.2 2493.3 2499.7 2513.9 2458.3 2480.8 2486.0 2495.4 2499.6 11669.2 11725.0 11740.0 11756.0 11774.2 6044.6 6126.9 6195.8 6203.3 6209.6 3159.9 3519.5 3611.7 3674.1 3752.7 1260.8 1273.9 1316.9 1326.5 1334.0 14014.2 14456.5 14899.4 15843.1 15949.7 4851.3 4866.7 4972.4 4994.2 5013.8 12035.3 12129.5 12137.3 12166.3 12171.1 6072.0 6077.4 6079.3 6100.7 6112.1 5443.7 5539.0 5549.1 5579.7 5676.5 4047.8 4117.2 4132.8 4150.1 4174.7 1099.5 1105.0 1106.0 1117.9 1120.6 940.4 964.6 979.0 989.4 1018.5 6620.9 6661.2 6688.0 6845.2 6933.2 990.8 995.9 997.8 1014.6 1029.4 4142.8 4200.0 4264.5 4292.6 4326.5 12359.6 13833.2 14280.8 14427.3 14611.6 14279.1 14309.4 14347.9 14356.7 14394.1 7863.4 10218.8 10408.5 10617.7 10641.9 6242.4 6344.4 6629.2 6770.7 6939.2 11335.8 12604.4 12711.8 12715.4 12751.0 12903.9 12973.0 13035.6 13040.0 13162.4 9565.8 9621.0 9703.4 9753.1 9781.9 10560.9 10639.5 10656.2 10709.5 10720.0 9043.2 9082.2 9119.4 9173.4 9243.4 8868.6 8969.9 9029.6 9046.6 9161.4 8175.9 8191.8 8221.6 8257.8 8312.6 5868.6 6026.7 6110.9 6132.9 6213.6 5866.9 5997.8 6096.6 6110.6 6156.3 09:53:19 INFO - PROCESS | 1638 | 09:53:19 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4828.22 Median:5155.97 stddev:1326.43 (25.7%) stddev-sans-first:1286.45 09:53:19 INFO - PROCESS | 1638 | Values: 329.5 375.5 394.7 401.2 402.3 4521.0 5220.3 5402.5 5504.3 5558.1 4631.4 4762.9 4793.4 5157.1 5326.2 3775.9 4167.1 4615.6 4636.5 4648.6 3972.6 4139.2 4411.8 4508.8 4673.5 4050.0 4544.5 4586.9 4593.6 4603.4 4168.0 4549.3 4619.1 4652.9 4676.4 4144.8 4369.2 4379.9 4395.7 4409.0 4674.6 4917.6 5138.1 5283.2 5336.6 4548.4 4617.4 5111.2 5447.7 5449.8 4942.9 5218.3 5454.1 5689.7 5713.2 3892.4 4692.3 4782.4 4951.4 4987.5 5396.8 5647.6 5848.9 5853.6 5894.9 4704.4 5019.7 5074.9 5087.1 5090.8 4450.0 4998.9 5108.4 5154.8 5179.0 4375.4 4676.0 4785.8 4987.8 5403.6 5098.7 5119.4 5188.7 5361.8 5777.4 1422.8 1443.2 1645.3 1657.8 1672.3 1112.7 1114.3 1140.9 1235.0 1243.4 4812.8 4954.6 5043.0 5215.6 5485.1 5422.9 5441.9 5533.3 6105.7 6190.4 5153.6 5216.7 5400.3 5442.6 5452.3 2982.7 3042.0 3063.5 3077.3 3148.4 5469.1 5546.3 5657.5 5658.7 6233.5 5455.3 5520.5 5766.9 6199.8 6248.3 5402.6 5501.0 5682.1 6187.8 6362.4 5351.4 5564.7 5605.2 5873.4 6090.5 5597.0 5667.7 5723.0 5974.3 6007.2 6224.4 6250.7 6262.9 6294.5 6297.8 4697.4 4835.3 4839.0 4849.0 4859.0 5270.3 5735.0 5886.8 5974.5 6068.3 5048.5 5124.8 5434.9 5768.2 5853.4 4944.4 5643.7 5831.6 5852.2 5916.2 5051.0 5266.4 5356.4 5483.0 5611.4 5333.9 5751.9 5849.7 5877.4 5971.3 4723.7 4899.8 5348.5 5664.5 5702.8 09:53:19 INFO - PROCESS | 1638 | 09:53:19 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:19949.49 Median:17410.93 stddev:27304.97 (156.8%) stddev-sans-first:27343.56 09:53:19 INFO - PROCESS | 1638 | Values: 761.6 778.2 805.9 870.5 917.6 16510.1 17376.2 17445.7 17466.7 17560.5 16153.9 16229.6 16348.0 16361.2 16388.3 959.1 988.1 988.3 1024.1 1032.1 1036.5 1037.0 1039.2 1039.3 1039.6 1086.5 1086.6 1087.7 1088.9 1089.2 1061.5 1072.0 1078.4 1080.3 1080.9 800.8 800.9 802.4 802.7 803.5 16609.8 17136.7 17326.1 17340.0 17534.0 14529.8 14634.7 14646.3 14834.3 14924.8 9821.5 10779.9 11891.3 12183.9 12710.6 3546.5 3865.5 3877.3 3891.3 3897.0 106296.0 106980.4 109642.4 109806.0 110026.9 11256.8 11309.5 11336.6 11478.1 11503.3 23330.2 23709.2 23715.9 23720.0 23783.3 8068.0 8101.7 8133.7 8191.0 8333.0 4593.5 4636.3 4656.1 4672.8 4720.1 3450.7 3491.3 3538.9 3547.8 3574.2 639.4 644.0 649.6 649.6 658.2 670.4 685.8 698.4 716.3 734.4 10645.9 10654.4 10903.9 10906.7 10942.0 698.9 725.4 740.0 744.6 750.3 5163.2 5165.9 5278.9 5282.9 5290.4 90178.0 91434.0 91623.7 92089.0 92317.0 57396.9 60989.0 74823.0 82533.0 85449.0 58163.7 73678.0 75939.2 77138.0 77218.0 7850.9 10215.0 11172.3 11535.3 11582.7 48718.5 49261.3 49338.5 50645.0 52535.7 59028.7 65489.0 66146.3 66346.7 66378.7 13228.5 13307.2 13329.1 13401.9 13657.2 14984.6 15003.1 15157.7 15476.1 15485.3 13707.5 13929.2 14120.1 14122.5 14209.9 13827.7 13973.4 14220.0 14483.6 14821.6 12441.4 13249.9 14370.9 14398.8 14554.1 10229.2 10273.4 10312.3 10478.9 10675.1 10093.2 10481.2 11329.4 11576.4 11579.6 09:53:19 INFO - PROCESS | 1638 | -------- Summary: end -------- 09:53:19 INFO - PROCESS | 1638 | 09:53:20 INFO - TEST-INFO | 1638: exit 0 09:53:20 INFO - TEST-OK | dromaeo_css | took 1115377ms 09:53:20 INFO - TEST-START | kraken 09:53:20 INFO - operating with platform_type : mac_ 09:53:20 INFO - Initialising browser for kraken test... 09:53:20 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp4keVxW/profile http://localhost:49226/getInfo.html) 09:53:22 INFO - PROCESS | 1649 | 2017-05-25 09:53:22.820 plugin-container[1651:10279] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 09:53:22 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:22 INFO - PROCESS | 1649 | 2017-05-25 09:53:22.821 plugin-container[1651:10279] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x880f, name = 'com.apple.CFPasteboardClient' 09:53:22 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:22 INFO - PROCESS | 1649 | 2017-05-25 09:53:22.821 plugin-container[1651:10279] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:53:22 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 09:53:22 INFO - PROCESS | 1649 | colorDepth:24 09:53:22 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 09:53:22 INFO - PROCESS | 1649 | __metrics 09:53:22 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 09:53:23 INFO - TEST-INFO | 1649: exit 0 09:53:23 INFO - Browser initialized. 09:53:23 INFO - Running cycle 1/1 for kraken test... 09:53:23 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/T/tmp4keVxW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:53:25 INFO - PROCESS | 1652 | 2017-05-25 09:53:25.796 plugin-container[1653:10405] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x453f, name = 'com.apple.tsm.portname' 09:53:25 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:25 INFO - PROCESS | 1652 | 2017-05-25 09:53:25.797 plugin-container[1653:10405] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8547, name = 'com.apple.CFPasteboardClient' 09:53:25 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:53:25 INFO - PROCESS | 1652 | 2017-05-25 09:53:25.797 plugin-container[1653:10405] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:53:35 INFO - PROCESS | 1652 | RSS: Main: 174522368 09:53:35 INFO - PROCESS | 1652 | 09:53:38 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 09:53:39 INFO - PROCESS | 1652 | RSS: Main: 188026880 09:53:39 INFO - PROCESS | 1652 | 09:53:42 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 09:53:42 INFO - PROCESS | 1652 | RSS: Main: 195973120 09:53:42 INFO - PROCESS | 1652 | 09:53:45 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 09:53:46 INFO - PROCESS | 1652 | RSS: Main: 199577600 09:53:46 INFO - PROCESS | 1652 | 09:53:48 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 09:53:49 INFO - PROCESS | 1652 | RSS: Main: 200052736 09:53:49 INFO - PROCESS | 1652 | 09:53:51 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:53:51 INFO - PROCESS | 1652 | RSS: Main: 200101888 09:53:51 INFO - PROCESS | 1652 | 09:53:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 09:53:55 INFO - PROCESS | 1652 | RSS: Main: 203780096 09:53:55 INFO - PROCESS | 1652 | 09:53:58 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 09:53:59 INFO - PROCESS | 1652 | RSS: Main: 204197888 09:53:59 INFO - PROCESS | 1652 | 09:54:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 09:54:03 INFO - PROCESS | 1652 | RSS: Main: 204562432 09:54:03 INFO - PROCESS | 1652 | 09:54:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:54:05 INFO - PROCESS | 1652 | RSS: Main: 204062720 09:54:05 INFO - PROCESS | 1652 | 09:54:07 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:54:08 INFO - PROCESS | 1652 | RSS: Main: 204115968 09:54:08 INFO - PROCESS | 1652 | 09:54:10 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:54:11 INFO - PROCESS | 1652 | RSS: Main: 204128256 09:54:11 INFO - PROCESS | 1652 | 09:54:14 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:54:14 INFO - PROCESS | 1652 | RSS: Main: 204460032 09:54:14 INFO - PROCESS | 1652 | 09:54:17 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:54:18 INFO - PROCESS | 1652 | RSS: Main: 204148736 09:54:18 INFO - PROCESS | 1652 | 09:54:19 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 09:54:20 INFO - PROCESS | 1652 | RSS: Main: 206098432 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | __start_tp_report 09:54:20 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 09:54:20 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 09:54:20 INFO - PROCESS | 1652 | |i|pagename|runs| 09:54:20 INFO - PROCESS | 1652 | |0;ai-astar;108;120;119;120;119;120;120;120;119;119 09:54:20 INFO - PROCESS | 1652 | |1;audio-beat-detection;141;108;110;151;138;141;136;109;129;128 09:54:20 INFO - PROCESS | 1652 | |2;audio-dft;205;202;201;198;200;199;199;201;206;200 09:54:20 INFO - PROCESS | 1652 | |3;audio-fft;82;58;59;89;61;83;77;60;85;71 09:54:20 INFO - PROCESS | 1652 | |4;audio-oscillator;84;69;85;74;71;69;80;94;98;109 09:54:20 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;125;133;90;91;101;77;134;120;90;98 09:54:20 INFO - PROCESS | 1652 | |6;imaging-darkroom;97;113;152;104;97;96;126;113;152;150 09:54:20 INFO - PROCESS | 1652 | |7;imaging-desaturate;92;110;140;132;143;120;92;140;129;91 09:54:20 INFO - PROCESS | 1652 | |8;json-parse-financial;66;67;65;68;70;70;70;68;69;69 09:54:20 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;59;66;63;63;79;51;57;67;80;87 09:54:20 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;85;82;84;85;83;84;83;88;85;84 09:54:20 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;137;137;151;128;146;142;141;145;187;137 09:54:20 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;162;166;166;163;162;175;167;165;168;160 09:54:20 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;66;64;65;64;72;65;64;62;64;67 09:54:20 INFO - PROCESS | 1652 | __end_tp_report 09:54:20 INFO - PROCESS | 1652 | __start_cc_report 09:54:20 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,1006 09:54:20 INFO - PROCESS | 1652 | __end_cc_report 09:54:20 INFO - PROCESS | 1652 | __startTimestamp1495731260149__endTimestamp 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | ------- Summary: start ------- 09:54:20 INFO - PROCESS | 1652 | Number of tests: 14 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:118.40 Median:120.00 stddev:3.69 (3.1%) stddev-sans-first:0.53 09:54:20 INFO - PROCESS | 1652 | Values: 108.0 120.0 119.0 120.0 119.0 120.0 120.0 120.0 119.0 119.0 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:129.10 Median:137.00 stddev:15.29 (11.2%) stddev-sans-first:15.60 09:54:20 INFO - PROCESS | 1652 | Values: 141.0 108.0 110.0 151.0 138.0 141.0 136.0 109.0 129.0 128.0 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:201.10 Median:201.00 stddev:2.60 (1.3%) stddev-sans-first:2.35 09:54:20 INFO - PROCESS | 1652 | Values: 205.0 202.0 201.0 198.0 200.0 199.0 199.0 201.0 206.0 200.0 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:72.50 Median:79.50 stddev:12.17 (15.3%) stddev-sans-first:12.41 09:54:20 INFO - PROCESS | 1652 | Values: 82.0 58.0 59.0 89.0 61.0 83.0 77.0 60.0 85.0 71.0 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:83.30 Median:82.00 stddev:13.55 (16.5%) stddev-sans-first:14.37 09:54:20 INFO - PROCESS | 1652 | Values: 84.0 69.0 85.0 74.0 71.0 69.0 80.0 94.0 98.0 109.0 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:105.90 Median:83.50 stddev:20.38 (24.4%) stddev-sans-first:20.41 09:54:20 INFO - PROCESS | 1652 | Values: 125.0 133.0 90.0 91.0 101.0 77.0 134.0 120.0 90.0 98.0 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:120.00 Median:152.00 stddev:23.50 (15.5%) stddev-sans-first:23.41 09:54:20 INFO - PROCESS | 1652 | Values: 97.0 113.0 152.0 104.0 97.0 96.0 126.0 113.0 152.0 150.0 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:118.90 Median:141.50 stddev:21.22 (15.0%) stddev-sans-first:20.15 09:54:20 INFO - PROCESS | 1652 | Values: 92.0 110.0 140.0 132.0 143.0 120.0 92.0 140.0 129.0 91.0 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:68.20 Median:69.00 stddev:1.75 (2.5%) stddev-sans-first:1.67 09:54:20 INFO - PROCESS | 1652 | Values: 66.0 67.0 65.0 68.0 70.0 70.0 70.0 68.0 69.0 69.0 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:67.20 Median:66.50 stddev:11.38 (17.1%) stddev-sans-first:11.68 09:54:20 INFO - PROCESS | 1652 | Values: 59.0 66.0 63.0 63.0 79.0 51.0 57.0 67.0 80.0 87.0 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:84.50 stddev:1.64 (1.9%) stddev-sans-first:1.72 09:54:20 INFO - PROCESS | 1652 | Values: 85.0 82.0 84.0 85.0 83.0 84.0 83.0 88.0 85.0 84.0 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:143.50 stddev:16.01 (11.2%) stddev-sans-first:16.71 09:54:20 INFO - PROCESS | 1652 | Values: 137.0 137.0 151.0 128.0 146.0 142.0 141.0 145.0 187.0 137.0 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.40 Median:166.00 stddev:4.22 (2.5%) stddev-sans-first:4.29 09:54:20 INFO - PROCESS | 1652 | Values: 162.0 166.0 166.0 163.0 162.0 175.0 167.0 165.0 168.0 160.0 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:65.00 stddev:2.71 (4.2%) stddev-sans-first:2.86 09:54:20 INFO - PROCESS | 1652 | Values: 66.0 64.0 65.0 64.0 72.0 65.0 64.0 62.0 64.0 67.0 09:54:20 INFO - PROCESS | 1652 | -------- Summary: end -------- 09:54:20 INFO - PROCESS | 1652 | 09:54:20 INFO - TEST-INFO | 1652: exit 0 09:54:20 INFO - TEST-OK | kraken | took 60577ms 09:54:20 INFO - SUITE-END | took 1175s 09:54:21 INFO - Completed test suite (00:19:36) 09:54:21 INFO - javascript benchmark 09:54:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7906.533079070659, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3539.292141571686, 3544.184837487378, 3579.141006158523, 3579.9105022374447, 3620.0587847159745, 5996.52027837773, 6052.122442245883, 6061.545384096199, 6094.786682466114, 6096.237970253718, 4533.0, 5785.942140578593, 6109.938900610992, 6132.417420345071, 6149.815505534841, 5459.481349271813, 5504.422035686255, 5532.723363831812, 5534.169874518814, 5684.204211410406, 5592.4407559243955, 5594.832155035356, 5612.41069687683, 5615.157726341061, 5619.522340601054, 4704.376989938482, 5328.254044433782, 5412.404635490093, 5609.971950140243, 5611.7755289788565, 5550.72246387679, 5554.583406244527, 5565.972170139144, 5584.246126772874, 5588.524975377098, 4697.436307643105, 5427.3215848019, 5491.148871924858, 5547.001539722848, 5588.469095435927, 5043.36957880265, 5936.762529498815, 6204.534659900525, 6223.471004964538, 6223.533235007348, 6394.328595497499, 6417.903731444032, 6443.3234510376205, 6466.7736629218225, 6579.736810527573, 6708.7651932182625, 6719.395254427125, 6727.024581435664, 6731.798046058626, 6795.1845778506895, 6195.783147589903, 6218.657973811484, 6266.185395898504, 6281.152044473984, 6295.433410992987, 5364.892702145935, 6543.9345606544275, 6569.638669873203, 6762.492813039042, 6829.863402732017, 6513.641749703717, 6527.314631963575, 6534.869302614016, 6539.247986481616, 6569.375909288706, 6645.401913827779, 6654.700538475683, 6690.063391125246, 6717.294684058202, 6721.66391680414, 6215.036669316263, 6330.272018717907, 6560.475161987125, 6609.6695165241545, 6616.206055273302, 6279.434850863353, 6486.67566621667, 6503.967480162569, 6628.370304821035, 6629.038789375513, 4901.632377571696, 5207.635465517377, 5238.057149713012, 5268.894622107537, 5272.20916862466, 4461.353103799996, 4664.160451118763, 4667.276572131257, 4668.649851261169, 4713.033828065239, 5058.4435712071645, 6113.354685976905, 6255.967765318672, 6268.717907694165, 6325.114483972247, 6510.576812507172, 6580.078788969548, 6634.502412319095, 6639.933600664028, 6674.132362792806, 6246.4378205960775, 6259.217597800275, 6259.937400625936, 6261.8747625047245, 6273.7176827042895, 5580.888382232332, 5829.7376618052285, 5836.387179346192, 5844.1818145459665, 5893.410658934073, 6717.1267735194115, 6746.763863264664, 6753.696083676148, 6826.385625293748, 6848.623325717133, 6713.899291510534, 6744.966275168593, 6746.089277947611, 6757.22291936414, 6770.32296770319, 6795.490338224752, 6871.484638652021, 6918.827029324307, 6938.098047253825, 6952.826179345355, 6308.243010838729, 6329.5252856036905, 6465.515086368635, 6485.156929599122, 6512.641804700787, 6670.299618540177, 6672.766453174116, 6674.733010679713, 6680.098186744727, 6692.866142677217, 6435.646039467874, 6445.6454894981225, 6469.77355792545, 6484.610923344614, 6522.804315870531, 6229.906551401823, 6279.058141278845, 6309.526785491198, 6322.715477803602, 6370.331115232834, 6219.471344935731, 6294.590851594632, 6311.211098612674, 6321.431071203614, 6323.588966717149, 6404.231492220963, 6460.483161347175, 6466.676666166767, 6478.028295755675, 6482.448991835747, 6108.22065524731, 6178.62928224308, 6244.68776561161, 6248.0, 6332.208473940757], "value": 6029.89488057438, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [769.3883362726632, 823.213830791594, 842.7935155886806, 982.0130768577587, 982.469466488096, 39564.80217598911, 40342.1931561369, 42099.0, 42178.7891060545, 42269.36595951056, 39681.206375872534, 39866.80066599663, 39906.80279591617, 39919.00202494939, 40005.59994400063, 8162.999999999985, 8304.169583041677, 10104.53484244784, 11049.944750276278, 11390.772184556305, 9828.459434731087, 10519.263651544396, 11074.944625276863, 11280.605178818743, 11311.886881131166, 9546.904530954705, 9548.665796697116, 11232.66302010941, 11259.155563332743, 11273.887261127407, 11249.325040497597, 11252.212345135844, 11263.324200547951, 11275.661730148067, 11292.548298068068, 11027.889721102767, 11060.502277397536, 11061.336319820837, 11066.059384952288, 11080.224384293102, 29865.55201671977, 33528.0, 33666.0, 33739.98780036603, 33816.492752608734, 3918.294706952747, 3948.348522493785, 3980.601939805996, 3994.980025099856, 4061.8578349757913, 26075.869620651963, 26282.73717262822, 26324.60513092305, 27682.861585692142, 27883.0, 3005.0534081764395, 3242.7243684286864, 3289.3092450585405, 3308.602967643874, 3315.436375816117, 307042.4647876769, 314342.0, 338077.3096134528, 345682.0, 346408.0, 44214.67355979326, 44414.77792611016, 44497.0, 44580.0, 44702.32946505805, 45706.771466143124, 45736.0, 45895.770521147846, 46040.7697961508, 46064.84837879013, 14059.226742529054, 14174.0, 14230.928845355915, 14235.43058277678, 14244.85755142456, 12466.626001219978, 12474.251544907334, 12490.625281241577, 12495.187812792139, 12516.937415312865, 12003.519859205533, 12023.398830058637, 12028.21816581937, 12042.698932526582, 12171.817422738666, 5599.740058486808, 5674.375818659944, 5730.484256416859, 5735.71321433935, 5747.885042299131, 967.2133596967932, 985.7979114281555, 1104.5416152296707, 1188.4176753390777, 1196.6768972377408, 26312.605310920353, 26513.46973060528, 27046.0, 27703.30741731473, 27906.0, 1065.4832406282947, 1238.3003602964297, 1241.273854794937, 1263.7236391244908, 1267.3283159925254, 14108.365123569463, 14125.869930405543, 14210.289485525682, 14446.0, 14481.78277325841, 371307.14346428093, 396387.0, 397964.0, 398859.00570496963, 399224.0, 56163.0, 56529.43470565324, 56563.71718141383, 56603.43396566064, 57823.0, 1461.9342129604192, 1470.8455612160783, 1506.623344163959, 1506.7438535449, 1519.7340465418508, 24651.0, 25226.0, 26948.326291842077, 26951.46097078008, 27104.593431098154, 44240.55759442364, 44299.11401772011, 44342.556574433846, 44378.0, 44498.3325250115, 324159.0, 325147.0, 325522.0, 325649.0, 326500.0, 24444.266671999867, 27307.678847297408, 30230.0, 31076.0, 31106.688933110378, 41041.973950651474, 44748.32877506775, 44893.102137956404, 45607.77196113998, 45809.541904580525, 26700.866495667397, 30653.693463065083, 30800.38399231958, 31394.901178458644, 31442.842785785924, 30634.84682576662, 30753.846230768704, 30872.38255234838, 31634.0, 31802.681973180897, 37069.81465092657, 37080.62919370772, 37678.62321376751, 37699.0, 37805.43291850569, 30543.694563054087, 30914.536281955337, 31860.5220921691, 31962.52056219112, 32124.518132227568, 20625.896870515553, 24268.271951841474, 24933.002679893325, 24979.500409992062, 25162.119325823507], "value": 18499.824824264004, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [417.12612077697213, 429.66700806874667, 435.14928315143896, 440.14172363890424, 443.03639583905, 17692.20385082671, 21025.684614730773, 21100.57798844024, 21660.675089873665, 21784.455388615293, 19964.101615427342, 20953.161873525078, 22320.330390088282, 22689.773102268973, 22794.0, 6080.939190608104, 6853.8629227415295, 7059.682314295869, 7080.327368899946, 7115.3951914087365, 6219.782307619234, 6343.365663433666, 6991.126109236346, 7047.43620510358, 7058.717651293942, 6707.98760371889, 6981.930180698178, 7039.366457018868, 7062.223155452896, 7070.9292907070785, 6945.097137372135, 6980.301969802979, 7007.964960175217, 7011.579305241702, 7023.754168604074, 7031.156261248631, 7384.446166537477, 7424.183339832614, 7429.8514029719645, 7450.627468626547, 26315.0, 26837.865810671017, 26873.79067941947, 27216.59175112375, 27669.584956225674, 3030.7878448508613, 3065.080475857234, 3066.2487690515745, 3482.94775578364, 3501.4747787831775, 14355.78466323006, 15349.539513814603, 15492.147931863743, 16987.53796521362, 17560.91219543894, 2146.356093172042, 2168.5120847809276, 2169.0673010605433, 2171.2400659769114, 2218.2901471529117, 128790.0, 163242.0, 168869.0, 171568.28431715773, 173782.99999999875, 19752.0, 20507.0, 20590.794092059186, 20735.481612959495, 20795.480112996993, 31504.842475787933, 32021.519677204862, 32210.19474513156, 32291.35417291641, 32400.0, 10740.892591074145, 10807.67576972692, 10953.0, 10979.945100274606, 11038.724031899106, 7619.1999840016, 9067.410618309788, 9071.546422678839, 9097.590608422637, 9251.352405331561, 9439.67693084303, 9750.751316663755, 9779.070988256104, 9846.0, 9904.504774761233, 3674.9448758268645, 3941.586133455946, 3964.9207015860097, 4341.544102993354, 4379.562043795595, 802.4291901040393, 914.2366124286162, 922.5802259971697, 959.4818797849223, 977.2524019125375, 19071.237150513825, 22459.101635934705, 22780.0, 22957.426064348525, 23066.423339416648, 874.4884242717955, 953.2122558695474, 969.5443141723379, 976.1312431935582, 978.2369751593768, 9257.814843703089, 11329.0, 11421.714457138638, 11555.480003399867, 11914.927656510956, 526562.0, 667857.0, 801975.0, 806300.0, 817831.0, 119978.0, 161300.19349903177, 165027.0, 165848.0, 166579.0, 1176.505867535637, 1226.7362517058875, 1239.6776838022179, 1239.702471406856, 1271.434211775751, 19171.88362414087, 20054.89972550128, 20060.896650683768, 20079.899600501903, 20092.89953550223, 64664.0, 64831.02753458796, 64847.67576162089, 64900.35099648943, 66375.0, 79196.20803791888, 89787.0, 90669.54665226632, 92337.0, 94609.0, 11419.200655954004, 11526.1931664786, 11965.581204657796, 12007.75984480288, 12017.819732703842, 13197.0, 13199.538016169388, 13308.334583270991, 13399.196048236747, 13565.38955746974, 9612.375195612263, 10380.688579342632, 10709.821919588994, 11051.447427628425, 11271.830922536004, 9492.620295188115, 10387.324823886423, 10537.209709271989, 10678.519466623866, 10809.243352965532, 11637.47631356608, 11697.356645384585, 11890.16768826174, 11921.0, 11993.280403175837, 11647.30116193031, 11653.417329133339, 11818.290902545874, 11984.40077996114, 12018.158728888893, 8977.685780997634, 9203.907960920305, 9280.675176368795, 11783.175177737476, 11856.051515878882], "value": 11750.061449150313, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1590.3320605345755, 1604.6469776649133, 1662.8835981481293, 1673.364121633779, 1689.054129687375, 10828.78342433152, 11314.038306743936, 11443.427828608577, 11455.427228638555, 11791.70520736982, 11352.943235283832, 11497.195196336259, 11520.193586448951, 11557.711057223552, 11606.013488853436, 2518.987405062972, 2563.205406324036, 2572.9742702573017, 2576.549103906809, 2577.1624222127884, 2164.5454454564483, 2189.3760278320683, 2493.987530062347, 2543.9109631162914, 2544.9236522904343, 2496.3384703053707, 2498.088197807796, 2512.359348366164, 2544.9618255726177, 2556.8465892046443, 2450.791682706972, 2488.1540276305964, 2493.326801763523, 2499.737527559598, 2513.8743062846875, 2458.3362492127026, 2480.8015358771436, 2485.962710559343, 2495.388629785696, 2499.5875680512695, 11669.183157179, 11725.0, 11740.002099821526, 11756.0, 11774.175807693466, 6044.607100538495, 6126.877462450726, 6195.81412557624, 6203.348648391899, 6209.59637623553, 3159.8578063987175, 3519.5248641433336, 3611.693006094485, 3674.136577904217, 3752.699784017272, 1260.7730608490467, 1273.8662440443804, 1316.9012324075732, 1326.5025615394227, 1334.0, 14014.22921739304, 14456.494022709154, 14899.404023838924, 15843.12862792546, 15949.681006379924, 4851.2723091535845, 4866.659333846596, 4972.4033116025585, 4994.175960966472, 5013.849584512471, 12035.338056406981, 12129.5148194073, 12137.271763694207, 12166.270023798601, 12171.0871684624, 6071.9696401518595, 6077.392260773887, 6079.300880398722, 6100.664463454553, 6112.083187521907, 5443.673379597237, 5539.0, 5549.056660367747, 5579.721013949286, 5676.545876329884, 4047.8095376633337, 4117.21772863155, 4132.772697501667, 4150.149219410014, 4174.707770456039, 1099.4612639806596, 1104.972375690598, 1105.983410248847, 1117.8882111788755, 1120.5573798349606, 940.3887473142375, 964.5611246882652, 978.9510524473748, 989.3717489394287, 1018.5365658625308, 6620.933790662128, 6661.16735408074, 6688.0, 6845.178578570504, 6933.168019837652, 990.762217067913, 995.929375920888, 997.8054279415444, 1014.6144465103362, 1029.444100185906, 4142.813573389205, 4199.958000419957, 4264.513334694074, 4292.59220374064, 4326.480822301344, 12359.62921112368, 13833.239171845646, 14280.786133178795, 14427.278636068364, 14611.634709132144, 14279.071860329046, 14309.427622894967, 14347.92826035863, 14356.712865742627, 14394.136351818925, 7863.449558530962, 10218.846717299246, 10408.47957602117, 10617.734556636145, 10641.946790266, 6242.375762423721, 6344.429001389897, 6629.171353580802, 6770.661466926733, 6939.236683964659, 11335.829962550404, 12604.369781511072, 12711.80932286035, 12715.427805748579, 12751.043671724472, 12903.870961290268, 12973.0, 13035.608931732064, 13040.021998350352, 13162.40769165409, 9565.808683826424, 9621.0, 9703.369280996712, 9753.122219000325, 9781.923988361132, 10560.894391055992, 10639.521221544894, 10656.200784941315, 10709.464526773474, 10720.035196832323, 9043.23132533742, 9082.228010619172, 9119.407238529475, 9173.35786494939, 9243.445393276155, 8868.565300285914, 8969.910300897169, 9029.63881444735, 9046.592903319235, 9161.358704890594, 8175.918240817754, 8191.795205119682, 8221.588920554068, 8257.79355516117, 8312.625931833203, 5868.618539794899, 6026.689197584588, 6110.938890611214, 6132.938670613236, 6213.565050446426, 5866.911996319973, 5997.790077347272, 6096.634201947897, 6110.572259941761, 6156.26124865019], "value": 5331.006913750704, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.46626465126496, 375.5230856811849, 394.65664871561745, 401.22763679916153, 402.31606269342126, 4521.0, 5220.321358223426, 5402.459754024596, 5504.3394792624895, 5558.082916318813, 4631.406412409045, 4762.880927976802, 4793.424789025313, 5157.097507936105, 5326.201069839526, 3775.8678446254385, 4167.083241686839, 4615.630749540029, 4636.536346365353, 4648.604868586174, 3972.55001924297, 4139.172165566883, 4411.750007496168, 4508.82770479676, 4673.485916549177, 4050.0, 4544.500104988449, 4586.862394128165, 4593.565410332529, 4603.3785438965815, 4167.978845182919, 4549.294859296814, 4619.083080521579, 4652.906941861161, 4676.392069031004, 4144.818726662887, 4369.208624463955, 4379.934300985487, 4395.670324725658, 4408.977955110236, 4674.649401294882, 4917.606591472673, 5138.126518491828, 5283.233931080011, 5336.626436149471, 4548.43079137697, 4617.445906491209, 5111.1566591512355, 5447.727613619343, 5449.828286918292, 4942.851714448537, 5218.347706536683, 5454.127339625641, 5689.7439615217, 5713.171590119451, 3892.3966785148064, 4692.290025263871, 4782.41319010742, 4951.430585482643, 4987.501249875019, 5396.757145928404, 5647.576431767593, 5848.8651642150735, 5853.5902486825935, 5894.882102357972, 4704.3649107370065, 5019.749012549358, 5074.898502029939, 5087.09234037236, 5090.796368145307, 4450.0, 4998.850034498971, 5108.412532558729, 5154.845354639367, 5179.015986962465, 4375.431193944767, 4676.0, 4785.784639691222, 4987.775550100254, 5403.648762830403, 5098.7368084426, 5119.411267704188, 5188.740562971836, 5361.758720857571, 5777.422257774189, 1422.8008078868966, 1443.2495102546827, 1645.3254165791968, 1657.7596248543894, 1672.3227093027344, 1112.6995711157942, 1114.3091283404342, 1140.9446262207425, 1234.975300493985, 1243.4466662335174, 4812.759362031885, 4954.5540901319055, 5042.974785126051, 5215.58275337972, 5485.149801780731, 5422.945770542323, 5441.891162176814, 5533.335999680065, 6105.725242364104, 6190.41191086846, 5153.587712982952, 5216.739163041832, 5400.30268067185, 5442.605253688364, 5452.28018826876, 2982.686817884134, 3042.034016833614, 3063.463893818617, 3077.261457250244, 3148.418296996441, 5469.108922757355, 5546.3200091856115, 5657.529042448993, 5658.74535645897, 6233.470155036782, 5455.34535855692, 5520.54335820564, 5766.88466230673, 6199.752009919643, 6248.31268560458, 5402.594805389612, 5501.0, 5682.1248987833915, 6187.752489900444, 6362.395572420613, 5351.438098999704, 5564.666120032811, 5605.234351179365, 5873.3832947541605, 6090.482309003784, 5597.0, 5667.688277144632, 5723.027085395576, 5974.29732526228, 6007.219061522149, 6224.37756224374, 6250.749970001149, 6262.906056409066, 6294.496440284858, 6297.811065668037, 4697.365855609586, 4835.250536166759, 4838.983813399225, 4848.9757551212015, 4858.95141048585, 5270.341207349082, 5735.025045742317, 5886.793962211302, 5974.534769833573, 6068.271807383142, 5048.495150484922, 5124.79500819978, 5434.864128396822, 5768.183022347947, 5853.385394533512, 4944.426201777162, 5643.689597072036, 5831.550748685478, 5852.244326702057, 5916.189809840292, 5051.045138593964, 5266.420693723764, 5356.410794812646, 5483.040467918018, 5611.352620796515, 5333.866653333697, 5751.856203594776, 5849.678267695318, 5877.35349111606, 5971.343152253162, 4723.740618973035, 4899.755012249302, 5348.545373643284, 5664.518515926193, 5702.7718891243885], "value": 4432.449465565983, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [761.5659074327635, 778.1634742651651, 805.8630032894407, 870.4907629037011, 917.646706018183, 16510.091944943022, 17376.218070186842, 17445.651086978254, 17466.65066698667, 17560.47318580443, 16153.949993250431, 16229.594260143502, 16348.019118852877, 16361.181940902938, 16388.344466221366, 959.141568296376, 988.0860204311019, 988.3378136648463, 1024.073213741565, 1032.143321043532, 1036.528379587286, 1036.9992956796689, 1039.1790485516433, 1039.2777019971122, 1039.5893622019298, 1086.483920137932, 1086.6033897627387, 1087.6573879228058, 1088.8638920134983, 1089.1722291058813, 1061.5276202090074, 1071.9517621707041, 1078.3799315393642, 1080.3409919948824, 1080.9405482698448, 800.8398320335898, 800.8558459477292, 802.4022103532847, 802.6869520886859, 803.5178892664413, 16609.83390166095, 17136.742948855775, 17326.133693315285, 17340.046297453635, 17534.0, 14529.770629481423, 14634.707305853823, 14646.267686615627, 14834.258287085711, 14924.776128358084, 9821.508924553744, 10779.892201078046, 11891.345975971319, 12183.939080304543, 12710.555130570385, 3546.5212196353677, 3865.4588357629955, 3877.3020856245867, 3891.3384724596604, 3896.980515097406, 106296.0, 106980.39529406988, 109642.3553646696, 109806.0, 110026.89973100327, 11256.774864502664, 11309.547618095348, 11336.6032188873, 11478.081753459703, 11503.277067484229, 23330.183443579393, 23709.17017904365, 23715.932783024462, 23720.0, 23783.286501404986, 8068.0, 8101.675932962733, 8133.674653013813, 8190.959045204736, 8333.041700204432, 4593.54064593538, 4636.304554316879, 4656.138614356355, 4672.813087476463, 4720.126776546282, 3450.706689931384, 3491.30173965208, 3538.8938331850086, 3547.7693949893173, 3574.1779390740276, 639.4245179338633, 644.0339490763854, 649.5842660697158, 649.6232185332495, 658.1871388834785, 670.3531092495765, 685.7874059041714, 698.3575110897884, 716.298027932629, 734.3904559215838, 10645.946770266253, 10654.414007229521, 10903.945480272549, 10906.745724241813, 10942.0, 698.9196242432083, 725.354434553248, 739.9778006659809, 744.6165224909176, 750.3159486919201, 5163.173892177234, 5165.9225111623355, 5278.947210527922, 5282.867928301746, 5290.418054014055, 90178.0, 91434.0, 91623.70940726402, 92089.0, 92317.0, 57396.852062958504, 60989.0, 74823.0, 82533.0, 85449.0, 58163.70918145382, 73678.0, 75939.24060759322, 77138.0, 77218.0, 7850.862537591061, 10215.0, 11172.329660220414, 11535.307881527135, 11582.6525204244, 48718.538443845326, 49261.261081083096, 49338.50661493483, 50644.98710025705, 52535.73732131468, 59028.704856475444, 65489.01766473602, 66146.33853661401, 66346.66826666029, 66378.66810665916, 13228.537001204912, 13307.201567905957, 13329.133606315558, 13401.86598134006, 13657.180569165881, 14984.565062858344, 15003.099814011195, 15157.696846062796, 15476.148811815458, 15485.303161357991, 13707.520236791665, 13929.23389213603, 14120.082194657314, 14122.50571230002, 14209.857901420854, 13827.654308642364, 13973.371198295898, 14220.0, 14483.565493035227, 14821.629459263604, 12441.440135193758, 13249.933750331187, 14370.928145359207, 14398.784018239525, 14554.126752394466, 10229.181665466596, 10273.383596984206, 10312.27814053039, 10478.947605261925, 10675.145988321072, 10093.173114750996, 10481.213908956706, 11329.376884271443, 11576.36330001858, 11579.5947141853], "value": 7886.941673690596, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1544.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 120.0, 119.0, 120.0, 119.0, 120.0, 120.0, 120.0, 119.0, 119.0], "value": 118.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 108.0, 110.0, 151.0, 138.0, 141.0, 136.0, 109.0, 129.0, 128.0], "value": 129.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 202.0, 201.0, 198.0, 200.0, 199.0, 199.0, 201.0, 206.0, 200.0], "value": 201.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 59.0, 89.0, 61.0, 83.0, 77.0, 60.0, 85.0, 71.0], "value": 72.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 69.0, 85.0, 74.0, 71.0, 69.0, 80.0, 94.0, 98.0, 109.0], "value": 83.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 133.0, 90.0, 91.0, 101.0, 77.0, 134.0, 120.0, 90.0, 98.0], "value": 105.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 113.0, 152.0, 104.0, 97.0, 96.0, 126.0, 113.0, 152.0, 150.0], "value": 120.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 110.0, 140.0, 132.0, 143.0, 120.0, 92.0, 140.0, 129.0, 91.0], "value": 118.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 68.0, 70.0, 70.0, 70.0, 68.0, 69.0, 69.0], "value": 68.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 66.0, 63.0, 63.0, 79.0, 51.0, 57.0, 67.0, 80.0, 87.0], "value": 67.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 84.0, 85.0, 83.0, 84.0, 83.0, 88.0, 85.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 137.0, 151.0, 128.0, 146.0, 142.0, 141.0, 145.0, 187.0, 137.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 166.0, 166.0, 163.0, 162.0, 175.0, 167.0, 165.0, 168.0, 160.0], "value": 165.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 65.0, 64.0, 72.0, 65.0, 64.0, 62.0, 64.0, 67.0], "value": 65.3, "unit": "score"}]}]} 09:54:21 INFO - Return code: 0 09:54:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:54:21 ERROR - # TBPL SUCCESS # 09:54:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:54:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:54:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:54:21 INFO - 2017-05-25 09:54:21.475 screenresolution[1657:10788] starting screenresolution argv=screenresolution get 09:54:21 INFO - 2017-05-25 09:54:21.478 screenresolution[1657:10788] Display 0: 1600x1200x32@60 09:54:21 INFO - 2017-05-25 09:54:21.484 screenresolution[1658:10791] starting screenresolution argv=screenresolution list 09:54:21 INFO - Available Modes on Display 0 09:54:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:54:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:54:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:54:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:54:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:54:21 INFO - Intel Iris: 09:54:21 INFO - Chipset Model: Intel Iris 09:54:21 INFO - Type: GPU 09:54:21 INFO - Bus: Built-In 09:54:21 INFO - VRAM (Dynamic, Max): 1536 MB 09:54:21 INFO - Vendor: Intel (0x8086) 09:54:21 INFO - Device ID: 0x0a2e 09:54:21 INFO - Revision ID: 0x0009 09:54:21 INFO - Displays: 09:54:21 INFO - Display: 09:54:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:54:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:54:21 INFO - Display Serial Number: 1600x1200 60 09:54:21 INFO - Main Display: Yes 09:54:21 INFO - Mirror: Off 09:54:21 INFO - Online: Yes 09:54:21 INFO - Rotation: Supported 09:54:21 INFO - Return code: 0 09:54:21 INFO - Running post-action listener: _resource_record_post_action 09:54:21 INFO - [mozharness: 2017-05-25 16:54:21.721146Z] Finished run-tests step (success) 09:54:21 INFO - Running post-run listener: _resource_record_post_run 09:54:21 INFO - Total resource usage - Wall time: 1191s; CPU: 55.0%; Read bytes: 39798784; Write bytes: 423581696; Read time: 251; Write time: 1426 09:54:21 INFO - TinderboxPrint: CPU usage
54.9% 09:54:21 INFO - TinderboxPrint: I/O read bytes / time
39,798,784 / 251 09:54:21 INFO - TinderboxPrint: I/O write bytes / time
423,581,696 / 1,426 09:54:21 INFO - TinderboxPrint: CPU idle
2,150.0 (45.1%) 09:54:21 INFO - TinderboxPrint: CPU system
964.0 (20.2%) 09:54:21 INFO - TinderboxPrint: CPU user
1,651.7 (34.7%) 09:54:21 INFO - TinderboxPrint: Swap in / out
162,447,360 / 0 09:54:21 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 123267584; Write bytes: 123031552; Read time: 11058; Write time: 287 09:54:21 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 35637248; Write bytes: 299112448; Read time: 226; Write time: 1134 09:54:22 INFO - Running post-run listener: _upload_blobber_files 09:54:22 INFO - Blob upload gear active. 09:54:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:54:22 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:54:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:54:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:54:22 INFO - (blobuploader) - INFO - Open directory for files ... 09:54:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:54:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:54:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:23 INFO - (blobuploader) - INFO - Done attempting. 09:54:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:54:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:54:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:24 INFO - (blobuploader) - INFO - Done attempting. 09:54:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:54:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:25 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:54:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:25 INFO - (blobuploader) - INFO - Done attempting. 09:54:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:54:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:54:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:54:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:54:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:54:27 INFO - (blobuploader) - INFO - Done attempting. 09:54:27 INFO - (blobuploader) - INFO - Iteration through files over. 09:54:27 INFO - Return code: 0 09:54:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:54:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:54:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8c2e5a94a439a026f89bcdefdf420efb9c5ef0a26c82999388b2cc443282946dce12f231e495bc5c2d888d60b09e8fd5d09a12a88b75dda66aaaeacd0ac40791", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8c084629cf3b6a517734a8201bfce684b658b5a7441fe268f4d3ad4c2e47a3e7aafa72b4f991707c051871ff9e0cde8e1cf4e7eee21cbc41362d790c5dfd3d15", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0638259e0ddd33a74cd3e70ace35f880d83b60de42d963d999e8071d0c312724d10dc7ce8a1541378a61c95c619cd2be4c156ef0c1970d736c8803bf25807812", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a41810594022975b912ca478fd6284fa297c171fc7e023a869606cbc1568b157a98ccb5d20e82967345008fab55b308c28781d44125460bbc4ff3b287455296f"} 09:54:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:54:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:54:27 INFO - Contents: 09:54:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8c2e5a94a439a026f89bcdefdf420efb9c5ef0a26c82999388b2cc443282946dce12f231e495bc5c2d888d60b09e8fd5d09a12a88b75dda66aaaeacd0ac40791", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8c084629cf3b6a517734a8201bfce684b658b5a7441fe268f4d3ad4c2e47a3e7aafa72b4f991707c051871ff9e0cde8e1cf4e7eee21cbc41362d790c5dfd3d15", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0638259e0ddd33a74cd3e70ace35f880d83b60de42d963d999e8071d0c312724d10dc7ce8a1541378a61c95c619cd2be4c156ef0c1970d736c8803bf25807812", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a41810594022975b912ca478fd6284fa297c171fc7e023a869606cbc1568b157a98ccb5d20e82967345008fab55b308c28781d44125460bbc4ff3b287455296f"} 09:54:27 INFO - Running post-run listener: copy_logs_to_upload_dir 09:54:27 INFO - Copying logs to upload dir... 09:54:27 INFO - mkdir: /builds/slave/test/build/upload/logs 09:54:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.967870 ========= master_lag: 2.13 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-25 09:54:29.633244) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 09:54:29.638061) ========= 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.xEjuExtI1p/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.q0ZSv3K879/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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/8c2e5a94a439a026f89bcdefdf420efb9c5ef0a26c82999388b2cc443282946dce12f231e495bc5c2d888d60b09e8fd5d09a12a88b75dda66aaaeacd0ac40791", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8c084629cf3b6a517734a8201bfce684b658b5a7441fe268f4d3ad4c2e47a3e7aafa72b4f991707c051871ff9e0cde8e1cf4e7eee21cbc41362d790c5dfd3d15", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0638259e0ddd33a74cd3e70ace35f880d83b60de42d963d999e8071d0c312724d10dc7ce8a1541378a61c95c619cd2be4c156ef0c1970d736c8803bf25807812", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a41810594022975b912ca478fd6284fa297c171fc7e023a869606cbc1568b157a98ccb5d20e82967345008fab55b308c28781d44125460bbc4ff3b287455296f"} build_url:https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011189 build_url: 'https://queue.taskcluster.net/v1/task/F7VNk9SOQlq44tTihA-mmQ/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/8c2e5a94a439a026f89bcdefdf420efb9c5ef0a26c82999388b2cc443282946dce12f231e495bc5c2d888d60b09e8fd5d09a12a88b75dda66aaaeacd0ac40791", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8c084629cf3b6a517734a8201bfce684b658b5a7441fe268f4d3ad4c2e47a3e7aafa72b4f991707c051871ff9e0cde8e1cf4e7eee21cbc41362d790c5dfd3d15", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0638259e0ddd33a74cd3e70ace35f880d83b60de42d963d999e8071d0c312724d10dc7ce8a1541378a61c95c619cd2be4c156ef0c1970d736c8803bf25807812", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a41810594022975b912ca478fd6284fa297c171fc7e023a869606cbc1568b157a98ccb5d20e82967345008fab55b308c28781d44125460bbc4ff3b287455296f"}' ========= master_lag: 0.38 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 09:54:30.032591) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:54:30.032999) ========= 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.xEjuExtI1p/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.q0ZSv3K879/Listeners TMPDIR=/var/folders/rx/3trszfh567qfgj3yl9jd_ztc00000w/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.005108 ========= master_lag: 0.10 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 09:54:30.133308) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 09:54:30.133894) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 09:54:30.134722) ========= ========= Total master_lag: 2.68 =========