builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0353 starttime: 1495209996.45 results: success (0) buildid: 20170519074619 builduid: d8260a392c3442a8a86777caa51ea529 revision: 61932206bf73a5c510f97eb3089b823b97b1141e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 09:06:36.452483) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-19 09:06:36.452938) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 09:06:36.453216) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-19 09:06:36.477555) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 09:06:36.477920) ========= 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.fOYbtiDass/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.DHVEW4EGXa/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-19 09:06:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 16.9M=0.001s 2017-05-19 09:06:36 (16.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.134056 ========= master_lag: 0.25 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 09:06:36.862677) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 09:06:36.863035) ========= 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.fOYbtiDass/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.DHVEW4EGXa/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.021054 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 09:06:36.905155) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-19 09:06:36.905519) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 61932206bf73a5c510f97eb3089b823b97b1141e --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 61932206bf73a5c510f97eb3089b823b97b1141e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fOYbtiDass/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.DHVEW4EGXa/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-19 09:06:36,989 truncating revision to first 12 chars 2017-05-19 09:06:36,989 Setting DEBUG logging. 2017-05-19 09:06:36,990 attempt 1/10 2017-05-19 09:06:36,990 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/61932206bf73?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-19 09:06:37,719 unpacking tar archive at: mozilla-beta-61932206bf73/testing/mozharness/ program finished with exit code 0 elapsedTime=0.987915 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-19 09:06:37.915611) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 09:06:37.915930) ========= script_repo_revision: 61932206bf73a5c510f97eb3089b823b97b1141e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-19 09:06:37.916334) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 09:06:37.916613) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-19 09:06:37.935589) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-05-19 09:06:37.935978) ========= /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.fOYbtiDass/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.DHVEW4EGXa/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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:06:38 INFO - MultiFileLogger online at 20170519 09:06:38 in /builds/slave/test 09:06:38 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:06:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:06:38 INFO - {'append_to_log': False, 09:06:38 INFO - 'base_work_dir': '/builds/slave/test', 09:06:38 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:06:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:06:38 INFO - 'branch': 'Mozilla-Beta', 09:06:38 INFO - 'buildbot_json_path': 'buildprops.json', 09:06:38 INFO - 'config_files': ('talos/mac_config.py',), 09:06:38 INFO - 'default_actions': ('clobber', 09:06:38 INFO - 'read-buildbot-config', 09:06:38 INFO - 'download-and-extract', 09:06:38 INFO - 'populate-webroot', 09:06:38 INFO - 'create-virtualenv', 09:06:38 INFO - 'install', 09:06:38 INFO - 'run-tests'), 09:06:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:06:38 INFO - 'download_minidump_stackwalk': True, 09:06:38 INFO - 'download_symbols': 'ondemand', 09:06:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:06:38 INFO - 'tooltool.py': '/tools/tooltool.py', 09:06:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:06:38 INFO - '/tools/misc-python/virtualenv.py')}, 09:06:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:06:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:06:38 INFO - 'gecko_profile': False, 09:06:38 INFO - 'gecko_profile_interval': 0, 09:06:38 INFO - 'installer_path': 'installer.exe', 09:06:38 INFO - 'log_level': 'info', 09:06:38 INFO - 'log_name': 'talos', 09:06:38 INFO - 'log_to_console': True, 09:06:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:06:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:06:38 INFO - 'opt_config_files': (), 09:06:38 INFO - 'pip_index': False, 09:06:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:06:38 INFO - 'cmd': ('bash', 09:06:38 INFO - '-c', 09:06:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:06:38 INFO - 'enabled': True, 09:06:38 INFO - 'halt_on_failure': False, 09:06:38 INFO - 'name': 'check_screen_resolution'},), 09:06:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:06:38 INFO - 'cmd': ('bash', 09:06:38 INFO - '-c', 09:06:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:06:38 INFO - 'enabled': True, 09:06:38 INFO - 'halt_on_failure': False, 09:06:38 INFO - 'name': 'check_screen_resolution'},), 09:06:38 INFO - 'run_cmd_checks_enabled': True, 09:06:38 INFO - 'suite': 'dromaeojs-e10s', 09:06:38 INFO - 'system_bits': '32', 09:06:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:06:38 INFO - 'title': 't-yosemite-r7-0353', 09:06:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:06:38 INFO - 'use_talos_json': True, 09:06:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:06:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:06:38 INFO - 'work_dir': 'build'} 09:06:38 INFO - [mozharness: 2017-05-19 16:06:38.138201Z] Running clobber step. 09:06:38 INFO - Running pre-action listener: _resource_record_pre_action 09:06:38 INFO - Running main action method: clobber 09:06:38 INFO - rmtree: /builds/slave/test/build 09:06:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:06:38 INFO - Running post-action listener: _resource_record_post_action 09:06:38 INFO - [mozharness: 2017-05-19 16:06:38.293727Z] Finished clobber step (success) 09:06:38 INFO - [mozharness: 2017-05-19 16:06:38.293893Z] Running read-buildbot-config step. 09:06:38 INFO - Running pre-action listener: _resource_record_pre_action 09:06:38 INFO - Running main action method: read_buildbot_config 09:06:38 INFO - Using buildbot properties: 09:06:38 INFO - { 09:06:38 INFO - "project": "", 09:06:38 INFO - "product": "firefox", 09:06:38 INFO - "script_repo_revision": "production", 09:06:38 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 09:06:38 INFO - "repository": "", 09:06:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 09:06:38 INFO - "buildid": "20170519074619", 09:06:38 INFO - "pgo_build": "False", 09:06:38 INFO - "basedir": "/builds/slave/test", 09:06:38 INFO - "buildnumber": 15, 09:06:38 INFO - "slavename": "t-yosemite-r7-0353", 09:06:38 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:06:38 INFO - "platform": "yosemite_r7", 09:06:38 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:06:38 INFO - "branch": "mozilla-beta", 09:06:38 INFO - "repo_path": "releases/mozilla-beta", 09:06:38 INFO - "revision": "61932206bf73a5c510f97eb3089b823b97b1141e", 09:06:38 INFO - "stage_platform": "macosx64", 09:06:38 INFO - "builduid": "d8260a392c3442a8a86777caa51ea529", 09:06:38 INFO - "slavebuilddir": "test" 09:06:38 INFO - } 09:06:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 09:06:38 INFO - Running post-action listener: _resource_record_post_action 09:06:38 INFO - [mozharness: 2017-05-19 16:06:38.297196Z] Finished read-buildbot-config step (success) 09:06:38 INFO - [mozharness: 2017-05-19 16:06:38.297320Z] Running download-and-extract step. 09:06:38 INFO - Running pre-action listener: _resource_record_pre_action 09:06:38 INFO - Running main action method: download_and_extract 09:06:38 INFO - mkdir: /builds/slave/test/build/tests 09:06:38 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:06:38 INFO - trying https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 09:06:38 INFO - Downloading https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/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:06:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/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:06:39 INFO - Downloaded 1287 bytes. 09:06:39 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 09:06:39 INFO - Using the following test package requirements: 09:06:39 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:06:39 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 09:06:39 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:06:39 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:06:39 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 09:06:39 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:06:39 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 09:06:39 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:06:39 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:06:39 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 09:06:39 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 09:06:39 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:06:39 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 09:06:39 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:06:39 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 09:06:39 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:06:39 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 09:06:39 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 09:06:39 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 09:06:39 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 09:06:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:06:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:06:39 INFO - Fetch https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:06:41 INFO - Expected file size: 51226921 09:06:41 INFO - Obtained file size: 51226921 09:06:56 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:06:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 09:06:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 09:06:56 INFO - Fetch https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 09:06:59 INFO - Expected file size: 51226921 09:06:59 INFO - Obtained file size: 51226921 09:07:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 09:07:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 09:07:14 INFO - Fetch https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 09:07:15 INFO - Expected file size: 14404449 09:07:15 INFO - Obtained file size: 14404449 09:07: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:07:15 INFO - trying https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:07:15 INFO - Downloading https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 09:07:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/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:07:17 INFO - Downloaded 59542504 bytes. 09:07:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:07:17 INFO - mkdir: /builds/slave/test/properties 09:07:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:07:17 INFO - Writing to file /builds/slave/test/properties/build_url 09:07:17 INFO - Contents: 09:07:17 INFO - build_url:https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.dmg 09:07:18 INFO - Running post-action listener: _resource_record_post_action 09:07:18 INFO - Running post-action listener: set_extra_try_arguments 09:07:18 INFO - [mozharness: 2017-05-19 16:07:18.001244Z] Finished download-and-extract step (success) 09:07:18 INFO - [mozharness: 2017-05-19 16:07:18.001387Z] Running populate-webroot step. 09:07:18 INFO - Running pre-action listener: _resource_record_pre_action 09:07:18 INFO - Running main action method: populate_webroot 09:07:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:07:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:07:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:07:18 INFO - '--tppagecycles', 09:07:18 INFO - '7'], 09:07:18 INFO - 'tests': ['tsvgm']}}, 09:07:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:07:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:07:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:07:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:07:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:07:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:07:18 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:07:18 INFO - 'talos_options': ['--disable-e10s'], 09:07:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:18 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:07:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:18 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:07:18 INFO - 'talos_options': ['--disable-e10s'], 09:07:18 INFO - 'tests': ['damp', 'tps']}, 09:07:18 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:07:18 INFO - 'tests': ['damp', 'tps']}, 09:07:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:07:18 INFO - 'tests': ['dromaeo_dom']}, 09:07:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:07:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:07:18 INFO - 'tests': ['basic_compositor_video']}, 09:07:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:07:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:07:18 INFO - 'tests': ['a11yr', 09:07:18 INFO - 'ts_paint', 09:07:18 INFO - 'tpaint', 09:07:18 INFO - 'sessionrestore', 09:07:18 INFO - 'sessionrestore_no_auto_restore', 09:07:18 INFO - 'tabpaint']}, 09:07:18 INFO - 'other-e10s': {'tests': ['a11yr', 09:07:18 INFO - 'ts_paint', 09:07:18 INFO - 'tpaint', 09:07:18 INFO - 'sessionrestore', 09:07:18 INFO - 'sessionrestore_no_auto_restore', 09:07:18 INFO - 'tabpaint']}, 09:07:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:07:18 INFO - 'ts_paint', 09:07:18 INFO - 'tpaint', 09:07:18 INFO - 'sessionrestore', 09:07:18 INFO - 'sessionrestore_no_auto_restore', 09:07:18 INFO - 'tabpaint']}, 09:07:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:07:18 INFO - 'ts_paint', 09:07:18 INFO - 'tpaint', 09:07:18 INFO - 'sessionrestore', 09:07:18 INFO - 'sessionrestore_no_auto_restore', 09:07:18 INFO - 'tabpaint']}, 09:07:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:07:18 INFO - 'tests': ['a11yr', 09:07:18 INFO - 'ts_paint', 09:07:18 INFO - 'tpaint', 09:07:18 INFO - 'sessionrestore', 09:07:18 INFO - 'sessionrestore_no_auto_restore', 09:07:18 INFO - 'tabpaint']}, 09:07:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:07:18 INFO - 'tests': ['a11yr', 09:07:18 INFO - 'ts_paint', 09:07:18 INFO - 'tpaint', 09:07:18 INFO - 'sessionrestore', 09:07:18 INFO - 'sessionrestore_no_auto_restore', 09:07:18 INFO - 'tabpaint']}, 09:07:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:07:18 INFO - 'tests': ['tsvgx', 09:07:18 INFO - 'tsvgr_opacity', 09:07:18 INFO - 'tart', 09:07:18 INFO - 'tscrollx', 09:07:18 INFO - 'cart', 09:07:18 INFO - 'tsvg_static']}, 09:07:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:07:18 INFO - 'tsvgr_opacity', 09:07:18 INFO - 'tart', 09:07:18 INFO - 'tscrollx', 09:07:18 INFO - 'cart', 09:07:18 INFO - 'tsvg_static']}, 09:07:18 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:07:18 INFO - 'talos_options': ['--disable-e10s'], 09:07:18 INFO - 'tests': ['tp5o']}, 09:07:18 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:07:18 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:07:18 INFO - 'talos_options': ['--disable-e10s', 09:07:18 INFO - '--xperf_path', 09:07:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:18 INFO - 'tests': ['tp5n']}, 09:07:18 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:07:18 INFO - 'talos_options': ['--xperf_path', 09:07:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:18 INFO - 'tests': ['tp5n']}}, 09:07:18 INFO - 'talos.zip': {'path': '', 09:07:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:07:18 INFO - Running post-action listener: _resource_record_post_action 09:07:18 INFO - [mozharness: 2017-05-19 16:07:18.019740Z] Finished populate-webroot step (success) 09:07:18 INFO - [mozharness: 2017-05-19 16:07:18.019873Z] Running create-virtualenv step. 09:07:18 INFO - Running pre-action listener: _resource_record_pre_action 09:07:18 INFO - Running main action method: create_virtualenv 09:07:18 INFO - Creating virtualenv /builds/slave/test/build/venv 09:07:18 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:07:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:07:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:07:18 INFO - Using real prefix '/tools/python27' 09:07:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:07:18 INFO - Installing distribute.............................................................................................................................................................................................done. 09:07:21 INFO - Installing pip.................done. 09:07:21 INFO - Return code: 0 09:07:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:07:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:07:21 INFO - Reading from file tmpfile_stdout 09:07:21 INFO - Output received: 09:07:21 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:07:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:07:21 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:07:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:21 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:07:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:21 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 0x100f40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5724d3250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d1ed30>, '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 0x100c45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d13be8>, '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.fOYbtiDass/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.DHVEW4EGXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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:07:21 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:07:21 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:07:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fOYbtiDass/Render', 09:07:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:21 INFO - 'HOME': '/Users/cltbld', 09:07:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:21 INFO - 'LOGNAME': 'cltbld', 09:07:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:21 INFO - 'MOZ_NO_REMOTE': '1', 09:07:21 INFO - 'NO_EM_RESTART': '1', 09:07:21 INFO - 'PAGER': '/bin/cat', 09:07:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:21 INFO - 'PWD': '/builds/slave/test', 09:07:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:21 INFO - 'SHELL': '/bin/bash', 09:07:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHVEW4EGXa/Listeners', 09:07:21 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:07:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:21 INFO - 'USER': 'cltbld', 09:07:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:21 INFO - 'XPC_FLAGS': '0x0', 09:07:21 INFO - 'XPC_SERVICE_NAME': '0', 09:07:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:22 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:07:22 INFO - Cleaning up... 09:07:22 INFO - Return code: 0 09:07:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:07:22 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:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:22 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:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:22 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 0x100f40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5724d3250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d1ed30>, '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 0x100c45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d13be8>, '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.fOYbtiDass/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.DHVEW4EGXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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:07:22 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:07:22 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:07:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fOYbtiDass/Render', 09:07:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:22 INFO - 'HOME': '/Users/cltbld', 09:07:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:22 INFO - 'LOGNAME': 'cltbld', 09:07:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:22 INFO - 'MOZ_NO_REMOTE': '1', 09:07:22 INFO - 'NO_EM_RESTART': '1', 09:07:22 INFO - 'PAGER': '/bin/cat', 09:07:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:22 INFO - 'PWD': '/builds/slave/test', 09:07:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:22 INFO - 'SHELL': '/bin/bash', 09:07:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHVEW4EGXa/Listeners', 09:07:22 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:07:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:22 INFO - 'USER': 'cltbld', 09:07:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:22 INFO - 'XPC_FLAGS': '0x0', 09:07:22 INFO - 'XPC_SERVICE_NAME': '0', 09:07:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:22 INFO - Downloading/unpacking psutil>=3.1.1 09:07:22 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:07:22 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:07:22 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:07:22 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:07:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:07:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:07:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:07:24 INFO - Installing collected packages: psutil 09:07:24 INFO - Running setup.py install for psutil 09:07:24 INFO - building 'psutil._psutil_osx' extension 09:07:24 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:07:24 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:07:24 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:07:25 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:07:25 INFO - building 'psutil._psutil_posix' extension 09:07:25 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:07:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:07:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:07:25 INFO - ^ 09:07:25 INFO - 1 warning generated. 09:07:25 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:07:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:07:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:07:25 INFO - Successfully installed psutil 09:07:25 INFO - Cleaning up... 09:07:25 INFO - Return code: 0 09:07:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:25 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 0x100f40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5724d3250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d1ed30>, '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 0x100c45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d13be8>, '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.fOYbtiDass/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.DHVEW4EGXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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:07:25 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:07:25 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:07:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fOYbtiDass/Render', 09:07:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:25 INFO - 'HOME': '/Users/cltbld', 09:07:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:25 INFO - 'LOGNAME': 'cltbld', 09:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:25 INFO - 'MOZ_NO_REMOTE': '1', 09:07:25 INFO - 'NO_EM_RESTART': '1', 09:07:25 INFO - 'PAGER': '/bin/cat', 09:07:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:25 INFO - 'PWD': '/builds/slave/test', 09:07:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:25 INFO - 'SHELL': '/bin/bash', 09:07:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHVEW4EGXa/Listeners', 09:07:25 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:07:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:25 INFO - 'USER': 'cltbld', 09:07:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:25 INFO - 'XPC_FLAGS': '0x0', 09:07:25 INFO - 'XPC_SERVICE_NAME': '0', 09:07:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:07:25 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:07:25 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:07:25 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:07:25 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:07:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:07:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:07:27 INFO - Installing collected packages: mozsystemmonitor 09:07:27 INFO - Running setup.py install for mozsystemmonitor 09:07:27 INFO - Successfully installed mozsystemmonitor 09:07:27 INFO - Cleaning up... 09:07:27 INFO - Return code: 0 09:07:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:07:27 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:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:27 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:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:27 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 0x100f40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5724d3250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d1ed30>, '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 0x100c45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d13be8>, '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.fOYbtiDass/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.DHVEW4EGXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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:07:27 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:07:27 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:07:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fOYbtiDass/Render', 09:07:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:27 INFO - 'HOME': '/Users/cltbld', 09:07:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:27 INFO - 'LOGNAME': 'cltbld', 09:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:27 INFO - 'MOZ_NO_REMOTE': '1', 09:07:27 INFO - 'NO_EM_RESTART': '1', 09:07:27 INFO - 'PAGER': '/bin/cat', 09:07:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:27 INFO - 'PWD': '/builds/slave/test', 09:07:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:27 INFO - 'SHELL': '/bin/bash', 09:07:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHVEW4EGXa/Listeners', 09:07:27 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:07:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:27 INFO - 'USER': 'cltbld', 09:07:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:27 INFO - 'XPC_FLAGS': '0x0', 09:07:27 INFO - 'XPC_SERVICE_NAME': '0', 09:07:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:27 INFO - Downloading/unpacking jsonschema==2.5.1 09:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:07:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:07:28 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:07:28 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:07:28 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:07:28 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:07:29 INFO - Downloading functools32-3.2.3-2.tar.gz 09:07:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:07:29 INFO - warning: no files found matching '*.txt' 09:07:29 INFO - no previously-included directories found matching 'build' 09:07:29 INFO - no previously-included directories found matching 'dist' 09:07:29 INFO - no previously-included directories found matching '.git*' 09:07:29 INFO - Installing collected packages: jsonschema, functools32 09:07:29 INFO - Running setup.py install for functools32 09:07:29 INFO - warning: no files found matching '*.txt' 09:07:29 INFO - no previously-included directories found matching 'build' 09:07:29 INFO - no previously-included directories found matching 'dist' 09:07:29 INFO - no previously-included directories found matching '.git*' 09:07:29 INFO - Successfully installed jsonschema functools32 09:07:29 INFO - Cleaning up... 09:07:29 INFO - Return code: 0 09:07:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:07: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:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:30 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:07:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:30 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 0x100f40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5724d3250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d1ed30>, '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 0x100c45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d13be8>, '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.fOYbtiDass/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.DHVEW4EGXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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:07:30 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:07:30 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:07:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fOYbtiDass/Render', 09:07:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:30 INFO - 'HOME': '/Users/cltbld', 09:07:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:30 INFO - 'LOGNAME': 'cltbld', 09:07:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:30 INFO - 'MOZ_NO_REMOTE': '1', 09:07:30 INFO - 'NO_EM_RESTART': '1', 09:07:30 INFO - 'PAGER': '/bin/cat', 09:07:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:30 INFO - 'PWD': '/builds/slave/test', 09:07:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:30 INFO - 'SHELL': '/bin/bash', 09:07:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHVEW4EGXa/Listeners', 09:07:30 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:07:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:30 INFO - 'USER': 'cltbld', 09:07:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:30 INFO - 'XPC_FLAGS': '0x0', 09:07:30 INFO - 'XPC_SERVICE_NAME': '0', 09:07:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:07:30 INFO - Cleaning up... 09:07:30 INFO - Return code: 0 09:07:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:07:30 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:07:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:30 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:07:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:31 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 0x100f40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5724d3250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d1ed30>, '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 0x100c45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d13be8>, '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.fOYbtiDass/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.DHVEW4EGXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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:07:31 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:07:31 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:07:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fOYbtiDass/Render', 09:07:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:31 INFO - 'HOME': '/Users/cltbld', 09:07:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:31 INFO - 'LOGNAME': 'cltbld', 09:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:31 INFO - 'MOZ_NO_REMOTE': '1', 09:07:31 INFO - 'NO_EM_RESTART': '1', 09:07:31 INFO - 'PAGER': '/bin/cat', 09:07:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:31 INFO - 'PWD': '/builds/slave/test', 09:07:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:31 INFO - 'SHELL': '/bin/bash', 09:07:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHVEW4EGXa/Listeners', 09:07:31 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:07:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:31 INFO - 'USER': 'cltbld', 09:07:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:31 INFO - 'XPC_FLAGS': '0x0', 09:07:31 INFO - 'XPC_SERVICE_NAME': '0', 09:07:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:31 INFO - Downloading/unpacking blobuploader==1.2.4 09:07:31 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:07:31 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:07:31 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:07:31 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:07:33 INFO - Downloading blobuploader-1.2.4.tar.gz 09:07:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:07:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:07:33 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:07:33 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:07:33 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:07:33 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:07:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:07:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:07:33 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:07:33 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:07:33 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:07:33 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:07:34 INFO - Downloading docopt-0.6.1.tar.gz 09:07:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:07:34 INFO - Installing collected packages: blobuploader, requests, docopt 09:07:34 INFO - Running setup.py install for blobuploader 09:07:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:07:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:07:34 INFO - Running setup.py install for requests 09:07:34 INFO - Running setup.py install for docopt 09:07:35 INFO - Successfully installed blobuploader requests docopt 09:07:35 INFO - Cleaning up... 09:07:35 INFO - Return code: 0 09:07:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:35 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:07:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:35 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:07:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:35 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 0x100f40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5724d3250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d1ed30>, '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 0x100c45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d13be8>, '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.fOYbtiDass/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.DHVEW4EGXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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:07:35 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:07:35 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:07:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fOYbtiDass/Render', 09:07:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:35 INFO - 'HOME': '/Users/cltbld', 09:07:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:35 INFO - 'LOGNAME': 'cltbld', 09:07:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:35 INFO - 'MOZ_NO_REMOTE': '1', 09:07:35 INFO - 'NO_EM_RESTART': '1', 09:07:35 INFO - 'PAGER': '/bin/cat', 09:07:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:35 INFO - 'PWD': '/builds/slave/test', 09:07:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:35 INFO - 'SHELL': '/bin/bash', 09:07:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHVEW4EGXa/Listeners', 09:07:35 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:07:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:35 INFO - 'USER': 'cltbld', 09:07:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:35 INFO - 'XPC_FLAGS': '0x0', 09:07:35 INFO - 'XPC_SERVICE_NAME': '0', 09:07:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:35 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-jih0F9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:35 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-7xjHaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:35 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-pVbgsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:35 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-wRHn0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:35 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-JJsWde-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:35 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-dalPif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-aY36mw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-BkLEeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-ajpwq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-TpFpC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-twZ2cl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-17ulEz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-tar13h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-cNq52d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-uHI8te-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-y5SSTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:37 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-jMixPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:07:37 INFO - Running setup.py install for manifestparser 09:07:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:07:37 INFO - Running setup.py install for mozcrash 09:07:37 INFO - Running setup.py install for mozdebug 09:07:37 INFO - Running setup.py install for mozdevice 09:07:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:07:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:07:37 INFO - Running setup.py install for mozfile 09:07:37 INFO - Running setup.py install for mozhttpd 09:07:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:07:38 INFO - Running setup.py install for mozinfo 09:07:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:07:38 INFO - Running setup.py install for mozInstall 09:07:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:07:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:07:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:07:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:07:38 INFO - Running setup.py install for mozleak 09:07:38 INFO - Running setup.py install for mozlog 09:07:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:07:38 INFO - Running setup.py install for moznetwork 09:07:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:07:38 INFO - Running setup.py install for mozprocess 09:07:38 INFO - Running setup.py install for mozprofile 09:07:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:07:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:07:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:07:39 INFO - Running setup.py install for mozrunner 09:07:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:07:39 INFO - Running setup.py install for mozscreenshot 09:07:39 INFO - Running setup.py install for moztest 09:07:39 INFO - Running setup.py install for mozversion 09:07:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:07:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:07:39 INFO - Cleaning up... 09:07:39 INFO - Return code: 0 09:07:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:39 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:07:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:39 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:07:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:39 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 0x100f40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5724d3250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d1ed30>, '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 0x100c45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d13be8>, '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.fOYbtiDass/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.DHVEW4EGXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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:07:39 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:07:39 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:07:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fOYbtiDass/Render', 09:07:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:39 INFO - 'HOME': '/Users/cltbld', 09:07:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:39 INFO - 'LOGNAME': 'cltbld', 09:07:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:39 INFO - 'MOZ_NO_REMOTE': '1', 09:07:39 INFO - 'NO_EM_RESTART': '1', 09:07:39 INFO - 'PAGER': '/bin/cat', 09:07:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:39 INFO - 'PWD': '/builds/slave/test', 09:07:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:39 INFO - 'SHELL': '/bin/bash', 09:07:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHVEW4EGXa/Listeners', 09:07:39 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:07:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:39 INFO - 'USER': 'cltbld', 09:07:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:39 INFO - 'XPC_FLAGS': '0x0', 09:07:39 INFO - 'XPC_SERVICE_NAME': '0', 09:07:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:40 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-9Q06sP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:40 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:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:40 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-WGuzes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:40 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:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:40 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Pk6_jI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:40 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:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:40 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-xd3A61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:40 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:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:40 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-tSClSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:40 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:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:40 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-VBefyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:40 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:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:40 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-UcyCXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:40 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:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:40 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-7PIOd_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:40 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:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:40 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-ZZMKGV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:40 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:07:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:40 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Gy0pAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:41 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:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:41 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-7PeIXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:41 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:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:41 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-ibTGys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:41 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:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:41 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-6kTHpW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:41 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:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:41 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-BMHl0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:41 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:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:41 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-WNXzYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:41 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:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:41 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-mzACaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:41 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:07:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:41 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-IdLJoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:41 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:07:41 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:07:41 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:07:41 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:07:41 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:07:41 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:07:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:07:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:43 INFO - Downloading blessings-1.6.tar.gz 09:07:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:07:43 INFO - Installing collected packages: blessings 09:07:43 INFO - Running setup.py install for blessings 09:07:43 INFO - Successfully installed blessings 09:07:43 INFO - Cleaning up... 09:07:43 INFO - Return code: 0 09:07:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:07:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:07:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:07:43 INFO - Reading from file tmpfile_stdout 09:07:43 INFO - Current package versions: 09:07:43 INFO - blessings == 1.6 09:07:43 INFO - blobuploader == 1.2.4 09:07:43 INFO - docopt == 0.6.1 09:07:43 INFO - functools32 == 3.2.3-2 09:07:43 INFO - jsonschema == 2.5.1 09:07:43 INFO - manifestparser == 1.1 09:07:43 INFO - mozInstall == 1.12 09:07:43 INFO - mozcrash == 1.0 09:07:43 INFO - mozdebug == 0.1 09:07:43 INFO - mozdevice == 0.49 09:07:43 INFO - mozfile == 1.2 09:07:43 INFO - mozhttpd == 0.7 09:07:43 INFO - mozinfo == 0.9 09:07:43 INFO - mozleak == 0.1 09:07:43 INFO - mozlog == 3.4 09:07:43 INFO - moznetwork == 0.27 09:07:43 INFO - mozprocess == 0.25 09:07:43 INFO - mozprofile == 0.28 09:07:43 INFO - mozrunner == 6.13 09:07:43 INFO - mozscreenshot == 0.1 09:07:43 INFO - mozsystemmonitor == 0.3 09:07:43 INFO - moztest == 0.8 09:07:43 INFO - mozversion == 1.4 09:07:43 INFO - psutil == 3.1.1 09:07:43 INFO - requests == 1.2.3 09:07:43 INFO - wsgiref == 0.1.2 09:07:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:43 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 0x100f40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5724d3250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d1ed30>, '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 0x100c45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d13be8>, '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.fOYbtiDass/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.DHVEW4EGXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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:07:43 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:07:43 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:07:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fOYbtiDass/Render', 09:07:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:43 INFO - 'HOME': '/Users/cltbld', 09:07:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:43 INFO - 'LOGNAME': 'cltbld', 09:07:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:43 INFO - 'MOZ_NO_REMOTE': '1', 09:07:43 INFO - 'NO_EM_RESTART': '1', 09:07:43 INFO - 'PAGER': '/bin/cat', 09:07:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:43 INFO - 'PWD': '/builds/slave/test', 09:07:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:43 INFO - 'SHELL': '/bin/bash', 09:07:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHVEW4EGXa/Listeners', 09:07:43 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:07:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:43 INFO - 'USER': 'cltbld', 09:07:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:43 INFO - 'XPC_FLAGS': '0x0', 09:07:43 INFO - 'XPC_SERVICE_NAME': '0', 09:07:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:44 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:07:44 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:07:44 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:07:44 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:07:44 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:07:44 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:07:44 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:07:44 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:07:44 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:07:44 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:07:44 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:07:44 INFO - Cleaning up... 09:07:44 INFO - Return code: 0 09:07:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:07: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:07:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07: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:07:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:44 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 0x100f40b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f43768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5724d3250>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100d1ed30>, '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 0x100c45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d13be8>, '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.fOYbtiDass/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.DHVEW4EGXa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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:07:44 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:07:44 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:07:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fOYbtiDass/Render', 09:07:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:44 INFO - 'HOME': '/Users/cltbld', 09:07:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:44 INFO - 'LOGNAME': 'cltbld', 09:07:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:44 INFO - 'MOZ_NO_REMOTE': '1', 09:07:44 INFO - 'NO_EM_RESTART': '1', 09:07:44 INFO - 'PAGER': '/bin/cat', 09:07:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:44 INFO - 'PWD': '/builds/slave/test', 09:07:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:44 INFO - 'SHELL': '/bin/bash', 09:07:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHVEW4EGXa/Listeners', 09:07:44 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:07:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:44 INFO - 'USER': 'cltbld', 09:07:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:44 INFO - 'XPC_FLAGS': '0x0', 09:07:44 INFO - 'XPC_SERVICE_NAME': '0', 09:07:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:07:44 INFO - Cleaning up... 09:07:44 INFO - Return code: 0 09:07:44 INFO - Running post-action listener: _resource_record_post_action 09:07:44 INFO - Running post-action listener: _start_resource_monitoring 09:07:44 INFO - Starting resource monitoring. 09:07:44 INFO - [mozharness: 2017-05-19 16:07:44.400000Z] Finished create-virtualenv step (success) 09:07:44 INFO - [mozharness: 2017-05-19 16:07:44.401030Z] Running install step. 09:07:44 INFO - Running pre-action listener: _resource_record_pre_action 09:07:44 INFO - Running main action method: install 09:07:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:07:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:07:44 INFO - Reading from file tmpfile_stdout 09:07:44 INFO - Detecting whether we're running mozinstall >=1.0... 09:07:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:07:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:07:44 INFO - Reading from file tmpfile_stdout 09:07:44 INFO - Output received: 09:07:44 INFO - Usage: mozinstall [options] installer 09:07:44 INFO - Options: 09:07:44 INFO - -h, --help show this help message and exit 09:07:44 INFO - -d DEST, --destination=DEST 09:07:44 INFO - Directory to install application into. [default: 09:07:44 INFO - "/builds/slave/test"] 09:07:44 INFO - --app=APP Application being installed. [default: firefox] 09:07:44 INFO - mkdir: /builds/slave/test/build/application 09:07:44 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:07:44 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:07:59 INFO - Reading from file tmpfile_stdout 09:07:59 INFO - Output received: 09:07:59 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:07:59 INFO - Running post-action listener: _resource_record_post_action 09:07:59 INFO - [mozharness: 2017-05-19 16:07:59.493483Z] Finished install step (success) 09:07:59 INFO - [mozharness: 2017-05-19 16:07:59.493633Z] Running run-tests step. 09:07:59 INFO - Running pre-action listener: _resource_record_pre_action 09:07:59 INFO - Running main action method: run_tests 09:07:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:07:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:07:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:07:59 INFO - 2017-05-19 09:07:59.507 screenresolution[2285:16984] starting screenresolution argv=screenresolution get 09:07:59 INFO - 2017-05-19 09:07:59.510 screenresolution[2285:16984] Display 0: 1600x1200x32@60 09:07:59 INFO - 2017-05-19 09:07:59.515 screenresolution[2286:16986] starting screenresolution argv=screenresolution list 09:07:59 INFO - Available Modes on Display 0 09:07:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:07:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:07:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:07:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:07:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:07:59 INFO - Intel Iris: 09:07:59 INFO - Chipset Model: Intel Iris 09:07:59 INFO - Type: GPU 09:07:59 INFO - Bus: Built-In 09:07:59 INFO - VRAM (Dynamic, Max): 1536 MB 09:07:59 INFO - Vendor: Intel (0x8086) 09:07:59 INFO - Device ID: 0x0a2e 09:07:59 INFO - Revision ID: 0x0009 09:07:59 INFO - Displays: 09:07:59 INFO - Display: 09:07:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:07:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:07:59 INFO - Display Serial Number: 1600x1200 60 09:07:59 INFO - Main Display: Yes 09:07:59 INFO - Mirror: Off 09:07:59 INFO - Online: Yes 09:07:59 INFO - Rotation: Supported 09:07:59 INFO - Return code: 0 09:07:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:07:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:07:59 INFO - Python 2.7.3 09:07:59 INFO - Return code: 0 09:07:59 INFO - grabbing minidump binary from tooltool 09:07:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:59 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 0x100d1ed30>, '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 0x100c45db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100d13be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:07:59 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:07:59 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:07:59 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:07:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:07:59 INFO - Return code: 0 09:07:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:07:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:07:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:07:59 INFO - ENV: RUST_BACKTRACE is now 1 09:07:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:07:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:07:59 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/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--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:07:59 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/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0353 --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:07:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fOYbtiDass/Render', 09:07:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:59 INFO - 'HOME': '/Users/cltbld', 09:07:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:59 INFO - 'LOGNAME': 'cltbld', 09:07:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:07:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:59 INFO - 'MOZ_NO_REMOTE': '1', 09:07:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:07:59 INFO - 'NO_EM_RESTART': '1', 09:07:59 INFO - 'PAGER': '/bin/cat', 09:07:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:59 INFO - 'PWD': '/builds/slave/test', 09:07:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:07:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:59 INFO - 'RUST_BACKTRACE': '1', 09:07:59 INFO - 'SHELL': '/bin/bash', 09:07:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.DHVEW4EGXa/Listeners', 09:07:59 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 09:07:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:59 INFO - 'USER': 'cltbld', 09:07:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:59 INFO - 'XPC_FLAGS': '0x0', 09:07:59 INFO - 'XPC_SERVICE_NAME': '0', 09:07:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:59 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/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--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:08:00 INFO - mozversion application_buildid: 20170519074619 09:08:00 INFO - mozversion application_changeset: 61932206bf73a5c510f97eb3089b823b97b1141e 09:08:00 INFO - mozversion application_display_name: Firefox 09:08:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:08:00 INFO - mozversion application_name: Firefox 09:08:00 INFO - mozversion application_remotingname: firefox 09:08:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:08:00 INFO - mozversion application_vendor: Mozilla 09:08:00 INFO - mozversion application_version: 54.0 09:08:00 INFO - mozversion platform_buildid: 20170519074619 09:08:00 INFO - mozversion platform_changeset: 61932206bf73a5c510f97eb3089b823b97b1141e 09:08:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:08:00 INFO - mozversion platform_version: 54.0 09:08:00 INFO - using testdate: 1495210080 09:08:00 INFO - actual date: 1495210080 09:08:00 INFO - starting webserver on 'localhost:49664' 09:08:00 INFO - SUITE-START | Running 2 tests 09:08:00 INFO - TEST-START | dromaeo_css 09:08:00 INFO - operating with platform_type : mac_ 09:08:00 INFO - Initialising browser for dromaeo_css test... 09:08:00 INFO - TEST-INFO | started process 2296 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpALsEXZ/profile http://localhost:49664/getInfo.html) 09:08:02 INFO - PROCESS | 2296 | 2017-05-19 09:08:02.853 plugin-container[2298:17137] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d47, name = 'com.apple.tsm.portname' 09:08:02 INFO - PROCESS | 2296 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:08:02 INFO - PROCESS | 2296 | 2017-05-19 09:08:02.854 plugin-container[2298:17137] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 09:08:02 INFO - PROCESS | 2296 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:08:02 INFO - PROCESS | 2296 | 2017-05-19 09:08:02.854 plugin-container[2298:17137] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:08:02 INFO - PROCESS | 2296 | __metrics Screen width/height:1600/1200 09:08:02 INFO - PROCESS | 2296 | colorDepth:24 09:08:02 INFO - PROCESS | 2296 | Browser inner width/height: 1024/689 09:08:02 INFO - PROCESS | 2296 | __metrics 09:08:02 ERROR - PROCESS | 2296 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 09:08:03 INFO - TEST-INFO | 2296: exit 0 09:08:03 INFO - Browser initialized. 09:08:03 INFO - Running cycle 1/1 for dromaeo_css test... 09:08:03 INFO - TEST-INFO | started process 2299 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpALsEXZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:05 INFO - PROCESS | 2299 | 2017-05-19 09:08:05.973 plugin-container[2300:17268] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x854b, name = 'com.apple.tsm.portname' 09:08:05 INFO - PROCESS | 2299 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:08:05 INFO - PROCESS | 2299 | 2017-05-19 09:08:05.973 plugin-container[2300:17268] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b03, name = 'com.apple.CFPasteboardClient' 09:08:05 INFO - PROCESS | 2299 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:08:05 INFO - PROCESS | 2299 | 2017-05-19 09:08:05.973 plugin-container[2300:17268] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:08:15 INFO - PROCESS | 2299 | RSS: Main: 174194688 09:08:15 INFO - PROCESS | 2299 | 09:11:18 INFO - PROCESS | 2299 | Cycle 1(1): loaded http://localhost:49664/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49664/tests/dromaeo/cssquery-ext.html) 09:11:20 INFO - PROCESS | 2299 | RSS: Main: 190087168 09:11:20 INFO - PROCESS | 2299 | 09:14:22 INFO - PROCESS | 2299 | Cycle 1(1): loaded http://localhost:49664/tests/dromaeo/cssquery-ext.html (next: http://localhost:49664/tests/dromaeo/cssquery-jquery.html) 09:14:22 INFO - PROCESS | 2299 | RSS: Main: 202698752 09:14:22 INFO - PROCESS | 2299 | 09:17:24 INFO - PROCESS | 2299 | Cycle 1(1): loaded http://localhost:49664/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49664/tests/dromaeo/cssquery-mootools.html) 09:17:25 INFO - PROCESS | 2299 | RSS: Main: 204210176 09:17:25 INFO - PROCESS | 2299 | 09:20:27 INFO - PROCESS | 2299 | Cycle 1(1): loaded http://localhost:49664/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49664/tests/dromaeo/cssquery-prototype.html) 09:20:27 INFO - PROCESS | 2299 | RSS: Main: 207384576 09:20:27 INFO - PROCESS | 2299 | 09:23:30 INFO - PROCESS | 2299 | Cycle 1(1): loaded http://localhost:49664/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49664/tests/dromaeo/cssquery-yui.html) 09:23:31 INFO - PROCESS | 2299 | RSS: Main: 209903616 09:23:31 INFO - PROCESS | 2299 | 09:26:34 INFO - PROCESS | 2299 | Cycle 1(1): loaded http://localhost:49664/tests/dromaeo/cssquery-yui.html (next: http://localhost:49664/tests/dromaeo/cssquery-dojo.html) 09:26:34 INFO - PROCESS | 2299 | RSS: Main: 208027648 09:26:34 INFO - PROCESS | 2299 | 09:26:34 INFO - PROCESS | 2299 | __start_tp_report 09:26:34 INFO - PROCESS | 2299 | _x_x_mozilla_page_load 09:26:34 INFO - PROCESS | 2299 | _x_x_mozilla_page_load_details 09:26:34 INFO - PROCESS | 2299 | |i|pagename|runs| 09:26:34 INFO - PROCESS | 2299 | |0;dojo.html;3017.969820301798;3085.645150807657;3584.7490675652684;3607.1523192049863;3632.4369722692977;6163.260408750951;6175.289841668209;6197.659128747917;6200.875982480347;6218.906716399258;6157.815265542041;6184.56708030438;6190.938090619093;6217.782377616784;6222.346653601375;5644.181593668925;5721.799737009205;5733.254676891998;5734.254546908896;5783.219265399181;5775.480206781389;5782.24830771999;5787.1608616750755;5789.073748200289;5818.738156782955;5521.861953451155;5547.750351234184;5578.386377498472;5764.394738552454;5776.162456443813;5685;5690.317161940573;5694.601377903507;5739.684317362543;5758.193852860602;5640.182173584807;5695.886082278373;5712.571557133231;5713.428657134295;5776.364599894008;6225.844353891143;6236.220472440945;6248.87502249957;6255.437010669061;6256.906146407808;6280;6303.527235457359;6399.968000159969;6423.132877061584;6554.016897465371;6681.899771503431;6692.330766923317;6696.2968888267005;6701.530892837503;6702;6246.593971391845;6302.527310451735;6303.275123360826;6328.8101356958905;6343.619382837044;6431.1317972073175;6437.839054023687;6539.2479864815205;6766.69549870257;6794.048833163361;6356.682165891687;6400.839979000562;6409.839754006187;6422.518311126591;6430.196225471816;5784.623999440023;6155.230596175478;6622.966885165543;6627.768028118992;6629;6280;6416.358364163546;6455.644939528277;6557.049227861933;6564.704588293538;5834.299884013945;6089.187877106585;6331.113644089831;6501.544891857619;6527.314631963575;4466.642668586505;5201.323827902348;5277.313949186581;5307.283516725192;5400.594955378362;4742.691725037862;4768.237082066853;4772.880677983078;4782.163121453732;4807.831725889577;5252.317198764213;6246.406591373812;6257.43683068526;6285.685715714196;6324.177856878577;6613.570117942319;6708.463322934153;6713.765018224339;6724.025016372696;6741;6245.938190507624;6277.372262773777;6316.30520642729;6327.303996560375;6333.746650133943;5687.914681279784;5731.627444216113;5918.4377484139795;5943.375945525744;5950.761969521171;6743.258241593421;6764.985252212109;6804.897926531007;6811.318868113149;6845.555038922654;6662.800115996528;6665.866682666417;6682.064510968566;6685.899711504234;6718.697658605277;6535.967320163369;6801.965990170018;6812.795616131524;6846.623435710884;6851.520393572402;6371.267304260069;6513.8697226056165;6541.378569035934;6568.244651865097;6591.274959754327;6685.364890335411;6700.028495867974;6718.093057437183;6718.664066796739;6724.226713927961;6392.137061496638;6498.252700939263;6516.413522782972;6531.314212007671;6541.901871472027;6156.1381406604005;6238.844028899314;6327.272363678236;6338.841528961812;6463.806085817433;6265.8120256392385;6360.014197799349;6375;6380.106785050004;6384.9042264367;6188.164597779242;6420.711068001858;6471.838204044749;6486.156799616208;6488.870222595616;6234.127222188988;6252.749890004349;6280.434760871543;6284.905726414016;6341.55609107358 09:26:34 INFO - PROCESS | 2299 | |1;ext.html;779.7582749347703;831.846108469933;859.7334826203877;997.6707686463468;999.6551189839507;40190.799046004766;41208.38187427187;42486.7875660622;42519.787401063026;42522.57477425225;41466.37800432988;41646.791766041206;41675.79162104193;41705.374419383734;41785.79107104468;8338.95830520848;8481.703140390087;11443.198976071637;11593.884061159364;11898.583549575767;11507.07943364529;11608.825867611993;11638.476268567892;11642.592509262178;11644.475998580041;11571.363575003372;11633.302001879914;11646.417679116053;11646.650600481998;11683.182177247554;11573.074154067654;11589.884101158965;11595.594154204562;11611.883881161164;11612.245204061752;11283.605073822377;11309.660710178709;11321.716957076056;11332.376719280437;11375.658730238107;35442.46836297458;35445;35612.64387356119;35613.82193089044;35662.46506302407;3920.862769803043;3951.604839516054;3984.442178095069;3994.380870965005;4060.3909413587903;26026;26242.606360904603;26457.074002409823;27843.16470505888;27962.440751184862;2825.307799589093;3221.8562410344225;3276.311974485361;3339.215284408162;3446.724262059054;361419.19290403643;366172.16913915524;366984;367852;368052;46449.7677511617;46486.76756616263;46525.53474465212;46771;46802.297965530546;47865.282020769715;47946.76026619845;48095;48130;48173.75913120482;14702.705945881022;14800.259987000607;15004;15035.398584056735;15060.774088388684;12706.745865082832;12842;12889.355532223351;12907.419166137546;12921;10324.690259292232;12247.326397048248;12355.011599072053;12400.875991240153;12424.503019879285;4934.728589927517;5701.287339082614;5769.826905192851;5802.912956305659;5830.5627077968475;1138.248755821154;1159.565163063851;1180.5749930025183;1190.5297407523979;1197.8921897029309;27736.445271094868;28227.57658635122;28269.15192544227;28348.724307406217;28492.572611410844;1260.2564486952742;1275.9234445933273;1289.6711338608595;1306.8105124756858;1341.1751772659743;14020.929895350459;14342.139471631735;14442.061266017676;14541;14645.853541464448;486662;491281;491591;492058;492110;58930.82138357295;59362.40637593655;59442.70278648579;59511;59547.404525955055;1479.9704005920037;1489.7839813227022;1518.9392424302905;1522.62695639569;1528.656052388226;23022.654660179775;27625.861870690518;28825.855870720512;29011.83952641955;29025.274368140967;41795.7910210447;46396.7680161597;47778.28332575084;49903.500964989886;49933.751656208886;415718;418863;420584;420954;421439;31364;31375;31420.528692070093;31570.684293157694;31594.842025789723;32440.351192975537;39937.60062399455;40014.79992600018;40457.190856182126;40607.796961015;28054.158375248775;28669.283267917635;29138;29208.415831683673;29234.56148157737;28651;29366;29489.410211796072;29633.555496667137;29803.95686150974;33786.32427351488;34146.31707365789;34309.656903430645;34344.48483272703;34654.306913862085;25406.110786122397;27370.768315426252;27816.86091569529;29059.709402906545;30075;21928.780712192674;22878.542429151657;23164.420889477897;24438;24449 09:26:34 INFO - PROCESS | 2299 | |2;jquery.html;413.2375766710422;433.5426125437664;436.05811447273874;438.66003847018555;454.54545454545456;20262.696059559083;20441.693374599392;20513.487162820937;21145.57708845824;22787;20795.584088318225;21952.45118872029;22390.440238994033;23041.308760737204;23063.538729225405;5938.9703051484685;6822.454203663695;7126.465515086364;7163.534370265916;7174.461915356343;6114.571979961404;6259.718312675917;6855.794326170223;7009.649517524129;7052.576845389267;6748.696308666097;6918.34275743806;7001.334873187039;7057.047298614698;7061.50569460138;6961.095057642525;6978.4417246620405;6980.022796808451;7021.508494405393;7061.858762824766;6811.024802807229;6871.072405225281;6889.379955803952;6898.931010689879;6901.896471552931;27115.593266101023;27148.321291967663;27571.448571028668;27745.58381624277;27911.720882791116;3025.410045041218;3034.150437877398;3046.7257946784894;3059.8470076496087;3072.7695422843376;16320.591985200346;16501.0924399158;17023.91488042552;17044.14779261032;17407;2071.9171233150655;2072.8549001569895;2105.147415296807;2119.7774233705395;2120.692499587551;136430;138641.30679346638;141437.29281353528;141819.2909035448;142614.28692856574;21567.13731450756;22469.10123595038;22678.092876284765;22736;22741;36519.991400472696;36711.63288367136;36905.630943690754;37558.24883502314;37599.62400376016;11198.440077996067;11420.771584568261;11648.14473507246;11731.530738770354;11863.525458981716;9375.437473751597;9380.5309734513;9417.952910235543;9520.381175223589;9573.377730447499;10644.308119972176;10905.727356816144;10925.180611454172;10949.726256843642;11005.17461190398;3870.845166193377;4480.38706065816;4534.659900507475;4534.77326133692;4572.9314060289125;1034.5758239121924;1061.9362838229576;1077.1167642533205;1083.6099004358425;1126.459299536211;21457.892710536347;21809.56380872374;21865.453363666034;21893.452663683216;21928.89035554812;901.963921443135;1070.0743856564015;1085.7014321061772;1108.545496346494;1132.4677401621225;11096.1123110151;11631.185816992729;11738.002269806992;11755.177137600285;11767.11746619007;748583;757365.2131739416;772200;797263;797725.0113749395;96064;101403.49298253609;111564;135529.3223533876;139561.30219348837;1414.1161773891317;1464.7290251303543;1485.3538710660896;1487.4050379847931;1488.3971991343524;17022.14889255557;17966;18155.81844181594;19420.640555161004;20754.688679669514;57136.71431642815;59390.81218375522;60541.69729151326;61554.38445615487;61640.38359616346;67202;77311.84032239634;79605.80591291252;83584.58207708923;84741;10889.673309800719;12416.379181040731;12501.24992500453;12521.248725076524;12873.22760634365;13589.86410135886;13599.320033998063;13866.237356945465;13995.090319129224;14079.788803167756;11562.88437115618;11812.940935295268;12074.939625301817;12083.879161208275;12088.51645934188;10824.134069274596;10844.837327440253;11078.446077696244;11402.48688808989;11404.885951140383;11675.416229188337;11794.941025294818;11855.288682679067;12091.274523528264;12188.268703877795;12302.81545776832;12333.321667308384;12559.811602825783;12579.245245284947;12781;9868.358556693793;11732.589359372381;11874.109441791727;11979.401029948642;11987.523618150988 09:26:34 INFO - PROCESS | 2299 | |3;mootools.html;1543.9768403473947;1578.0768250573415;1614.2493740410707;1630.7635392868035;1636.860866826319;9765.12113909749;11037.392943388111;11461.885381146187;11733;11838.05295576354;11581.710457238572;11775.587854425097;11789.941050294736;11796.292222466658;11809.055275577955;2484.6148846928754;2523.5962246040635;2527.7093134289516;2531.1267612673596;2532.822702410832;2448.8775561221955;2497.1509686706518;2535.4422027154087;2539.162076514746;2542.0975553678454;2180.898646183675;2467.938301542458;2493.4265119022634;2504.949901001979;2527.8736063196766;2449.5504495504497;2475.504899020203;2484.5900426429616;2495.0768215760195;2500.6624105745673;2111.8944052797297;2258.2660635293596;2469.1358024691394;2492.289697436222;2499.5875680512695;10504.686914135733;10830.296030758054;11641.35972521511;11744.882551174464;11750.58872939443;6073.271207455089;6221.471174950135;6251.937480625181;6255.093011513305;6258.186435763321;3682.2451397463456;3748.0629842539365;3812.2375524895133;3815.866444674451;3880.1463677990796;1253.780588397036;1528.7019031288903;1697.533178375944;1964.1652297773533;2069.068918986462;14477.927610361881;14743.336549855283;14997.95014348996;16193.190340483046;16371.345146194139;4831.323614693945;4849.951500485021;4976.328195693535;5001.524855138756;5005.874853128628;12080.818787718192;12152.027837772957;12156.331401772988;12160.939195304143;12163.817542736866;6084.361142080106;6134.938650613526;6145.26256849172;6146.907796383058;6147.784827531104;4820.903581928342;5509.614326997072;5516.751746171351;5619.8595035124445;5669.971650141723;3994.2410941920944;4070.531888832763;4093.97953010239;4134.966258435391;4183.769892655872;1099.2195541165702;1101.1961268274204;1102.101787043557;1113.2429947635685;1119.205364191433;789.8420315936836;825.793551612097;846.627483907079;850.9276711479594;852.8464876322197;6674.199096108498;6676.833079172962;6736.124303840469;6905.482088843357;6963.547369420971;949.0462085603924;1022.9335093218917;1048.1614708233535;1056.445366182745;1079.4548752879746;3640.381135207021;3926.980365098156;3969.861054863066;4239.236937351307;4328.350747387917;13802.309884505734;14384.424623015171;14423.855761442252;14434.350454229585;14434.783478247835;13408.731825363438;14336.713265734836;14337.426502939765;14341.498047568284;14345.139291642536;9123.452592844318;10509.316894401993;10757.408342541079;10825;10833;6199.28708198563;6940.340667636567;7095.787126386217;7104.3961263293195;7210.819729506804;12110.939445302716;12135.635930922086;12151.453184606537;12447.439865205906;12624.621261362174;12381.62855114348;12831.03767217481;12941.741165176456;12995.480180792661;13190.670233243862;9412.19996300322;9419.952900235729;9592.136707696342;9624.0375962406;9658;10588.523516441932;10625.203109766644;10707.982741639531;10720.410377429316;10728.570857165625;8909.55452227399;9082.318826088202;9086.318526110435;9150.039245879087;9490.240780737386;8880.422772519765;8924.910750892668;8937;9001.414908030956;9057.411268267542;8105.9867516560425;8239.917600823916;8267.049289331804;8296.045954715284;8307.169283071644;6005.849853753691;6018.307894592178;6056.515478761777;6096.908546371897;6229.875402491834;5186.611004174777;5960.225170727777;6271.153394291712;6305.432511074026;6340.809775706736 09:26:34 INFO - PROCESS | 2299 | |4;prototype.html;294.7155994465341;396.10085106807566;398.9840406383745;403.3767828704649;405.5316109893073;4669.463011753649;4857.271409288608;5103.413107492639;5210.394802598701;5457.42697016813;4675.2753323234865;4752.90494190116;5217.947820521794;5282.23407605897;5456.781728730846;4024.7182697211206;4208.936865946998;4245.961938269617;4249.10914642984;4284.914301713971;3515.6132825389186;3903;4265.317549192115;4276.097136774137;4318.330658747884;3816.0772603341793;3901.2977664020464;4144.772037537934;4187.706860519765;4230.640395566365;3863.1456900687685;4174.937375939363;4200.432941552913;4218.409422680827;4233.539428897026;3746.850125994957;3784.9621503784883;3785.9242815143816;3791.241751649681;3798.8860334190017;4953.6037117030555;5080.923786143211;5257.421683614799;5295.187465025176;5335.306410166692;4422.098497646011;4460.219461594208;4570;4917.704937703714;4983.700977941335;5242.580593552507;5545.611807173499;5804.332501762267;5821.67980761058;5903.5572332074835;4485.394471746337;4505.43682039745;4514.9774251128865;4515.932261016087;4685.5314468553215;5148.91872706732;5859;5964.821055368346;6056.697165141725;6162.568620196588;4636.327732478771;4752.95247047532;4795.784189711471;4818.734969576637;4833.516648335139;4814;4835.250536166829;4837.274408838632;4866.270059491105;4867.9269810952865;4285.550017248206;5030.748462576857;5050.72221027847;5178.818741344035;5239.738013099406;5747.798827041033;5766.365699773021;5781.566382521328;5805.8548536286935;5829.591928565044;1373.3957058894055;1615.6768646270557;1629.2485578003693;1633.3058450158635;1636.7626694129285;1178.5915830582371;1182.645206438065;1224.412282104577;1234.6234398508361;1235.9629211123681;4979.676321039121;5471.868739102032;5508.614396992172;5524.4199359067525;5529.668219906818;5383;5764.694122351009;5994.28068631767;5999.490043346277;6029.201602990088;4639.837605683785;4666.673332866667;4876.001259225551;5022.296878437021;5488.4785945335125;2951.6900725423952;3115.759610005569;3118.469860123784;3127.2643682755834;3141.513065474855;5174.508421699932;5268.710220937808;5624.325080990226;5733.541316694655;6263.022980407474;5772.843552642091;6179.632100505235;6193.628382297076;6211.05597945308;6218.60081481672;5506.669599824023;5636.718164091779;5689.601727879009;6116.480099191618;6284.811455656338;5322.733863306897;5443.39419871131;5500.532454741232;5500.862478438071;6092.482139018233;5355.634313250096;5388.919166212589;5443.591730620298;5705.686187259741;5778.7864548445295;5823.475887170175;5866.901049729875;6064.909026364519;6258.058712405163;6325.462335701517;4269.380939763779;4433.89152711822;4443.822247110079;4678.485366609738;4703.412073490814;5308;5545.61359660085;5611.7194140292;5888.440598143169;6029.045767573945;5289.338832645919;5393.487618676219;5883.323417807007;5924.170616113834;5973.5519836013345;5042.823501177441;5211.27312637185;5364.30264065669;5466.835994920159;5590.888182236414;5681.346645135863;5724.313082430135;5740.6268592541355;5781.692638983984;5792.21805056312;5347.598930080338;5665.716714164193;5695.857603559944;5699.002674532023;5964.403559644001;4652.906566956694;5532.695701736443;5598.888022239451;5708.372079071381;5742.885142297214 09:26:34 INFO - PROCESS | 2299 | |5;yui.html;714.8141483214363;769.0732667136098;803.8593246181916;890.9064548222437;896.5203616065404;14996.325165367558;17177.74233386497;17200.53394998933;17466.650666986654;17906.73139902899;16362.018278903273;16577.83422165778;16732;17463.91268043658;18128.637427251448;1017.893121222274;1031.747221930628;1038.5118994072811;1042.426665334067;1043.8747350317972;996.2428554298749;1030.9226807989394;1034.6895931220604;1037.5694086953904;1046.9528871200814;1087.7008822573775;1090.6837017264984;1095.6548687163558;1099.5217080569937;1100.2573263047411;1077.8221593437074;1090.0353187429182;1091.7652704668455;1093.415022962715;1093.4314156638543;818.2799136759672;819.6598411659214;820.0937963550251;820.6143112737004;823.3989187892872;17483.737743933852;17659.940403575827;17718.29126834925;18401.35595254173;18651.974141422215;14570.05294655844;14930.25348732559;14936.850631493655;14987.32557034936;15054.322555485029;12165.635030949086;12214.022878169724;12288.631341059783;12412.689682757913;12956.222626642431;3848.3136296097564;3905.941410878839;3948.2300951314505;3970.285348637244;3995;121922.39038804838;124046.37976810148;126706.73293267042;127423.7257627421;129263;11171.83242251367;11221.719457013476;11545.538178472767;11659.76680466386;11727.941360293144;23756.168534101223;23855.284341469436;24004.63993040106;24014.51970960571;24081.879590601933;8018.238267364708;8287.12985136564;8312.418130730912;8336.124706905808;8340.708075217339;4593.862184134481;4624.213883639789;4665.763572653182;4744.383230180055;4758.1673207188605;3665.725070619714;3730.477733117366;3776.358019136698;3776.546814382237;3838.8272527736317;753.1978442958341;759.9772006839804;760.3688938181297;776.9417293702995;778.7118766056595;636.7739452494391;720.5172534401963;724.1925253342486;743.2381808646181;761.6534476813036;9460.864696236358;10550.472476376304;10722.678319650424;10896;11018.39398833056;691.3155975584135;705.5696025424487;719.4963525532148;720.4920531025614;737.9520331178456;5118.309028281209;5235.685858848481;5326.813561525328;5353.9196912046355;5382.865428364244;105804;107656;108284;108399.3550441913;108483;78076;97580.53629195568;100138.49792253123;101099.49450252701;102733.48633256786;81299;81962.18037819545;82371.3525729494;83118.75321870176;84548;8221.917780822116;10171.338862973884;10227.795444090927;10232.181425486093;10275.383476991405;47733.806654833905;52348.90604375782;54521.454785451635;54879.725601373335;55803;67221.99167012596;67509;68066.65966670135;68747;68959;12324.81512777327;12612.513830603864;12700;13112.950963922673;13361.465541378237;13450.46198152063;13451.58245143332;13680.908082952832;13850.30748462593;13869.653258668613;11567.826482602599;12488.937555312164;12687.175333603287;12903.612891613267;12968.481260749464;12418.503259869503;12696.428660710106;12862.485500579873;12868.549600763929;13119.313441137787;12097;12157.08821838348;12464;12813.094899045074;12959.805602916153;9098.727038188865;9311.580978855833;9327;9362.251019918527;9474;9591.376560523544;10315.690529284133;10600.1519878408;10799.838002429813;10837.45812709377 09:26:34 INFO - PROCESS | 2299 | __end_tp_report 09:26:34 INFO - PROCESS | 2299 | __start_cc_report 09:26:34 INFO - PROCESS | 2299 | _x_x_mozilla_cycle_collect,392 09:26:34 INFO - PROCESS | 2299 | __end_cc_report 09:26:34 INFO - PROCESS | 2299 | __startTimestamp1495211194454__endTimestamp 09:26:34 INFO - PROCESS | 2299 | 09:26:34 INFO - PROCESS | 2299 | ------- Summary: start ------- 09:26:34 INFO - PROCESS | 2299 | Number of tests: 6 09:26:34 INFO - PROCESS | 2299 | 09:26:34 INFO - PROCESS | 2299 | [#0] dojo.html Cycles:165 Average:6127.17 Median:6303.28 stddev:681.20 (10.8%) stddev-sans-first:638.13 09:26:34 INFO - PROCESS | 2299 | Values: 3018.0 3085.6 3584.7 3607.2 3632.4 6163.3 6175.3 6197.7 6200.9 6218.9 6157.8 6184.6 6190.9 6217.8 6222.3 5644.2 5721.8 5733.3 5734.3 5783.2 5775.5 5782.2 5787.2 5789.1 5818.7 5521.9 5547.8 5578.4 5764.4 5776.2 5685.0 5690.3 5694.6 5739.7 5758.2 5640.2 5695.9 5712.6 5713.4 5776.4 6225.8 6236.2 6248.9 6255.4 6256.9 6280.0 6303.5 6400.0 6423.1 6554.0 6681.9 6692.3 6696.3 6701.5 6702.0 6246.6 6302.5 6303.3 6328.8 6343.6 6431.1 6437.8 6539.2 6766.7 6794.0 6356.7 6400.8 6409.8 6422.5 6430.2 5784.6 6155.2 6623.0 6627.8 6629.0 6280.0 6416.4 6455.6 6557.0 6564.7 5834.3 6089.2 6331.1 6501.5 6527.3 4466.6 5201.3 5277.3 5307.3 5400.6 4742.7 4768.2 4772.9 4782.2 4807.8 5252.3 6246.4 6257.4 6285.7 6324.2 6613.6 6708.5 6713.8 6724.0 6741.0 6245.9 6277.4 6316.3 6327.3 6333.7 5687.9 5731.6 5918.4 5943.4 5950.8 6743.3 6765.0 6804.9 6811.3 6845.6 6662.8 6665.9 6682.1 6685.9 6718.7 6536.0 6802.0 6812.8 6846.6 6851.5 6371.3 6513.9 6541.4 6568.2 6591.3 6685.4 6700.0 6718.1 6718.7 6724.2 6392.1 6498.3 6516.4 6531.3 6541.9 6156.1 6238.8 6327.3 6338.8 6463.8 6265.8 6360.0 6375.0 6380.1 6384.9 6188.2 6420.7 6471.8 6486.2 6488.9 6234.1 6252.7 6280.4 6284.9 6341.6 09:26:34 INFO - PROCESS | 2299 | 09:26:34 INFO - PROCESS | 2299 | [#1] ext.html Cycles:180 Average:56150.88 Median:29561.48 stddev:113693.74 (384.6%) stddev-sans-first:113936.68 09:26:34 INFO - PROCESS | 2299 | Values: 779.8 831.8 859.7 997.7 999.7 40190.8 41208.4 42486.8 42519.8 42522.6 41466.4 41646.8 41675.8 41705.4 41785.8 8339.0 8481.7 11443.2 11593.9 11898.6 11507.1 11608.8 11638.5 11642.6 11644.5 11571.4 11633.3 11646.4 11646.7 11683.2 11573.1 11589.9 11595.6 11611.9 11612.2 11283.6 11309.7 11321.7 11332.4 11375.7 35442.5 35445.0 35612.6 35613.8 35662.5 3920.9 3951.6 3984.4 3994.4 4060.4 26026.0 26242.6 26457.1 27843.2 27962.4 2825.3 3221.9 3276.3 3339.2 3446.7 361419.2 366172.2 366984.0 367852.0 368052.0 46449.8 46486.8 46525.5 46771.0 46802.3 47865.3 47946.8 48095.0 48130.0 48173.8 14702.7 14800.3 15004.0 15035.4 15060.8 12706.7 12842.0 12889.4 12907.4 12921.0 10324.7 12247.3 12355.0 12400.9 12424.5 4934.7 5701.3 5769.8 5802.9 5830.6 1138.2 1159.6 1180.6 1190.5 1197.9 27736.4 28227.6 28269.2 28348.7 28492.6 1260.3 1275.9 1289.7 1306.8 1341.2 14020.9 14342.1 14442.1 14541.0 14645.9 486662.0 491281.0 491591.0 492058.0 492110.0 58930.8 59362.4 59442.7 59511.0 59547.4 1480.0 1489.8 1518.9 1522.6 1528.7 23022.7 27625.9 28825.9 29011.8 29025.3 41795.8 46396.8 47778.3 49903.5 49933.8 415718.0 418863.0 420584.0 420954.0 421439.0 31364.0 31375.0 31420.5 31570.7 31594.8 32440.4 39937.6 40014.8 40457.2 40607.8 28054.2 28669.3 29138.0 29208.4 29234.6 28651.0 29366.0 29489.4 29633.6 29804.0 33786.3 34146.3 34309.7 34344.5 34654.3 25406.1 27370.8 27816.9 29059.7 30075.0 21928.8 22878.5 23164.4 24438.0 24449.0 09:26:34 INFO - PROCESS | 2299 | 09:26:34 INFO - PROCESS | 2299 | [#2] jquery.html Cycles:180 Average:42617.32 Median:2120.23 stddev:127827.42 (6028.9%) stddev-sans-first:128146.73 09:26:34 INFO - PROCESS | 2299 | Values: 413.2 433.5 436.1 438.7 454.5 20262.7 20441.7 20513.5 21145.6 22787.0 20795.6 21952.5 22390.4 23041.3 23063.5 5939.0 6822.5 7126.5 7163.5 7174.5 6114.6 6259.7 6855.8 7009.6 7052.6 6748.7 6918.3 7001.3 7057.0 7061.5 6961.1 6978.4 6980.0 7021.5 7061.9 6811.0 6871.1 6889.4 6898.9 6901.9 27115.6 27148.3 27571.4 27745.6 27911.7 3025.4 3034.2 3046.7 3059.8 3072.8 16320.6 16501.1 17023.9 17044.1 17407.0 2071.9 2072.9 2105.1 2119.8 2120.7 136430.0 138641.3 141437.3 141819.3 142614.3 21567.1 22469.1 22678.1 22736.0 22741.0 36520.0 36711.6 36905.6 37558.2 37599.6 11198.4 11420.8 11648.1 11731.5 11863.5 9375.4 9380.5 9418.0 9520.4 9573.4 10644.3 10905.7 10925.2 10949.7 11005.2 3870.8 4480.4 4534.7 4534.8 4572.9 1034.6 1061.9 1077.1 1083.6 1126.5 21457.9 21809.6 21865.5 21893.5 21928.9 902.0 1070.1 1085.7 1108.5 1132.5 11096.1 11631.2 11738.0 11755.2 11767.1 748583.0 757365.2 772200.0 797263.0 797725.0 96064.0 101403.5 111564.0 135529.3 139561.3 1414.1 1464.7 1485.4 1487.4 1488.4 17022.1 17966.0 18155.8 19420.6 20754.7 57136.7 59390.8 60541.7 61554.4 61640.4 67202.0 77311.8 79605.8 83584.6 84741.0 10889.7 12416.4 12501.2 12521.2 12873.2 13589.9 13599.3 13866.2 13995.1 14079.8 11562.9 11812.9 12074.9 12083.9 12088.5 10824.1 10844.8 11078.4 11402.5 11404.9 11675.4 11794.9 11855.3 12091.3 12188.3 12302.8 12333.3 12559.8 12579.2 12781.0 9868.4 11732.6 11874.1 11979.4 11987.5 09:26:34 INFO - PROCESS | 2299 | 09:26:34 INFO - PROCESS | 2299 | [#3] mootools.html Cycles:180 Average:6980.26 Median:2527.79 stddev:4333.00 (171.4%) stddev-sans-first:4325.90 09:26:34 INFO - PROCESS | 2299 | Values: 1544.0 1578.1 1614.2 1630.8 1636.9 9765.1 11037.4 11461.9 11733.0 11838.1 11581.7 11775.6 11789.9 11796.3 11809.1 2484.6 2523.6 2527.7 2531.1 2532.8 2448.9 2497.2 2535.4 2539.2 2542.1 2180.9 2467.9 2493.4 2504.9 2527.9 2449.6 2475.5 2484.6 2495.1 2500.7 2111.9 2258.3 2469.1 2492.3 2499.6 10504.7 10830.3 11641.4 11744.9 11750.6 6073.3 6221.5 6251.9 6255.1 6258.2 3682.2 3748.1 3812.2 3815.9 3880.1 1253.8 1528.7 1697.5 1964.2 2069.1 14477.9 14743.3 14998.0 16193.2 16371.3 4831.3 4850.0 4976.3 5001.5 5005.9 12080.8 12152.0 12156.3 12160.9 12163.8 6084.4 6134.9 6145.3 6146.9 6147.8 4820.9 5509.6 5516.8 5619.9 5670.0 3994.2 4070.5 4094.0 4135.0 4183.8 1099.2 1101.2 1102.1 1113.2 1119.2 789.8 825.8 846.6 850.9 852.8 6674.2 6676.8 6736.1 6905.5 6963.5 949.0 1022.9 1048.2 1056.4 1079.5 3640.4 3927.0 3969.9 4239.2 4328.4 13802.3 14384.4 14423.9 14434.4 14434.8 13408.7 14336.7 14337.4 14341.5 14345.1 9123.5 10509.3 10757.4 10825.0 10833.0 6199.3 6940.3 7095.8 7104.4 7210.8 12110.9 12135.6 12151.5 12447.4 12624.6 12381.6 12831.0 12941.7 12995.5 13190.7 9412.2 9420.0 9592.1 9624.0 9658.0 10588.5 10625.2 10708.0 10720.4 10728.6 8909.6 9082.3 9086.3 9150.0 9490.2 8880.4 8924.9 8937.0 9001.4 9057.4 8106.0 8239.9 8267.0 8296.0 8307.2 6005.8 6018.3 6056.5 6096.9 6229.9 5186.6 5960.2 6271.2 6305.4 6340.8 09:26:34 INFO - PROCESS | 2299 | 09:26:34 INFO - PROCESS | 2299 | [#4] prototype.html Cycles:180 Average:4774.58 Median:5210.83 stddev:1350.49 (25.9%) stddev-sans-first:1311.75 09:26:34 INFO - PROCESS | 2299 | Values: 294.7 396.1 399.0 403.4 405.5 4669.5 4857.3 5103.4 5210.4 5457.4 4675.3 4752.9 5217.9 5282.2 5456.8 4024.7 4208.9 4246.0 4249.1 4284.9 3515.6 3903.0 4265.3 4276.1 4318.3 3816.1 3901.3 4144.8 4187.7 4230.6 3863.1 4174.9 4200.4 4218.4 4233.5 3746.9 3785.0 3785.9 3791.2 3798.9 4953.6 5080.9 5257.4 5295.2 5335.3 4422.1 4460.2 4570.0 4917.7 4983.7 5242.6 5545.6 5804.3 5821.7 5903.6 4485.4 4505.4 4515.0 4515.9 4685.5 5148.9 5859.0 5964.8 6056.7 6162.6 4636.3 4753.0 4795.8 4818.7 4833.5 4814.0 4835.3 4837.3 4866.3 4867.9 4285.6 5030.7 5050.7 5178.8 5239.7 5747.8 5766.4 5781.6 5805.9 5829.6 1373.4 1615.7 1629.2 1633.3 1636.8 1178.6 1182.6 1224.4 1234.6 1236.0 4979.7 5471.9 5508.6 5524.4 5529.7 5383.0 5764.7 5994.3 5999.5 6029.2 4639.8 4666.7 4876.0 5022.3 5488.5 2951.7 3115.8 3118.5 3127.3 3141.5 5174.5 5268.7 5624.3 5733.5 6263.0 5772.8 6179.6 6193.6 6211.1 6218.6 5506.7 5636.7 5689.6 6116.5 6284.8 5322.7 5443.4 5500.5 5500.9 6092.5 5355.6 5388.9 5443.6 5705.7 5778.8 5823.5 5866.9 6064.9 6258.1 6325.5 4269.4 4433.9 4443.8 4678.5 4703.4 5308.0 5545.6 5611.7 5888.4 6029.0 5289.3 5393.5 5883.3 5924.2 5973.6 5042.8 5211.3 5364.3 5466.8 5590.9 5681.3 5724.3 5740.6 5781.7 5792.2 5347.6 5665.7 5695.9 5699.0 5964.4 4652.9 5532.7 5598.9 5708.4 5742.9 09:26:34 INFO - PROCESS | 2299 | 09:26:34 INFO - PROCESS | 2299 | [#5] yui.html Cycles:180 Average:21845.19 Median:17465.28 stddev:32247.18 (184.6%) stddev-sans-first:32298.61 09:26:34 INFO - PROCESS | 2299 | Values: 714.8 769.1 803.9 890.9 896.5 14996.3 17177.7 17200.5 17466.7 17906.7 16362.0 16577.8 16732.0 17463.9 18128.6 1017.9 1031.7 1038.5 1042.4 1043.9 996.2 1030.9 1034.7 1037.6 1047.0 1087.7 1090.7 1095.7 1099.5 1100.3 1077.8 1090.0 1091.8 1093.4 1093.4 818.3 819.7 820.1 820.6 823.4 17483.7 17659.9 17718.3 18401.4 18652.0 14570.1 14930.3 14936.9 14987.3 15054.3 12165.6 12214.0 12288.6 12412.7 12956.2 3848.3 3905.9 3948.2 3970.3 3995.0 121922.4 124046.4 126706.7 127423.7 129263.0 11171.8 11221.7 11545.5 11659.8 11727.9 23756.2 23855.3 24004.6 24014.5 24081.9 8018.2 8287.1 8312.4 8336.1 8340.7 4593.9 4624.2 4665.8 4744.4 4758.2 3665.7 3730.5 3776.4 3776.5 3838.8 753.2 760.0 760.4 776.9 778.7 636.8 720.5 724.2 743.2 761.7 9460.9 10550.5 10722.7 10896.0 11018.4 691.3 705.6 719.5 720.5 738.0 5118.3 5235.7 5326.8 5353.9 5382.9 105804.0 107656.0 108284.0 108399.4 108483.0 78076.0 97580.5 100138.5 101099.5 102733.5 81299.0 81962.2 82371.4 83118.8 84548.0 8221.9 10171.3 10227.8 10232.2 10275.4 47733.8 52348.9 54521.5 54879.7 55803.0 67222.0 67509.0 68066.7 68747.0 68959.0 12324.8 12612.5 12700.0 13113.0 13361.5 13450.5 13451.6 13680.9 13850.3 13869.7 11567.8 12488.9 12687.2 12903.6 12968.5 12418.5 12696.4 12862.5 12868.5 13119.3 12097.0 12157.1 12464.0 12813.1 12959.8 9098.7 9311.6 9327.0 9362.3 9474.0 9591.4 10315.7 10600.2 10799.8 10837.5 09:26:34 INFO - PROCESS | 2299 | -------- Summary: end -------- 09:26:34 INFO - PROCESS | 2299 | 09:26:35 INFO - TEST-INFO | 2299: exit 0 09:26:35 INFO - TEST-OK | dromaeo_css | took 1114931ms 09:26:35 INFO - TEST-START | kraken 09:26:35 INFO - operating with platform_type : mac_ 09:26:35 INFO - Initialising browser for kraken test... 09:26:35 INFO - TEST-INFO | started process 2305 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpqB2bGz/profile http://localhost:49664/getInfo.html) 09:26:37 INFO - PROCESS | 2305 | 2017-05-19 09:26:37.620 plugin-container[2307:19581] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 09:26:37 INFO - PROCESS | 2305 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:37 INFO - PROCESS | 2305 | 2017-05-19 09:26:37.620 plugin-container[2307:19581] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 09:26:37 INFO - PROCESS | 2305 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:37 INFO - PROCESS | 2305 | 2017-05-19 09:26:37.620 plugin-container[2307:19581] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:26:37 INFO - PROCESS | 2305 | __metrics Screen width/height:1600/1200 09:26:37 INFO - PROCESS | 2305 | colorDepth:24 09:26:37 INFO - PROCESS | 2305 | Browser inner width/height: 1024/689 09:26:37 INFO - PROCESS | 2305 | __metrics 09:26:37 ERROR - PROCESS | 2305 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 09:26:38 INFO - TEST-INFO | 2305: exit 0 09:26:38 INFO - Browser initialized. 09:26:38 INFO - Running cycle 1/1 for kraken test... 09:26:38 INFO - TEST-INFO | started process 2308 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpqB2bGz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:26:40 INFO - PROCESS | 2308 | 2017-05-19 09:26:40.587 plugin-container[2309:19713] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 09:26:40 INFO - PROCESS | 2308 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:40 INFO - PROCESS | 2308 | 2017-05-19 09:26:40.587 plugin-container[2309:19713] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x841b, name = 'com.apple.CFPasteboardClient' 09:26:40 INFO - PROCESS | 2308 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:26:40 INFO - PROCESS | 2308 | 2017-05-19 09:26:40.587 plugin-container[2309:19713] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:26:50 INFO - PROCESS | 2308 | RSS: Main: 174346240 09:26:50 INFO - PROCESS | 2308 | 09:26:53 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:49664/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49664/tests/kraken/driver.html?testName=audio-beat-detection) 09:26:54 INFO - PROCESS | 2308 | RSS: Main: 187981824 09:26:54 INFO - PROCESS | 2308 | 09:26:56 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:49664/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49664/tests/kraken/driver.html?testName=audio-dft) 09:26:57 INFO - PROCESS | 2308 | RSS: Main: 195883008 09:26:57 INFO - PROCESS | 2308 | 09:27:00 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:49664/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49664/tests/kraken/driver.html?testName=audio-fft) 09:27:01 INFO - PROCESS | 2308 | RSS: Main: 199618560 09:27:01 INFO - PROCESS | 2308 | 09:27:03 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:49664/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49664/tests/kraken/driver.html?testName=audio-oscillator) 09:27:03 INFO - PROCESS | 2308 | RSS: Main: 201101312 09:27:03 INFO - PROCESS | 2308 | 09:27:05 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:49664/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49664/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:27:06 INFO - PROCESS | 2308 | RSS: Main: 201150464 09:27:06 INFO - PROCESS | 2308 | 09:27:09 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:49664/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49664/tests/kraken/driver.html?testName=imaging-darkroom) 09:27:09 INFO - PROCESS | 2308 | RSS: Main: 205250560 09:27:09 INFO - PROCESS | 2308 | 09:27:12 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:49664/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49664/tests/kraken/driver.html?testName=imaging-desaturate) 09:27:13 INFO - PROCESS | 2308 | RSS: Main: 205238272 09:27:13 INFO - PROCESS | 2308 | 09:27:16 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:49664/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49664/tests/kraken/driver.html?testName=json-parse-financial) 09:27:17 INFO - PROCESS | 2308 | RSS: Main: 204681216 09:27:17 INFO - PROCESS | 2308 | 09:27:18 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:49664/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49664/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:27:19 INFO - PROCESS | 2308 | RSS: Main: 204177408 09:27:19 INFO - PROCESS | 2308 | 09:27:21 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:49664/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49664/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:27:21 INFO - PROCESS | 2308 | RSS: Main: 204247040 09:27:21 INFO - PROCESS | 2308 | 09:27:23 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:49664/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49664/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:27:24 INFO - PROCESS | 2308 | RSS: Main: 204234752 09:27:24 INFO - PROCESS | 2308 | 09:27:27 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:49664/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49664/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:27:28 INFO - PROCESS | 2308 | RSS: Main: 204251136 09:27:28 INFO - PROCESS | 2308 | 09:27:30 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:49664/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49664/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:27:31 INFO - PROCESS | 2308 | RSS: Main: 204603392 09:27:31 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:49664/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49664/tests/kraken/driver.html?testName=ai-astar) 09:27:33 INFO - PROCESS | 2308 | RSS: Main: 206807040 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | __start_tp_report 09:27:33 INFO - PROCESS | 2308 | _x_x_mozilla_page_load 09:27:33 INFO - PROCESS | 2308 | _x_x_mozilla_page_load_details 09:27:33 INFO - PROCESS | 2308 | |i|pagename|runs| 09:27:33 INFO - PROCESS | 2308 | |0;ai-astar;105;115;115;116;140;120;118;118;117;116 09:27:33 INFO - PROCESS | 2308 | |1;audio-beat-detection;139;128;144;151;110;112;138;98;112;123 09:27:33 INFO - PROCESS | 2308 | |2;audio-dft;195;199;196;195;200;202;177;272;274;201 09:27:33 INFO - PROCESS | 2308 | |3;audio-fft;83;60;58;86;62;61;59;60;72;59 09:27:33 INFO - PROCESS | 2308 | |4;audio-oscillator;93;71;84;72;72;70;81;69;70;82 09:27:33 INFO - PROCESS | 2308 | |5;imaging-gaussian-blur;88;88;88;94;89;87;88;88;107;89 09:27:33 INFO - PROCESS | 2308 | |6;imaging-darkroom;94;97;94;93;95;94;127;95;95;96 09:27:33 INFO - PROCESS | 2308 | |7;imaging-desaturate;80;139;144;94;90;91;92;89;88;102 09:27:33 INFO - PROCESS | 2308 | |8;json-parse-financial;65;61;64;67;65;66;67;68;68;67 09:27:33 INFO - PROCESS | 2308 | |9;json-stringify-tinderbox;58;62;56;63;61;58;58;60;57;58 09:27:33 INFO - PROCESS | 2308 | |10;stanford-crypto-aes;84;81;83;85;83;86;83;81;87;84 09:27:33 INFO - PROCESS | 2308 | |11;stanford-crypto-ccm;136;134;169;142;136;133;146;141;203;176 09:27:33 INFO - PROCESS | 2308 | |12;stanford-crypto-pbkdf2;164;162;163;164;169;175;164;169;180;139 09:27:33 INFO - PROCESS | 2308 | |13;stanford-crypto-sha256-iterative;67;67;64;63;64;79;70;64;64;64 09:27:33 INFO - PROCESS | 2308 | __end_tp_report 09:27:33 INFO - PROCESS | 2308 | __start_cc_report 09:27:33 INFO - PROCESS | 2308 | _x_x_mozilla_cycle_collect,972 09:27:33 INFO - PROCESS | 2308 | __end_cc_report 09:27:33 INFO - PROCESS | 2308 | __startTimestamp1495211253578__endTimestamp 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | ------- Summary: start ------- 09:27:33 INFO - PROCESS | 2308 | Number of tests: 14 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | [#0] ai-astar Cycles:10 Average:118.00 Median:117.50 stddev:8.72 (7.4%) stddev-sans-first:7.88 09:27:33 INFO - PROCESS | 2308 | Values: 105.0 115.0 115.0 116.0 140.0 120.0 118.0 118.0 117.0 116.0 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | [#1] audio-beat-detection Cycles:10 Average:125.50 Median:138.50 stddev:17.33 (12.5%) stddev-sans-first:17.68 09:27:33 INFO - PROCESS | 2308 | Values: 139.0 128.0 144.0 151.0 110.0 112.0 138.0 98.0 112.0 123.0 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | [#2] audio-dft Cycles:10 Average:211.10 Median:200.50 stddev:33.38 (16.6%) stddev-sans-first:34.89 09:27:33 INFO - PROCESS | 2308 | Values: 195.0 199.0 196.0 195.0 200.0 202.0 177.0 272.0 274.0 201.0 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | [#3] audio-fft Cycles:10 Average:66.00 Median:61.50 stddev:10.54 (17.1%) stddev-sans-first:9.21 09:27:33 INFO - PROCESS | 2308 | Values: 83.0 60.0 58.0 86.0 62.0 61.0 59.0 60.0 72.0 59.0 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | [#4] audio-oscillator Cycles:10 Average:76.40 Median:76.50 stddev:8.10 (10.6%) stddev-sans-first:5.96 09:27:33 INFO - PROCESS | 2308 | Values: 93.0 71.0 84.0 72.0 72.0 70.0 81.0 69.0 70.0 82.0 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | [#5] imaging-gaussian-blur Cycles:10 Average:90.60 Median:88.00 stddev:6.08 (6.9%) stddev-sans-first:6.37 09:27:33 INFO - PROCESS | 2308 | Values: 88.0 88.0 88.0 94.0 89.0 87.0 88.0 88.0 107.0 89.0 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | [#6] imaging-darkroom Cycles:10 Average:98.00 Median:95.00 stddev:10.25 (10.8%) stddev-sans-first:10.77 09:27:33 INFO - PROCESS | 2308 | Values: 94.0 97.0 94.0 93.0 95.0 94.0 127.0 95.0 95.0 96.0 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | [#7] imaging-desaturate Cycles:10 Average:100.90 Median:89.50 stddev:22.11 (24.7%) stddev-sans-first:22.12 09:27:33 INFO - PROCESS | 2308 | Values: 80.0 139.0 144.0 94.0 90.0 91.0 92.0 89.0 88.0 102.0 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:67.00 stddev:2.15 (3.2%) stddev-sans-first:2.26 09:27:33 INFO - PROCESS | 2308 | Values: 65.0 61.0 64.0 67.0 65.0 66.0 67.0 68.0 68.0 67.0 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | [#9] json-stringify-tinderbox Cycles:10 Average:59.10 Median:59.00 stddev:2.28 (3.9%) stddev-sans-first:2.39 09:27:33 INFO - PROCESS | 2308 | Values: 58.0 62.0 56.0 63.0 61.0 58.0 58.0 60.0 57.0 58.0 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.00 stddev:1.95 (2.3%) stddev-sans-first:2.06 09:27:33 INFO - PROCESS | 2308 | Values: 84.0 81.0 83.0 85.0 83.0 86.0 83.0 81.0 87.0 84.0 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | [#11] stanford-crypto-ccm Cycles:10 Average:151.60 Median:144.00 stddev:23.38 (16.2%) stddev-sans-first:24.10 09:27:33 INFO - PROCESS | 2308 | Values: 136.0 134.0 169.0 142.0 136.0 133.0 146.0 141.0 203.0 176.0 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.90 Median:166.50 stddev:10.80 (6.5%) stddev-sans-first:11.45 09:27:33 INFO - PROCESS | 2308 | Values: 164.0 162.0 163.0 164.0 169.0 175.0 164.0 169.0 180.0 139.0 09:27:33 INFO - PROCESS | 2308 | 09:27:33 INFO - PROCESS | 2308 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:65.50 stddev:4.86 (7.4%) stddev-sans-first:5.15 09:27:33 INFO - PROCESS | 2308 | Values: 67.0 67.0 64.0 63.0 64.0 79.0 70.0 64.0 64.0 64.0 09:27:33 INFO - PROCESS | 2308 | -------- Summary: end -------- 09:27:33 INFO - PROCESS | 2308 | 09:27:34 INFO - TEST-INFO | 2308: exit 0 09:27:34 INFO - TEST-OK | kraken | took 59342ms 09:27:34 INFO - SUITE-END | took 1174s 09:27:34 INFO - Completed test suite (00:19:34) 09:27:34 INFO - javascript benchmark 09:27:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7998.762033456973, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3017.969820301798, 3085.645150807657, 3584.7490675652684, 3607.1523192049863, 3632.4369722692977, 6163.260408750951, 6175.289841668209, 6197.659128747917, 6200.875982480347, 6218.906716399258, 6157.815265542041, 6184.56708030438, 6190.938090619093, 6217.782377616784, 6222.346653601375, 5644.181593668925, 5721.799737009205, 5733.254676891998, 5734.254546908896, 5783.219265399181, 5775.480206781389, 5782.24830771999, 5787.1608616750755, 5789.073748200289, 5818.738156782955, 5521.861953451155, 5547.750351234184, 5578.386377498472, 5764.394738552454, 5776.162456443813, 5685.0, 5690.317161940573, 5694.601377903507, 5739.684317362543, 5758.193852860602, 5640.182173584807, 5695.886082278373, 5712.571557133231, 5713.428657134295, 5776.364599894008, 6225.844353891143, 6236.220472440945, 6248.87502249957, 6255.437010669061, 6256.906146407808, 6280.0, 6303.527235457359, 6399.968000159969, 6423.132877061584, 6554.016897465371, 6681.899771503431, 6692.330766923317, 6696.2968888267005, 6701.530892837503, 6702.0, 6246.593971391845, 6302.527310451735, 6303.275123360826, 6328.8101356958905, 6343.619382837044, 6431.1317972073175, 6437.839054023687, 6539.2479864815205, 6766.69549870257, 6794.048833163361, 6356.682165891687, 6400.839979000562, 6409.839754006187, 6422.518311126591, 6430.196225471816, 5784.623999440023, 6155.230596175478, 6622.966885165543, 6627.768028118992, 6629.0, 6280.0, 6416.358364163546, 6455.644939528277, 6557.049227861933, 6564.704588293538, 5834.299884013945, 6089.187877106585, 6331.113644089831, 6501.544891857619, 6527.314631963575, 4466.642668586505, 5201.323827902348, 5277.313949186581, 5307.283516725192, 5400.594955378362, 4742.691725037862, 4768.237082066853, 4772.880677983078, 4782.163121453732, 4807.831725889577, 5252.317198764213, 6246.406591373812, 6257.43683068526, 6285.685715714196, 6324.177856878577, 6613.570117942319, 6708.463322934153, 6713.765018224339, 6724.025016372696, 6741.0, 6245.938190507624, 6277.372262773777, 6316.30520642729, 6327.303996560375, 6333.746650133943, 5687.914681279784, 5731.627444216113, 5918.4377484139795, 5943.375945525744, 5950.761969521171, 6743.258241593421, 6764.985252212109, 6804.897926531007, 6811.318868113149, 6845.555038922654, 6662.800115996528, 6665.866682666417, 6682.064510968566, 6685.899711504234, 6718.697658605277, 6535.967320163369, 6801.965990170018, 6812.795616131524, 6846.623435710884, 6851.520393572402, 6371.267304260069, 6513.8697226056165, 6541.378569035934, 6568.244651865097, 6591.274959754327, 6685.364890335411, 6700.028495867974, 6718.093057437183, 6718.664066796739, 6724.226713927961, 6392.137061496638, 6498.252700939263, 6516.413522782972, 6531.314212007671, 6541.901871472027, 6156.1381406604005, 6238.844028899314, 6327.272363678236, 6338.841528961812, 6463.806085817433, 6265.8120256392385, 6360.014197799349, 6375.0, 6380.106785050004, 6384.9042264367, 6188.164597779242, 6420.711068001858, 6471.838204044749, 6486.156799616208, 6488.870222595616, 6234.127222188988, 6252.749890004349, 6280.434760871543, 6284.905726414016, 6341.55609107358], "value": 6082.118034074977, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [779.7582749347703, 831.846108469933, 859.7334826203877, 997.6707686463468, 999.6551189839507, 40190.799046004766, 41208.38187427187, 42486.7875660622, 42519.787401063026, 42522.57477425225, 41466.37800432988, 41646.791766041206, 41675.79162104193, 41705.374419383734, 41785.79107104468, 8338.95830520848, 8481.703140390087, 11443.198976071637, 11593.884061159364, 11898.583549575767, 11507.07943364529, 11608.825867611993, 11638.476268567892, 11642.592509262178, 11644.475998580041, 11571.363575003372, 11633.302001879914, 11646.417679116053, 11646.650600481998, 11683.182177247554, 11573.074154067654, 11589.884101158965, 11595.594154204562, 11611.883881161164, 11612.245204061752, 11283.605073822377, 11309.660710178709, 11321.716957076056, 11332.376719280437, 11375.658730238107, 35442.46836297458, 35445.0, 35612.64387356119, 35613.82193089044, 35662.46506302407, 3920.862769803043, 3951.604839516054, 3984.442178095069, 3994.380870965005, 4060.3909413587903, 26026.0, 26242.606360904603, 26457.074002409823, 27843.16470505888, 27962.440751184862, 2825.307799589093, 3221.8562410344225, 3276.311974485361, 3339.215284408162, 3446.724262059054, 361419.19290403643, 366172.16913915524, 366984.0, 367852.0, 368052.0, 46449.7677511617, 46486.76756616263, 46525.53474465212, 46771.0, 46802.297965530546, 47865.282020769715, 47946.76026619845, 48095.0, 48130.0, 48173.75913120482, 14702.705945881022, 14800.259987000607, 15004.0, 15035.398584056735, 15060.774088388684, 12706.745865082832, 12842.0, 12889.355532223351, 12907.419166137546, 12921.0, 10324.690259292232, 12247.326397048248, 12355.011599072053, 12400.875991240153, 12424.503019879285, 4934.728589927517, 5701.287339082614, 5769.826905192851, 5802.912956305659, 5830.5627077968475, 1138.248755821154, 1159.565163063851, 1180.5749930025183, 1190.5297407523979, 1197.8921897029309, 27736.445271094868, 28227.57658635122, 28269.15192544227, 28348.724307406217, 28492.572611410844, 1260.2564486952742, 1275.9234445933273, 1289.6711338608595, 1306.8105124756858, 1341.1751772659743, 14020.929895350459, 14342.139471631735, 14442.061266017676, 14541.0, 14645.853541464448, 486662.0, 491281.0, 491591.0, 492058.0, 492110.0, 58930.82138357295, 59362.40637593655, 59442.70278648579, 59511.0, 59547.404525955055, 1479.9704005920037, 1489.7839813227022, 1518.9392424302905, 1522.62695639569, 1528.656052388226, 23022.654660179775, 27625.861870690518, 28825.855870720512, 29011.83952641955, 29025.274368140967, 41795.7910210447, 46396.7680161597, 47778.28332575084, 49903.500964989886, 49933.751656208886, 415718.0, 418863.0, 420584.0, 420954.0, 421439.0, 31364.0, 31375.0, 31420.528692070093, 31570.684293157694, 31594.842025789723, 32440.351192975537, 39937.60062399455, 40014.79992600018, 40457.190856182126, 40607.796961015, 28054.158375248775, 28669.283267917635, 29138.0, 29208.415831683673, 29234.56148157737, 28651.0, 29366.0, 29489.410211796072, 29633.555496667137, 29803.95686150974, 33786.32427351488, 34146.31707365789, 34309.656903430645, 34344.48483272703, 34654.306913862085, 25406.110786122397, 27370.768315426252, 27816.86091569529, 29059.709402906545, 30075.0, 21928.780712192674, 22878.542429151657, 23164.420889477897, 24438.0, 24449.0], "value": 19080.770536066753, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.2375766710422, 433.5426125437664, 436.05811447273874, 438.66003847018555, 454.54545454545456, 20262.696059559083, 20441.693374599392, 20513.487162820937, 21145.57708845824, 22787.0, 20795.584088318225, 21952.45118872029, 22390.440238994033, 23041.308760737204, 23063.538729225405, 5938.9703051484685, 6822.454203663695, 7126.465515086364, 7163.534370265916, 7174.461915356343, 6114.571979961404, 6259.718312675917, 6855.794326170223, 7009.649517524129, 7052.576845389267, 6748.696308666097, 6918.34275743806, 7001.334873187039, 7057.047298614698, 7061.50569460138, 6961.095057642525, 6978.4417246620405, 6980.022796808451, 7021.508494405393, 7061.858762824766, 6811.024802807229, 6871.072405225281, 6889.379955803952, 6898.931010689879, 6901.896471552931, 27115.593266101023, 27148.321291967663, 27571.448571028668, 27745.58381624277, 27911.720882791116, 3025.410045041218, 3034.150437877398, 3046.7257946784894, 3059.8470076496087, 3072.7695422843376, 16320.591985200346, 16501.0924399158, 17023.91488042552, 17044.14779261032, 17407.0, 2071.9171233150655, 2072.8549001569895, 2105.147415296807, 2119.7774233705395, 2120.692499587551, 136430.0, 138641.30679346638, 141437.29281353528, 141819.2909035448, 142614.28692856574, 21567.13731450756, 22469.10123595038, 22678.092876284765, 22736.0, 22741.0, 36519.991400472696, 36711.63288367136, 36905.630943690754, 37558.24883502314, 37599.62400376016, 11198.440077996067, 11420.771584568261, 11648.14473507246, 11731.530738770354, 11863.525458981716, 9375.437473751597, 9380.5309734513, 9417.952910235543, 9520.381175223589, 9573.377730447499, 10644.308119972176, 10905.727356816144, 10925.180611454172, 10949.726256843642, 11005.17461190398, 3870.845166193377, 4480.38706065816, 4534.659900507475, 4534.77326133692, 4572.9314060289125, 1034.5758239121924, 1061.9362838229576, 1077.1167642533205, 1083.6099004358425, 1126.459299536211, 21457.892710536347, 21809.56380872374, 21865.453363666034, 21893.452663683216, 21928.89035554812, 901.963921443135, 1070.0743856564015, 1085.7014321061772, 1108.545496346494, 1132.4677401621225, 11096.1123110151, 11631.185816992729, 11738.002269806992, 11755.177137600285, 11767.11746619007, 748583.0, 757365.2131739416, 772200.0, 797263.0, 797725.0113749395, 96064.0, 101403.49298253609, 111564.0, 135529.3223533876, 139561.30219348837, 1414.1161773891317, 1464.7290251303543, 1485.3538710660896, 1487.4050379847931, 1488.3971991343524, 17022.14889255557, 17966.0, 18155.81844181594, 19420.640555161004, 20754.688679669514, 57136.71431642815, 59390.81218375522, 60541.69729151326, 61554.38445615487, 61640.38359616346, 67202.0, 77311.84032239634, 79605.80591291252, 83584.58207708923, 84741.0, 10889.673309800719, 12416.379181040731, 12501.24992500453, 12521.248725076524, 12873.22760634365, 13589.86410135886, 13599.320033998063, 13866.237356945465, 13995.090319129224, 14079.788803167756, 11562.88437115618, 11812.940935295268, 12074.939625301817, 12083.879161208275, 12088.51645934188, 10824.134069274596, 10844.837327440253, 11078.446077696244, 11402.48688808989, 11404.885951140383, 11675.416229188337, 11794.941025294818, 11855.288682679067, 12091.274523528264, 12188.268703877795, 12302.81545776832, 12333.321667308384, 12559.811602825783, 12579.245245284947, 12781.0, 9868.358556693793, 11732.589359372381, 11874.109441791727, 11979.401029948642, 11987.523618150988], "value": 12010.777880429609, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1543.9768403473947, 1578.0768250573415, 1614.2493740410707, 1630.7635392868035, 1636.860866826319, 9765.12113909749, 11037.392943388111, 11461.885381146187, 11733.0, 11838.05295576354, 11581.710457238572, 11775.587854425097, 11789.941050294736, 11796.292222466658, 11809.055275577955, 2484.6148846928754, 2523.5962246040635, 2527.7093134289516, 2531.1267612673596, 2532.822702410832, 2448.8775561221955, 2497.1509686706518, 2535.4422027154087, 2539.162076514746, 2542.0975553678454, 2180.898646183675, 2467.938301542458, 2493.4265119022634, 2504.949901001979, 2527.8736063196766, 2449.5504495504497, 2475.504899020203, 2484.5900426429616, 2495.0768215760195, 2500.6624105745673, 2111.8944052797297, 2258.2660635293596, 2469.1358024691394, 2492.289697436222, 2499.5875680512695, 10504.686914135733, 10830.296030758054, 11641.35972521511, 11744.882551174464, 11750.58872939443, 6073.271207455089, 6221.471174950135, 6251.937480625181, 6255.093011513305, 6258.186435763321, 3682.2451397463456, 3748.0629842539365, 3812.2375524895133, 3815.866444674451, 3880.1463677990796, 1253.780588397036, 1528.7019031288903, 1697.533178375944, 1964.1652297773533, 2069.068918986462, 14477.927610361881, 14743.336549855283, 14997.95014348996, 16193.190340483046, 16371.345146194139, 4831.323614693945, 4849.951500485021, 4976.328195693535, 5001.524855138756, 5005.874853128628, 12080.818787718192, 12152.027837772957, 12156.331401772988, 12160.939195304143, 12163.817542736866, 6084.361142080106, 6134.938650613526, 6145.26256849172, 6146.907796383058, 6147.784827531104, 4820.903581928342, 5509.614326997072, 5516.751746171351, 5619.8595035124445, 5669.971650141723, 3994.2410941920944, 4070.531888832763, 4093.97953010239, 4134.966258435391, 4183.769892655872, 1099.2195541165702, 1101.1961268274204, 1102.101787043557, 1113.2429947635685, 1119.205364191433, 789.8420315936836, 825.793551612097, 846.627483907079, 850.9276711479594, 852.8464876322197, 6674.199096108498, 6676.833079172962, 6736.124303840469, 6905.482088843357, 6963.547369420971, 949.0462085603924, 1022.9335093218917, 1048.1614708233535, 1056.445366182745, 1079.4548752879746, 3640.381135207021, 3926.980365098156, 3969.861054863066, 4239.236937351307, 4328.350747387917, 13802.309884505734, 14384.424623015171, 14423.855761442252, 14434.350454229585, 14434.783478247835, 13408.731825363438, 14336.713265734836, 14337.426502939765, 14341.498047568284, 14345.139291642536, 9123.452592844318, 10509.316894401993, 10757.408342541079, 10825.0, 10833.0, 6199.28708198563, 6940.340667636567, 7095.787126386217, 7104.3961263293195, 7210.819729506804, 12110.939445302716, 12135.635930922086, 12151.453184606537, 12447.439865205906, 12624.621261362174, 12381.62855114348, 12831.03767217481, 12941.741165176456, 12995.480180792661, 13190.670233243862, 9412.19996300322, 9419.952900235729, 9592.136707696342, 9624.0375962406, 9658.0, 10588.523516441932, 10625.203109766644, 10707.982741639531, 10720.410377429316, 10728.570857165625, 8909.55452227399, 9082.318826088202, 9086.318526110435, 9150.039245879087, 9490.240780737386, 8880.422772519765, 8924.910750892668, 8937.0, 9001.414908030956, 9057.411268267542, 8105.9867516560425, 8239.917600823916, 8267.049289331804, 8296.045954715284, 8307.169283071644, 6005.849853753691, 6018.307894592178, 6056.515478761777, 6096.908546371897, 6229.875402491834, 5186.611004174777, 5960.225170727777, 6271.153394291712, 6305.432511074026, 6340.809775706736], "value": 5346.832625549181, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.7155994465341, 396.10085106807566, 398.9840406383745, 403.3767828704649, 405.5316109893073, 4669.463011753649, 4857.271409288608, 5103.413107492639, 5210.394802598701, 5457.42697016813, 4675.2753323234865, 4752.90494190116, 5217.947820521794, 5282.23407605897, 5456.781728730846, 4024.7182697211206, 4208.936865946998, 4245.961938269617, 4249.10914642984, 4284.914301713971, 3515.6132825389186, 3903.0, 4265.317549192115, 4276.097136774137, 4318.330658747884, 3816.0772603341793, 3901.2977664020464, 4144.772037537934, 4187.706860519765, 4230.640395566365, 3863.1456900687685, 4174.937375939363, 4200.432941552913, 4218.409422680827, 4233.539428897026, 3746.850125994957, 3784.9621503784883, 3785.9242815143816, 3791.241751649681, 3798.8860334190017, 4953.6037117030555, 5080.923786143211, 5257.421683614799, 5295.187465025176, 5335.306410166692, 4422.098497646011, 4460.219461594208, 4570.0, 4917.704937703714, 4983.700977941335, 5242.580593552507, 5545.611807173499, 5804.332501762267, 5821.67980761058, 5903.5572332074835, 4485.394471746337, 4505.43682039745, 4514.9774251128865, 4515.932261016087, 4685.5314468553215, 5148.91872706732, 5859.0, 5964.821055368346, 6056.697165141725, 6162.568620196588, 4636.327732478771, 4752.95247047532, 4795.784189711471, 4818.734969576637, 4833.516648335139, 4814.0, 4835.250536166829, 4837.274408838632, 4866.270059491105, 4867.9269810952865, 4285.550017248206, 5030.748462576857, 5050.72221027847, 5178.818741344035, 5239.738013099406, 5747.798827041033, 5766.365699773021, 5781.566382521328, 5805.8548536286935, 5829.591928565044, 1373.3957058894055, 1615.6768646270557, 1629.2485578003693, 1633.3058450158635, 1636.7626694129285, 1178.5915830582371, 1182.645206438065, 1224.412282104577, 1234.6234398508361, 1235.9629211123681, 4979.676321039121, 5471.868739102032, 5508.614396992172, 5524.4199359067525, 5529.668219906818, 5383.0, 5764.694122351009, 5994.28068631767, 5999.490043346277, 6029.201602990088, 4639.837605683785, 4666.673332866667, 4876.001259225551, 5022.296878437021, 5488.4785945335125, 2951.6900725423952, 3115.759610005569, 3118.469860123784, 3127.2643682755834, 3141.513065474855, 5174.508421699932, 5268.710220937808, 5624.325080990226, 5733.541316694655, 6263.022980407474, 5772.843552642091, 6179.632100505235, 6193.628382297076, 6211.05597945308, 6218.60081481672, 5506.669599824023, 5636.718164091779, 5689.601727879009, 6116.480099191618, 6284.811455656338, 5322.733863306897, 5443.39419871131, 5500.532454741232, 5500.862478438071, 6092.482139018233, 5355.634313250096, 5388.919166212589, 5443.591730620298, 5705.686187259741, 5778.7864548445295, 5823.475887170175, 5866.901049729875, 6064.909026364519, 6258.058712405163, 6325.462335701517, 4269.380939763779, 4433.89152711822, 4443.822247110079, 4678.485366609738, 4703.412073490814, 5308.0, 5545.61359660085, 5611.7194140292, 5888.440598143169, 6029.045767573945, 5289.338832645919, 5393.487618676219, 5883.323417807007, 5924.170616113834, 5973.5519836013345, 5042.823501177441, 5211.27312637185, 5364.30264065669, 5466.835994920159, 5590.888182236414, 5681.346645135863, 5724.313082430135, 5740.6268592541355, 5781.692638983984, 5792.21805056312, 5347.598930080338, 5665.716714164193, 5695.857603559944, 5699.002674532023, 5964.403559644001, 4652.906566956694, 5532.695701736443, 5598.888022239451, 5708.372079071381, 5742.885142297214], "value": 4377.486874544992, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [714.8141483214363, 769.0732667136098, 803.8593246181916, 890.9064548222437, 896.5203616065404, 14996.325165367558, 17177.74233386497, 17200.53394998933, 17466.650666986654, 17906.73139902899, 16362.018278903273, 16577.83422165778, 16732.0, 17463.91268043658, 18128.637427251448, 1017.893121222274, 1031.747221930628, 1038.5118994072811, 1042.426665334067, 1043.8747350317972, 996.2428554298749, 1030.9226807989394, 1034.6895931220604, 1037.5694086953904, 1046.9528871200814, 1087.7008822573775, 1090.6837017264984, 1095.6548687163558, 1099.5217080569937, 1100.2573263047411, 1077.8221593437074, 1090.0353187429182, 1091.7652704668455, 1093.415022962715, 1093.4314156638543, 818.2799136759672, 819.6598411659214, 820.0937963550251, 820.6143112737004, 823.3989187892872, 17483.737743933852, 17659.940403575827, 17718.29126834925, 18401.35595254173, 18651.974141422215, 14570.05294655844, 14930.25348732559, 14936.850631493655, 14987.32557034936, 15054.322555485029, 12165.635030949086, 12214.022878169724, 12288.631341059783, 12412.689682757913, 12956.222626642431, 3848.3136296097564, 3905.941410878839, 3948.2300951314505, 3970.285348637244, 3995.0, 121922.39038804838, 124046.37976810148, 126706.73293267042, 127423.7257627421, 129263.0, 11171.83242251367, 11221.719457013476, 11545.538178472767, 11659.76680466386, 11727.941360293144, 23756.168534101223, 23855.284341469436, 24004.63993040106, 24014.51970960571, 24081.879590601933, 8018.238267364708, 8287.12985136564, 8312.418130730912, 8336.124706905808, 8340.708075217339, 4593.862184134481, 4624.213883639789, 4665.763572653182, 4744.383230180055, 4758.1673207188605, 3665.725070619714, 3730.477733117366, 3776.358019136698, 3776.546814382237, 3838.8272527736317, 753.1978442958341, 759.9772006839804, 760.3688938181297, 776.9417293702995, 778.7118766056595, 636.7739452494391, 720.5172534401963, 724.1925253342486, 743.2381808646181, 761.6534476813036, 9460.864696236358, 10550.472476376304, 10722.678319650424, 10896.0, 11018.39398833056, 691.3155975584135, 705.5696025424487, 719.4963525532148, 720.4920531025614, 737.9520331178456, 5118.309028281209, 5235.685858848481, 5326.813561525328, 5353.9196912046355, 5382.865428364244, 105804.0, 107656.0, 108284.0, 108399.3550441913, 108483.0, 78076.0, 97580.53629195568, 100138.49792253123, 101099.49450252701, 102733.48633256786, 81299.0, 81962.18037819545, 82371.3525729494, 83118.75321870176, 84548.0, 8221.917780822116, 10171.338862973884, 10227.795444090927, 10232.181425486093, 10275.383476991405, 47733.806654833905, 52348.90604375782, 54521.454785451635, 54879.725601373335, 55803.0, 67221.99167012596, 67509.0, 68066.65966670135, 68747.0, 68959.0, 12324.81512777327, 12612.513830603864, 12700.0, 13112.950963922673, 13361.465541378237, 13450.46198152063, 13451.58245143332, 13680.908082952832, 13850.30748462593, 13869.653258668613, 11567.826482602599, 12488.937555312164, 12687.175333603287, 12903.612891613267, 12968.481260749464, 12418.503259869503, 12696.428660710106, 12862.485500579873, 12868.549600763929, 13119.313441137787, 12097.0, 12157.08821838348, 12464.0, 12813.094899045074, 12959.805602916153, 9098.727038188865, 9311.580978855833, 9327.0, 9362.251019918527, 9474.0, 9591.376560523544, 10315.690529284133, 10600.1519878408, 10799.838002429813, 10837.45812709377], "value": 8027.018163758606, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1478.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 115.0, 116.0, 140.0, 120.0, 118.0, 118.0, 117.0, 116.0], "value": 118.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 128.0, 144.0, 151.0, 110.0, 112.0, 138.0, 98.0, 112.0, 123.0], "value": 125.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 199.0, 196.0, 195.0, 200.0, 202.0, 177.0, 272.0, 274.0, 201.0], "value": 211.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 58.0, 86.0, 62.0, 61.0, 59.0, 60.0, 72.0, 59.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 71.0, 84.0, 72.0, 72.0, 70.0, 81.0, 69.0, 70.0, 82.0], "value": 76.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 94.0, 89.0, 87.0, 88.0, 88.0, 107.0, 89.0], "value": 90.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 97.0, 94.0, 93.0, 95.0, 94.0, 127.0, 95.0, 95.0, 96.0], "value": 98.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 139.0, 144.0, 94.0, 90.0, 91.0, 92.0, 89.0, 88.0, 102.0], "value": 100.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 61.0, 64.0, 67.0, 65.0, 66.0, 67.0, 68.0, 68.0, 67.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 56.0, 63.0, 61.0, 58.0, 58.0, 60.0, 57.0, 58.0], "value": 59.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 83.0, 85.0, 83.0, 86.0, 83.0, 81.0, 87.0, 84.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 169.0, 142.0, 136.0, 133.0, 146.0, 141.0, 203.0, 176.0], "value": 151.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 162.0, 163.0, 164.0, 169.0, 175.0, 164.0, 169.0, 180.0, 139.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 64.0, 63.0, 64.0, 79.0, 70.0, 64.0, 64.0, 64.0], "value": 66.6, "unit": "score"}]}]} 09:27:34 INFO - Return code: 0 09:27:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:27:34 ERROR - # TBPL SUCCESS # 09:27:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:27:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:27:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:27:34 INFO - 2017-05-19 09:27:34.893 screenresolution[2313:20092] starting screenresolution argv=screenresolution get 09:27:34 INFO - 2017-05-19 09:27:34.895 screenresolution[2313:20092] Display 0: 1600x1200x32@60 09:27:34 INFO - 2017-05-19 09:27:34.901 screenresolution[2314:20095] starting screenresolution argv=screenresolution list 09:27:34 INFO - Available Modes on Display 0 09:27:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:27:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:27:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:27:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:27:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:27:35 INFO - Intel Iris: 09:27:35 INFO - Chipset Model: Intel Iris 09:27:35 INFO - Type: GPU 09:27:35 INFO - Bus: Built-In 09:27:35 INFO - VRAM (Dynamic, Max): 1536 MB 09:27:35 INFO - Vendor: Intel (0x8086) 09:27:35 INFO - Device ID: 0x0a2e 09:27:35 INFO - Revision ID: 0x0009 09:27:35 INFO - Displays: 09:27:35 INFO - Display: 09:27:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:27:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:27:35 INFO - Display Serial Number: 1600x1200 60 09:27:35 INFO - Main Display: Yes 09:27:35 INFO - Mirror: Off 09:27:35 INFO - Online: Yes 09:27:35 INFO - Rotation: Supported 09:27:35 INFO - Return code: 0 09:27:35 INFO - Running post-action listener: _resource_record_post_action 09:27:35 INFO - [mozharness: 2017-05-19 16:27:35.140769Z] Finished run-tests step (success) 09:27:35 INFO - Running post-run listener: _resource_record_post_run 09:27:35 INFO - Total resource usage - Wall time: 1190s; CPU: 55.0%; Read bytes: 1650688; Write bytes: 425052672; Read time: 17; Write time: 1423 09:27:35 INFO - TinderboxPrint: CPU usage
54.7% 09:27:35 INFO - TinderboxPrint: I/O read bytes / time
1,650,688 / 17 09:27:35 INFO - TinderboxPrint: I/O write bytes / time
425,052,672 / 1,423 09:27:35 INFO - TinderboxPrint: CPU idle
2,156.8 (45.3%) 09:27:35 INFO - TinderboxPrint: CPU system
958.6 (20.1%) 09:27:35 INFO - TinderboxPrint: CPU user
1,646.2 (34.6%) 09:27:35 INFO - TinderboxPrint: Swap in / out
124,198,912 / 0 09:27:35 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 122857984; Write bytes: 124274176; Read time: 11114; Write time: 284 09:27:35 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 1650688; Write bytes: 299521024; Read time: 17; Write time: 1131 09:27:35 INFO - Running post-run listener: _upload_blobber_files 09:27:35 INFO - Blob upload gear active. 09:27:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:27:35 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:27:35 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:27:35 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:27:36 INFO - (blobuploader) - INFO - Open directory for files ... 09:27:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:27:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:27:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:36 INFO - (blobuploader) - INFO - Done attempting. 09:27:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:27:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:27:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:37 INFO - (blobuploader) - INFO - Done attempting. 09:27:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:27:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:27:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:38 INFO - (blobuploader) - INFO - Done attempting. 09:27:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:27:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:27:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:40 INFO - (blobuploader) - INFO - Done attempting. 09:27:40 INFO - (blobuploader) - INFO - Iteration through files over. 09:27:40 INFO - Return code: 0 09:27:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:27:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:27:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6889296fcee8e6c5d95d27098f937e6cf246f223b065054b7e9fa2c89ed4837324ff0fbf63f373ccc0afcf830e35dbd1df90ccbc2463d12745167de9d9434bf1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6d8da9baee48ed01b4d31d7717a4cbf1f9c04d20a5a231e162d24a1a350d189c45d9d2bad7f1a8f889a014a70947fec69bb0fbdd7ed41796ea350417eb11eaea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/18497ecec64b271f706e7d3158442b92ebb0f7d77297311dc15a810d2da5b3f6d7efaab2e41586b950da24c98a87b9db71dbe0a1887dd18328c7dd3847355fba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8eee7c7e79af8ad2ee8761504f1edcf7bc8b3fc2198a24de79ac73f28de74e15d276ec78bde6e3fb9c0cb9706fd21580473fe2027e030b92122e429ace1dd9a3"} 09:27:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:27:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:27:40 INFO - Contents: 09:27:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6889296fcee8e6c5d95d27098f937e6cf246f223b065054b7e9fa2c89ed4837324ff0fbf63f373ccc0afcf830e35dbd1df90ccbc2463d12745167de9d9434bf1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6d8da9baee48ed01b4d31d7717a4cbf1f9c04d20a5a231e162d24a1a350d189c45d9d2bad7f1a8f889a014a70947fec69bb0fbdd7ed41796ea350417eb11eaea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/18497ecec64b271f706e7d3158442b92ebb0f7d77297311dc15a810d2da5b3f6d7efaab2e41586b950da24c98a87b9db71dbe0a1887dd18328c7dd3847355fba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8eee7c7e79af8ad2ee8761504f1edcf7bc8b3fc2198a24de79ac73f28de74e15d276ec78bde6e3fb9c0cb9706fd21580473fe2027e030b92122e429ace1dd9a3"} 09:27:40 INFO - Running post-run listener: copy_logs_to_upload_dir 09:27:40 INFO - Copying logs to upload dir... 09:27:40 INFO - mkdir: /builds/slave/test/build/upload/logs 09:27:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.797855 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-05-19 09:27:40.771380) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 09:27:40.774839) ========= 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.fOYbtiDass/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.DHVEW4EGXa/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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/6889296fcee8e6c5d95d27098f937e6cf246f223b065054b7e9fa2c89ed4837324ff0fbf63f373ccc0afcf830e35dbd1df90ccbc2463d12745167de9d9434bf1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6d8da9baee48ed01b4d31d7717a4cbf1f9c04d20a5a231e162d24a1a350d189c45d9d2bad7f1a8f889a014a70947fec69bb0fbdd7ed41796ea350417eb11eaea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/18497ecec64b271f706e7d3158442b92ebb0f7d77297311dc15a810d2da5b3f6d7efaab2e41586b950da24c98a87b9db71dbe0a1887dd18328c7dd3847355fba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8eee7c7e79af8ad2ee8761504f1edcf7bc8b3fc2198a24de79ac73f28de74e15d276ec78bde6e3fb9c0cb9706fd21580473fe2027e030b92122e429ace1dd9a3"} build_url:https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011601 build_url: 'https://queue.taskcluster.net/v1/task/c19G4SCSQJOrLO5WqVpX_A/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/6889296fcee8e6c5d95d27098f937e6cf246f223b065054b7e9fa2c89ed4837324ff0fbf63f373ccc0afcf830e35dbd1df90ccbc2463d12745167de9d9434bf1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6d8da9baee48ed01b4d31d7717a4cbf1f9c04d20a5a231e162d24a1a350d189c45d9d2bad7f1a8f889a014a70947fec69bb0fbdd7ed41796ea350417eb11eaea", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/18497ecec64b271f706e7d3158442b92ebb0f7d77297311dc15a810d2da5b3f6d7efaab2e41586b950da24c98a87b9db71dbe0a1887dd18328c7dd3847355fba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8eee7c7e79af8ad2ee8761504f1edcf7bc8b3fc2198a24de79ac73f28de74e15d276ec78bde6e3fb9c0cb9706fd21580473fe2027e030b92122e429ace1dd9a3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-19 09:27:40.808282) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 09:27:40.808618) ========= 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.fOYbtiDass/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.DHVEW4EGXa/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.005295 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-19 09:27:40.857440) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 09:27:40.857752) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-19 09:27:40.858069) ========= ========= Total master_lag: 0.40 =========