builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0365 starttime: 1496166866.33 results: success (0) buildid: 20170530100116 builduid: b3a9fa5fab3c472a8205e5f17bfd4f70 revision: 1bf2d9f8a5cb68370f9a797890beb14769f4144b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:26.326409) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:26.326887) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:26.327173) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:26.354168) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:26.354870) ========= 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.9IspgMenBs/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.G2C0WOJdrK/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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-30 10:54:26-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 157M=0s 2017-05-30 10:54:26 (157 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.096823 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:26.475360) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:26.475674) ========= 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.9IspgMenBs/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.G2C0WOJdrK/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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.027418 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:26.524744) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 10:54:26.525130) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 1bf2d9f8a5cb68370f9a797890beb14769f4144b --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 1bf2d9f8a5cb68370f9a797890beb14769f4144b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9IspgMenBs/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.G2C0WOJdrK/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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-30 10:54:26,626 truncating revision to first 12 chars 2017-05-30 10:54:26,626 Setting DEBUG logging. 2017-05-30 10:54:26,626 attempt 1/10 2017-05-30 10:54:26,626 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/1bf2d9f8a5cb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 10:54:27,654 unpacking tar archive at: mozilla-beta-1bf2d9f8a5cb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.316910 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 10:54:27.865606) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:27.866020) ========= script_repo_revision: 1bf2d9f8a5cb68370f9a797890beb14769f4144b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:27.866484) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:27.866812) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 10:54:27.885771) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-30 10:54:27.886130) ========= /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.9IspgMenBs/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.G2C0WOJdrK/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:54:28 INFO - MultiFileLogger online at 20170530 10:54:28 in /builds/slave/test 10:54:28 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 10:54:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:54:28 INFO - {'append_to_log': False, 10:54:28 INFO - 'base_work_dir': '/builds/slave/test', 10:54:28 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:54:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:54:28 INFO - 'branch': 'Mozilla-Beta', 10:54:28 INFO - 'buildbot_json_path': 'buildprops.json', 10:54:28 INFO - 'config_files': ('talos/mac_config.py',), 10:54:28 INFO - 'default_actions': ('clobber', 10:54:28 INFO - 'read-buildbot-config', 10:54:28 INFO - 'download-and-extract', 10:54:28 INFO - 'populate-webroot', 10:54:28 INFO - 'create-virtualenv', 10:54:28 INFO - 'install', 10:54:28 INFO - 'run-tests'), 10:54:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:54:28 INFO - 'download_minidump_stackwalk': True, 10:54:28 INFO - 'download_symbols': 'ondemand', 10:54:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:54:28 INFO - 'tooltool.py': '/tools/tooltool.py', 10:54:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:54:28 INFO - '/tools/misc-python/virtualenv.py')}, 10:54:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:54:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:54:28 INFO - 'gecko_profile': False, 10:54:28 INFO - 'gecko_profile_interval': 0, 10:54:28 INFO - 'installer_path': 'installer.exe', 10:54:28 INFO - 'log_level': 'info', 10:54:28 INFO - 'log_name': 'talos', 10:54:28 INFO - 'log_to_console': True, 10:54:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:54:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:54:28 INFO - 'opt_config_files': (), 10:54:28 INFO - 'pip_index': False, 10:54:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:54:28 INFO - 'cmd': ('bash', 10:54:28 INFO - '-c', 10:54:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:54:28 INFO - 'enabled': True, 10:54:28 INFO - 'halt_on_failure': False, 10:54:28 INFO - 'name': 'check_screen_resolution'},), 10:54:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:54:28 INFO - 'cmd': ('bash', 10:54:28 INFO - '-c', 10:54:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:54:28 INFO - 'enabled': True, 10:54:28 INFO - 'halt_on_failure': False, 10:54:28 INFO - 'name': 'check_screen_resolution'},), 10:54:28 INFO - 'run_cmd_checks_enabled': True, 10:54:28 INFO - 'suite': 'dromaeojs-e10s', 10:54:28 INFO - 'system_bits': '32', 10:54:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:54:28 INFO - 'title': 't-yosemite-r7-0365', 10:54:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:54:28 INFO - 'use_talos_json': True, 10:54:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:54:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:54:28 INFO - 'work_dir': 'build'} 10:54:28 INFO - [mozharness: 2017-05-30 17:54:28.096431Z] Running clobber step. 10:54:28 INFO - Running pre-action listener: _resource_record_pre_action 10:54:28 INFO - Running main action method: clobber 10:54:28 INFO - rmtree: /builds/slave/test/build 10:54:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:54:30 INFO - Running post-action listener: _resource_record_post_action 10:54:30 INFO - [mozharness: 2017-05-30 17:54:30.413618Z] Finished clobber step (success) 10:54:30 INFO - [mozharness: 2017-05-30 17:54:30.413743Z] Running read-buildbot-config step. 10:54:30 INFO - Running pre-action listener: _resource_record_pre_action 10:54:30 INFO - Running main action method: read_buildbot_config 10:54:30 INFO - Using buildbot properties: 10:54:30 INFO - { 10:54:30 INFO - "project": "", 10:54:30 INFO - "product": "firefox", 10:54:30 INFO - "script_repo_revision": "production", 10:54:30 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 10:54:30 INFO - "repository": "", 10:54:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 10:54:30 INFO - "buildid": "20170530100116", 10:54:30 INFO - "pgo_build": "False", 10:54:30 INFO - "basedir": "/builds/slave/test", 10:54:30 INFO - "buildnumber": 4, 10:54:30 INFO - "slavename": "t-yosemite-r7-0365", 10:54:30 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:54:30 INFO - "platform": "yosemite_r7", 10:54:30 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 10:54:30 INFO - "branch": "mozilla-beta", 10:54:30 INFO - "repo_path": "releases/mozilla-beta", 10:54:30 INFO - "revision": "1bf2d9f8a5cb68370f9a797890beb14769f4144b", 10:54:30 INFO - "stage_platform": "macosx64", 10:54:30 INFO - "builduid": "b3a9fa5fab3c472a8205e5f17bfd4f70", 10:54:30 INFO - "slavebuilddir": "test" 10:54:30 INFO - } 10:54:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 10:54:30 INFO - Running post-action listener: _resource_record_post_action 10:54:30 INFO - [mozharness: 2017-05-30 17:54:30.417200Z] Finished read-buildbot-config step (success) 10:54:30 INFO - [mozharness: 2017-05-30 17:54:30.417334Z] Running download-and-extract step. 10:54:30 INFO - Running pre-action listener: _resource_record_pre_action 10:54:30 INFO - Running main action method: download_and_extract 10:54:30 INFO - mkdir: /builds/slave/test/build/tests 10:54: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')]} 10:54:30 INFO - trying https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 10:54:30 INFO - Downloading https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/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 10:54:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/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 10:54:31 INFO - Downloaded 1287 bytes. 10:54:31 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:54:31 INFO - Using the following test package requirements: 10:54:31 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:31 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 10:54:31 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:54:31 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:31 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 10:54:31 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:31 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 10:54:31 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:54:31 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:31 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 10:54:31 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:54:31 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:31 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 10:54:31 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:31 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 10:54:31 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:31 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 10:54:31 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:54:31 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 10:54:31 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 10:54:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:54:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:54:31 INFO - Fetch https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:54:33 INFO - Expected file size: 51393243 10:54:33 INFO - Obtained file size: 51393243 10:54:49 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 10:54:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:54:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:54:49 INFO - Fetch https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:54:51 INFO - Expected file size: 51393243 10:54:51 INFO - Obtained file size: 51393243 10:55:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 10:55:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 10:55:06 INFO - Fetch https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 10:55:07 INFO - Expected file size: 14404461 10:55:07 INFO - Obtained file size: 14404461 10:55:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:08 INFO - trying https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:55:08 INFO - Downloading https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 10:55:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/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 10:55:09 INFO - Downloaded 59644154 bytes. 10:55:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:55:09 INFO - mkdir: /builds/slave/test/properties 10:55:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:55:09 INFO - Writing to file /builds/slave/test/properties/build_url 10:55:09 INFO - Contents: 10:55:09 INFO - build_url:https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:55:10 INFO - Running post-action listener: _resource_record_post_action 10:55:10 INFO - Running post-action listener: set_extra_try_arguments 10:55:10 INFO - [mozharness: 2017-05-30 17:55:10.368760Z] Finished download-and-extract step (success) 10:55:10 INFO - [mozharness: 2017-05-30 17:55:10.368956Z] Running populate-webroot step. 10:55:10 INFO - Running pre-action listener: _resource_record_pre_action 10:55:10 INFO - Running main action method: populate_webroot 10:55:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:55:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:55:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:55:10 INFO - '--tppagecycles', 10:55:10 INFO - '7'], 10:55:10 INFO - 'tests': ['tsvgm']}}, 10:55:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:55:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:55:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:55:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:55:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:55:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:55:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:55:10 INFO - 'talos_options': ['--disable-e10s'], 10:55:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:55:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:55:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:55:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:55:10 INFO - 'talos_options': ['--disable-e10s'], 10:55:10 INFO - 'tests': ['damp', 'tps']}, 10:55:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:55:10 INFO - 'tests': ['damp', 'tps']}, 10:55:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:55:10 INFO - 'tests': ['dromaeo_dom']}, 10:55:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:55:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:55:10 INFO - 'tests': ['basic_compositor_video']}, 10:55:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:55:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:55:10 INFO - 'tests': ['a11yr', 10:55:10 INFO - 'ts_paint', 10:55:10 INFO - 'tpaint', 10:55:10 INFO - 'sessionrestore', 10:55:10 INFO - 'sessionrestore_no_auto_restore', 10:55:10 INFO - 'tabpaint']}, 10:55:10 INFO - 'other-e10s': {'tests': ['a11yr', 10:55:10 INFO - 'ts_paint', 10:55:10 INFO - 'tpaint', 10:55:10 INFO - 'sessionrestore', 10:55:10 INFO - 'sessionrestore_no_auto_restore', 10:55:10 INFO - 'tabpaint']}, 10:55:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:55:10 INFO - 'ts_paint', 10:55:10 INFO - 'tpaint', 10:55:10 INFO - 'sessionrestore', 10:55:10 INFO - 'sessionrestore_no_auto_restore', 10:55:10 INFO - 'tabpaint']}, 10:55:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:55:10 INFO - 'ts_paint', 10:55:10 INFO - 'tpaint', 10:55:10 INFO - 'sessionrestore', 10:55:10 INFO - 'sessionrestore_no_auto_restore', 10:55:10 INFO - 'tabpaint']}, 10:55:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:55:10 INFO - 'tests': ['a11yr', 10:55:10 INFO - 'ts_paint', 10:55:10 INFO - 'tpaint', 10:55:10 INFO - 'sessionrestore', 10:55:10 INFO - 'sessionrestore_no_auto_restore', 10:55:10 INFO - 'tabpaint']}, 10:55:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:55:10 INFO - 'tests': ['a11yr', 10:55:10 INFO - 'ts_paint', 10:55:10 INFO - 'tpaint', 10:55:10 INFO - 'sessionrestore', 10:55:10 INFO - 'sessionrestore_no_auto_restore', 10:55:10 INFO - 'tabpaint']}, 10:55:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:55:10 INFO - 'tests': ['tsvgx', 10:55:10 INFO - 'tsvgr_opacity', 10:55:10 INFO - 'tart', 10:55:10 INFO - 'tscrollx', 10:55:10 INFO - 'cart', 10:55:10 INFO - 'tsvg_static']}, 10:55:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:55:10 INFO - 'tsvgr_opacity', 10:55:10 INFO - 'tart', 10:55:10 INFO - 'tscrollx', 10:55:10 INFO - 'cart', 10:55:10 INFO - 'tsvg_static']}, 10:55:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:55:10 INFO - 'talos_options': ['--disable-e10s'], 10:55:10 INFO - 'tests': ['tp5o']}, 10:55:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:55:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:55:10 INFO - 'talos_options': ['--disable-e10s', 10:55:10 INFO - '--xperf_path', 10:55:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:55:10 INFO - 'tests': ['tp5n']}, 10:55:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:55:10 INFO - 'talos_options': ['--xperf_path', 10:55:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:55:10 INFO - 'tests': ['tp5n']}}, 10:55:10 INFO - 'talos.zip': {'path': '', 10:55:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:55:10 INFO - Running post-action listener: _resource_record_post_action 10:55:10 INFO - [mozharness: 2017-05-30 17:55:10.389093Z] Finished populate-webroot step (success) 10:55:10 INFO - [mozharness: 2017-05-30 17:55:10.389230Z] Running create-virtualenv step. 10:55:10 INFO - Running pre-action listener: _resource_record_pre_action 10:55:10 INFO - Running main action method: create_virtualenv 10:55:10 INFO - Creating virtualenv /builds/slave/test/build/venv 10:55:10 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 10:55:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:55:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:55:10 INFO - Using real prefix '/tools/python27' 10:55:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:55:11 INFO - Installing distribute.............................................................................................................................................................................................done. 10:55:14 INFO - Installing pip.................done. 10:55:14 INFO - Return code: 0 10:55:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:55:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:55:14 INFO - Reading from file tmpfile_stdout 10:55:14 INFO - Output received: 10:55:14 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) 10:55:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102a31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd64bd61c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280fd30>, '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 0x102736db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102804be8>, '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.9IspgMenBs/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.G2C0WOJdrK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:55:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:55:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:55:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9IspgMenBs/Render', 10:55:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:14 INFO - 'HOME': '/Users/cltbld', 10:55:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:14 INFO - 'LOGNAME': 'cltbld', 10:55:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:14 INFO - 'MOZ_NO_REMOTE': '1', 10:55:14 INFO - 'NO_EM_RESTART': '1', 10:55:14 INFO - 'PAGER': '/bin/cat', 10:55:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:14 INFO - 'PWD': '/builds/slave/test', 10:55:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:14 INFO - 'SHELL': '/bin/bash', 10:55:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G2C0WOJdrK/Listeners', 10:55:14 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:55:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:14 INFO - 'USER': 'cltbld', 10:55:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:14 INFO - 'XPC_FLAGS': '0x0', 10:55:14 INFO - 'XPC_SERVICE_NAME': '0', 10:55:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:14 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 10:55:14 INFO - Cleaning up... 10:55:14 INFO - Return code: 0 10:55:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102a31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd64bd61c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280fd30>, '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 0x102736db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102804be8>, '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.9IspgMenBs/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.G2C0WOJdrK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:55:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:55:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:55:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9IspgMenBs/Render', 10:55:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:14 INFO - 'HOME': '/Users/cltbld', 10:55:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:14 INFO - 'LOGNAME': 'cltbld', 10:55:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:14 INFO - 'MOZ_NO_REMOTE': '1', 10:55:14 INFO - 'NO_EM_RESTART': '1', 10:55:14 INFO - 'PAGER': '/bin/cat', 10:55:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:14 INFO - 'PWD': '/builds/slave/test', 10:55:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:14 INFO - 'SHELL': '/bin/bash', 10:55:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G2C0WOJdrK/Listeners', 10:55:14 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:55:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:14 INFO - 'USER': 'cltbld', 10:55:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:14 INFO - 'XPC_FLAGS': '0x0', 10:55:14 INFO - 'XPC_SERVICE_NAME': '0', 10:55:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:14 INFO - Downloading/unpacking psutil>=3.1.1 10:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:55:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:55:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:55:16 INFO - Installing collected packages: psutil 10:55:16 INFO - Running setup.py install for psutil 10:55:16 INFO - building 'psutil._psutil_osx' extension 10:55:16 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 10:55:17 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 10:55:17 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 10:55:17 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 10:55:17 INFO - building 'psutil._psutil_posix' extension 10:55:17 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 10:55:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:55:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:55:17 INFO - ^ 10:55:17 INFO - 1 warning generated. 10:55:17 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 10:55:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:55:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:55:18 INFO - Successfully installed psutil 10:55:18 INFO - Cleaning up... 10:55:18 INFO - Return code: 0 10:55:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:55:18 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')]} 10:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:18 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')]} 10:55:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:18 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 0x102a31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd64bd61c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280fd30>, '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 0x102736db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102804be8>, '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.9IspgMenBs/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.G2C0WOJdrK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:55:18 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 10:55:18 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 10:55:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9IspgMenBs/Render', 10:55:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:18 INFO - 'HOME': '/Users/cltbld', 10:55:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:18 INFO - 'LOGNAME': 'cltbld', 10:55:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:18 INFO - 'MOZ_NO_REMOTE': '1', 10:55:18 INFO - 'NO_EM_RESTART': '1', 10:55:18 INFO - 'PAGER': '/bin/cat', 10:55:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:18 INFO - 'PWD': '/builds/slave/test', 10:55:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:18 INFO - 'SHELL': '/bin/bash', 10:55:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G2C0WOJdrK/Listeners', 10:55:18 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:55:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:18 INFO - 'USER': 'cltbld', 10:55:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:18 INFO - 'XPC_FLAGS': '0x0', 10:55:18 INFO - 'XPC_SERVICE_NAME': '0', 10:55:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:55:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:55:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:55:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:55:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:55:19 INFO - Installing collected packages: mozsystemmonitor 10:55:19 INFO - Running setup.py install for mozsystemmonitor 10:55:19 INFO - Successfully installed mozsystemmonitor 10:55:19 INFO - Cleaning up... 10:55:19 INFO - Return code: 0 10:55:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:55:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:19 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 0x102a31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd64bd61c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280fd30>, '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 0x102736db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102804be8>, '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.9IspgMenBs/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.G2C0WOJdrK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:55:19 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 10:55:19 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 10:55:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9IspgMenBs/Render', 10:55:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:19 INFO - 'HOME': '/Users/cltbld', 10:55:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:19 INFO - 'LOGNAME': 'cltbld', 10:55:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:19 INFO - 'MOZ_NO_REMOTE': '1', 10:55:19 INFO - 'NO_EM_RESTART': '1', 10:55:19 INFO - 'PAGER': '/bin/cat', 10:55:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:19 INFO - 'PWD': '/builds/slave/test', 10:55:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:19 INFO - 'SHELL': '/bin/bash', 10:55:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G2C0WOJdrK/Listeners', 10:55:19 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:55:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:19 INFO - 'USER': 'cltbld', 10:55:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:19 INFO - 'XPC_FLAGS': '0x0', 10:55:19 INFO - 'XPC_SERVICE_NAME': '0', 10:55:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:20 INFO - Downloading/unpacking jsonschema==2.5.1 10:55:20 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 10:55:20 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 10:55:20 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 10:55:20 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 10:55:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:55:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:55:21 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 10:55:21 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 10:55:21 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 10:55:21 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 10:55:21 INFO - Downloading functools32-3.2.3-2.tar.gz 10:55:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:55:21 INFO - warning: no files found matching '*.txt' 10:55:21 INFO - no previously-included directories found matching 'build' 10:55:21 INFO - no previously-included directories found matching 'dist' 10:55:21 INFO - no previously-included directories found matching '.git*' 10:55:22 INFO - Installing collected packages: jsonschema, functools32 10:55:22 INFO - Running setup.py install for functools32 10:55:22 INFO - warning: no files found matching '*.txt' 10:55:22 INFO - no previously-included directories found matching 'build' 10:55:22 INFO - no previously-included directories found matching 'dist' 10:55:22 INFO - no previously-included directories found matching '.git*' 10:55:22 INFO - Successfully installed jsonschema functools32 10:55:22 INFO - Cleaning up... 10:55:22 INFO - Return code: 0 10:55:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:55: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')]} 10:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55: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')]} 10:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55: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', '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 0x102a31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd64bd61c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280fd30>, '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 0x102736db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102804be8>, '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.9IspgMenBs/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.G2C0WOJdrK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:55: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:55: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 functools32==3.2.3-2 10:55:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9IspgMenBs/Render', 10:55:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:22 INFO - 'HOME': '/Users/cltbld', 10:55:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:22 INFO - 'LOGNAME': 'cltbld', 10:55:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:22 INFO - 'MOZ_NO_REMOTE': '1', 10:55:22 INFO - 'NO_EM_RESTART': '1', 10:55:22 INFO - 'PAGER': '/bin/cat', 10:55:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:22 INFO - 'PWD': '/builds/slave/test', 10:55:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:22 INFO - 'SHELL': '/bin/bash', 10:55:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G2C0WOJdrK/Listeners', 10:55:22 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:55:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:22 INFO - 'USER': 'cltbld', 10:55:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:22 INFO - 'XPC_FLAGS': '0x0', 10:55:22 INFO - 'XPC_SERVICE_NAME': '0', 10:55:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:55:22 INFO - Cleaning up... 10:55:22 INFO - Return code: 0 10:55:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:55: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')]} 10:55:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55: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')]} 10:55:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:23 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 0x102a31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd64bd61c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280fd30>, '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 0x102736db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102804be8>, '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.9IspgMenBs/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.G2C0WOJdrK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:55:23 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 10:55:23 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 10:55:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9IspgMenBs/Render', 10:55:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:23 INFO - 'HOME': '/Users/cltbld', 10:55:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:23 INFO - 'LOGNAME': 'cltbld', 10:55:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:23 INFO - 'MOZ_NO_REMOTE': '1', 10:55:23 INFO - 'NO_EM_RESTART': '1', 10:55:23 INFO - 'PAGER': '/bin/cat', 10:55:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:23 INFO - 'PWD': '/builds/slave/test', 10:55:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:23 INFO - 'SHELL': '/bin/bash', 10:55:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G2C0WOJdrK/Listeners', 10:55:23 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:55:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:23 INFO - 'USER': 'cltbld', 10:55:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:23 INFO - 'XPC_FLAGS': '0x0', 10:55:23 INFO - 'XPC_SERVICE_NAME': '0', 10:55:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:23 INFO - Downloading/unpacking blobuploader==1.2.4 10:55:23 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 10:55:23 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 10:55:23 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 10:55:23 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 10:55:25 INFO - Downloading blobuploader-1.2.4.tar.gz 10:55:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:55:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:55: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 10:55: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 10:55: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 10:55: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 10:55:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:55:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:55: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 10:55: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 10:55: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 10:55: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 10:55:26 INFO - Downloading docopt-0.6.1.tar.gz 10:55:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:55:26 INFO - Installing collected packages: blobuploader, requests, docopt 10:55:26 INFO - Running setup.py install for blobuploader 10:55:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:55:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:55:26 INFO - Running setup.py install for requests 10:55:27 INFO - Running setup.py install for docopt 10:55:27 INFO - Successfully installed blobuploader requests docopt 10:55:27 INFO - Cleaning up... 10:55:27 INFO - Return code: 0 10:55:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:55: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')]} 10:55:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55: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')]} 10:55:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:27 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 0x102a31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd64bd61c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280fd30>, '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 0x102736db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102804be8>, '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.9IspgMenBs/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.G2C0WOJdrK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:55:27 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 10:55:27 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 10:55:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9IspgMenBs/Render', 10:55:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:27 INFO - 'HOME': '/Users/cltbld', 10:55:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:27 INFO - 'LOGNAME': 'cltbld', 10:55:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:27 INFO - 'MOZ_NO_REMOTE': '1', 10:55:27 INFO - 'NO_EM_RESTART': '1', 10:55:27 INFO - 'PAGER': '/bin/cat', 10:55:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:27 INFO - 'PWD': '/builds/slave/test', 10:55:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:27 INFO - 'SHELL': '/bin/bash', 10:55:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G2C0WOJdrK/Listeners', 10:55:27 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:55:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:27 INFO - 'USER': 'cltbld', 10:55:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:27 INFO - 'XPC_FLAGS': '0x0', 10:55:27 INFO - 'XPC_SERVICE_NAME': '0', 10:55:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:55:27 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-m2A1Ow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:55:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:55:27 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-o1gLbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:55:28 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-byItxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:55:28 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-mDKkmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:55:28 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-wVOJzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:55:28 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Lo8zCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:55:28 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-N0UIVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:55:28 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-RscPMs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:55:28 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-dRMr2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:55:28 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-XcmSxQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:55:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:55:28 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-5RbrNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:55:29 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-t8MysW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:55:29 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-5kYsQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:55:29 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-X1c8Xr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:29 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-2TaDWe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:55:29 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-DPgmM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:55:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:55:29 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-KdGcw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:55:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:55:29 INFO - Running setup.py install for manifestparser 10:55:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:55:29 INFO - Running setup.py install for mozcrash 10:55:29 INFO - Running setup.py install for mozdebug 10:55:30 INFO - Running setup.py install for mozdevice 10:55:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:55:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:55:30 INFO - Running setup.py install for mozfile 10:55:30 INFO - Running setup.py install for mozhttpd 10:55:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:55:30 INFO - Running setup.py install for mozinfo 10:55:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:55:30 INFO - Running setup.py install for mozInstall 10:55:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:55:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:55:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:55:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:55:30 INFO - Running setup.py install for mozleak 10:55:30 INFO - Running setup.py install for mozlog 10:55:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:55:31 INFO - Running setup.py install for moznetwork 10:55:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:55:31 INFO - Running setup.py install for mozprocess 10:55:31 INFO - Running setup.py install for mozprofile 10:55:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:55:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:55:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:55:31 INFO - Running setup.py install for mozrunner 10:55:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:55:31 INFO - Running setup.py install for mozscreenshot 10:55:31 INFO - Running setup.py install for moztest 10:55:32 INFO - Running setup.py install for mozversion 10:55:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:55:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:55:32 INFO - Cleaning up... 10:55:32 INFO - Return code: 0 10:55:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:55:32 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')]} 10:55:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:32 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')]} 10:55:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:32 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 0x102a31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd64bd61c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280fd30>, '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 0x102736db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102804be8>, '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.9IspgMenBs/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.G2C0WOJdrK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:55:32 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 10:55:32 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 10:55:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9IspgMenBs/Render', 10:55:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:32 INFO - 'HOME': '/Users/cltbld', 10:55:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:32 INFO - 'LOGNAME': 'cltbld', 10:55:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:32 INFO - 'MOZ_NO_REMOTE': '1', 10:55:32 INFO - 'NO_EM_RESTART': '1', 10:55:32 INFO - 'PAGER': '/bin/cat', 10:55:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:32 INFO - 'PWD': '/builds/slave/test', 10:55:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:32 INFO - 'SHELL': '/bin/bash', 10:55:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G2C0WOJdrK/Listeners', 10:55:32 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:55:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:32 INFO - 'USER': 'cltbld', 10:55:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:32 INFO - 'XPC_FLAGS': '0x0', 10:55:32 INFO - 'XPC_SERVICE_NAME': '0', 10:55:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:55:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-Wg08NJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:55:32 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)) 10:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:55:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-NMRVfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:55:32 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)) 10:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:55:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-IbBFaw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:55:32 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)) 10:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:55:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-LXftpg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:55:32 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)) 10:55:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:55:32 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-9palLL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:55:33 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)) 10:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:55:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-7d3bLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:55:33 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)) 10:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:55:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-YfbFLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:55:33 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)) 10:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:55:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-AQ7iQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:55:33 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)) 10:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:55:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-tpLe_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:55:33 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)) 10:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:55:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-iMv1Ka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:55:33 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)) 10:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:55:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-S0QKeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:55:33 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)) 10:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:55:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-ugcRTj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:55:33 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)) 10:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:55:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-F3cvwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:55:33 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)) 10:55:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:55:33 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-j9HRa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:55:34 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)) 10:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:34 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-G15Ap8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:55:34 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)) 10:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:55:34 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-qd6NOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:55:34 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)) 10:55:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:55:34 INFO - Running setup.py (path:/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/pip-0xRPwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:55:34 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)) 10:55:34 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)) 10:55:34 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)) 10:55:34 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)) 10:55:34 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)) 10:55:34 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)) 10:55:34 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:55:34 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 10:55:34 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 10:55:34 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 10:55:34 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 10:55:35 INFO - Downloading blessings-1.6.tar.gz 10:55:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:55:35 INFO - Installing collected packages: blessings 10:55:35 INFO - Running setup.py install for blessings 10:55:35 INFO - Successfully installed blessings 10:55:35 INFO - Cleaning up... 10:55:36 INFO - Return code: 0 10:55:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:55:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:55:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:55:36 INFO - Reading from file tmpfile_stdout 10:55:36 INFO - Current package versions: 10:55:36 INFO - blessings == 1.6 10:55:36 INFO - blobuploader == 1.2.4 10:55:36 INFO - docopt == 0.6.1 10:55:36 INFO - functools32 == 3.2.3-2 10:55:36 INFO - jsonschema == 2.5.1 10:55:36 INFO - manifestparser == 1.1 10:55:36 INFO - mozInstall == 1.12 10:55:36 INFO - mozcrash == 1.0 10:55:36 INFO - mozdebug == 0.1 10:55:36 INFO - mozdevice == 0.49 10:55:36 INFO - mozfile == 1.2 10:55:36 INFO - mozhttpd == 0.7 10:55:36 INFO - mozinfo == 0.9 10:55:36 INFO - mozleak == 0.1 10:55:36 INFO - mozlog == 3.4 10:55:36 INFO - moznetwork == 0.27 10:55:36 INFO - mozprocess == 0.25 10:55:36 INFO - mozprofile == 0.28 10:55:36 INFO - mozrunner == 6.13 10:55:36 INFO - mozscreenshot == 0.1 10:55:36 INFO - mozsystemmonitor == 0.3 10:55:36 INFO - moztest == 0.8 10:55:36 INFO - mozversion == 1.4 10:55:36 INFO - psutil == 3.1.1 10:55:36 INFO - requests == 1.2.3 10:55:36 INFO - wsgiref == 0.1.2 10:55:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:55:36 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')]} 10:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:36 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')]} 10:55:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:36 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 0x102a31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd64bd61c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280fd30>, '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 0x102736db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102804be8>, '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.9IspgMenBs/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.G2C0WOJdrK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:55:36 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 10:55:36 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 10:55:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9IspgMenBs/Render', 10:55:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:36 INFO - 'HOME': '/Users/cltbld', 10:55:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:36 INFO - 'LOGNAME': 'cltbld', 10:55:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:36 INFO - 'MOZ_NO_REMOTE': '1', 10:55:36 INFO - 'NO_EM_RESTART': '1', 10:55:36 INFO - 'PAGER': '/bin/cat', 10:55:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:36 INFO - 'PWD': '/builds/slave/test', 10:55:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:36 INFO - 'SHELL': '/bin/bash', 10:55:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G2C0WOJdrK/Listeners', 10:55:36 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:55:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:36 INFO - 'USER': 'cltbld', 10:55:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:36 INFO - 'XPC_FLAGS': '0x0', 10:55:36 INFO - 'XPC_SERVICE_NAME': '0', 10:55:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:36 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)) 10:55:36 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)) 10:55:36 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)) 10:55:36 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)) 10:55:36 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)) 10:55:36 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)) 10:55:36 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)) 10:55:36 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)) 10:55:36 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)) 10:55:36 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)) 10:55:36 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)) 10:55:36 INFO - Cleaning up... 10:55:36 INFO - Return code: 0 10:55:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:55:36 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')]} 10:55:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:55:36 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')]} 10:55:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:55:36 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 0x102a31b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102a34768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd64bd61c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10280fd30>, '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 0x102736db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102804be8>, '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.9IspgMenBs/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.G2C0WOJdrK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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 10:55:36 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 10:55:36 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 10:55:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9IspgMenBs/Render', 10:55:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:36 INFO - 'HOME': '/Users/cltbld', 10:55:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:36 INFO - 'LOGNAME': 'cltbld', 10:55:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:36 INFO - 'MOZ_NO_REMOTE': '1', 10:55:36 INFO - 'NO_EM_RESTART': '1', 10:55:36 INFO - 'PAGER': '/bin/cat', 10:55:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:36 INFO - 'PWD': '/builds/slave/test', 10:55:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:36 INFO - 'SHELL': '/bin/bash', 10:55:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G2C0WOJdrK/Listeners', 10:55:36 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:55:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:36 INFO - 'USER': 'cltbld', 10:55:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:36 INFO - 'XPC_FLAGS': '0x0', 10:55:36 INFO - 'XPC_SERVICE_NAME': '0', 10:55:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:55:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:55:36 INFO - Cleaning up... 10:55:36 INFO - Return code: 0 10:55:36 INFO - Running post-action listener: _resource_record_post_action 10:55:36 INFO - Running post-action listener: _start_resource_monitoring 10:55:36 INFO - Starting resource monitoring. 10:55:36 INFO - [mozharness: 2017-05-30 17:55:36.880414Z] Finished create-virtualenv step (success) 10:55:36 INFO - [mozharness: 2017-05-30 17:55:36.881346Z] Running install step. 10:55:36 INFO - Running pre-action listener: _resource_record_pre_action 10:55:36 INFO - Running main action method: install 10:55:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:55:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:55:37 INFO - Reading from file tmpfile_stdout 10:55:37 INFO - Detecting whether we're running mozinstall >=1.0... 10:55:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:55:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:55:37 INFO - Reading from file tmpfile_stdout 10:55:37 INFO - Output received: 10:55:37 INFO - Usage: mozinstall [options] installer 10:55:37 INFO - Options: 10:55:37 INFO - -h, --help show this help message and exit 10:55:37 INFO - -d DEST, --destination=DEST 10:55:37 INFO - Directory to install application into. [default: 10:55:37 INFO - "/builds/slave/test"] 10:55:37 INFO - --app=APP Application being installed. [default: firefox] 10:55:37 INFO - mkdir: /builds/slave/test/build/application 10:55:37 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'] 10:55:37 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 10:55:52 INFO - Reading from file tmpfile_stdout 10:55:52 INFO - Output received: 10:55:52 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 10:55:52 INFO - Running post-action listener: _resource_record_post_action 10:55:52 INFO - [mozharness: 2017-05-30 17:55:52.203679Z] Finished install step (success) 10:55:52 INFO - [mozharness: 2017-05-30 17:55:52.203819Z] Running run-tests step. 10:55:52 INFO - Running pre-action listener: _resource_record_pre_action 10:55:52 INFO - Running main action method: run_tests 10:55:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:55:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:55:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:55:52 INFO - 2017-05-30 10:55:52.217 screenresolution[1630:7334] starting screenresolution argv=screenresolution get 10:55:52 INFO - 2017-05-30 10:55:52.220 screenresolution[1630:7334] Display 0: 1600x1200x32@60 10:55:52 INFO - 2017-05-30 10:55:52.225 screenresolution[1631:7336] starting screenresolution argv=screenresolution list 10:55:52 INFO - Available Modes on Display 0 10:55:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:55:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:55:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:55:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:55:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:55:52 INFO - Intel Iris: 10:55:52 INFO - Chipset Model: Intel Iris 10:55:52 INFO - Type: GPU 10:55:52 INFO - Bus: Built-In 10:55:52 INFO - VRAM (Dynamic, Max): 1536 MB 10:55:52 INFO - Vendor: Intel (0x8086) 10:55:52 INFO - Device ID: 0x0a2e 10:55:52 INFO - Revision ID: 0x0009 10:55:52 INFO - Displays: 10:55:52 INFO - Display: 10:55:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:55:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:55:52 INFO - Display Serial Number: 1600x1200 60 10:55:52 INFO - Main Display: Yes 10:55:52 INFO - Mirror: Off 10:55:52 INFO - Online: Yes 10:55:52 INFO - Rotation: Supported 10:55:52 INFO - Return code: 0 10:55:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:55:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:55:52 INFO - Python 2.7.3 10:55:52 INFO - Return code: 0 10:55:52 INFO - grabbing minidump binary from tooltool 10:55:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:55:52 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 0x10280fd30>, '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 0x102736db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102804be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:55:52 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 10:55:52 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 10:55:52 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 10:55:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:55:52 INFO - Return code: 0 10:55:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:55:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:55:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:55:52 INFO - ENV: RUST_BACKTRACE is now 1 10:55:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:55:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:55:52 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/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--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 10:55:52 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/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0365 --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 10:55:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9IspgMenBs/Render', 10:55:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:55:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:55:52 INFO - 'HOME': '/Users/cltbld', 10:55:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:55:52 INFO - 'LOGNAME': 'cltbld', 10:55:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:55:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:55:52 INFO - 'MOZ_NO_REMOTE': '1', 10:55:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:55:52 INFO - 'NO_EM_RESTART': '1', 10:55:52 INFO - 'PAGER': '/bin/cat', 10:55:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:55:52 INFO - 'PWD': '/builds/slave/test', 10:55:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:55:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:55:52 INFO - 'RUST_BACKTRACE': '1', 10:55:52 INFO - 'SHELL': '/bin/bash', 10:55:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.G2C0WOJdrK/Listeners', 10:55:52 INFO - 'TMPDIR': '/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/', 10:55:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:55:52 INFO - 'USER': 'cltbld', 10:55:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:55:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:55:52 INFO - 'XPC_FLAGS': '0x0', 10:55:52 INFO - 'XPC_SERVICE_NAME': '0', 10:55:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:55:52 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/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0365', '--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 10:55:52 INFO - mozversion application_buildid: 20170530100116 10:55:52 INFO - mozversion application_changeset: 1bf2d9f8a5cb68370f9a797890beb14769f4144b 10:55:52 INFO - mozversion application_display_name: Firefox 10:55:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:55:52 INFO - mozversion application_name: Firefox 10:55:52 INFO - mozversion application_remotingname: firefox 10:55:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:55:52 INFO - mozversion application_vendor: Mozilla 10:55:52 INFO - mozversion application_version: 54.0 10:55:52 INFO - mozversion platform_buildid: 20170530100116 10:55:52 INFO - mozversion platform_changeset: 1bf2d9f8a5cb68370f9a797890beb14769f4144b 10:55:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:55:52 INFO - mozversion platform_version: 54.0 10:55:52 INFO - using testdate: 1496166952 10:55:52 INFO - actual date: 1496166952 10:55:52 INFO - starting webserver on 'localhost:49224' 10:55:52 INFO - SUITE-START | Running 2 tests 10:55:52 INFO - TEST-START | dromaeo_css 10:55:52 INFO - operating with platform_type : mac_ 10:55:52 INFO - Initialising browser for dromaeo_css test... 10:55:52 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpiq48eX/profile http://localhost:49224/getInfo.html) 10:55:55 INFO - PROCESS | 1641 | 2017-05-30 10:55:55.801 plugin-container[1644:7502] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 10:55:55 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:55 INFO - PROCESS | 1641 | 2017-05-30 10:55:55.802 plugin-container[1644:7502] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d17, name = 'com.apple.CFPasteboardClient' 10:55:55 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:55 INFO - PROCESS | 1641 | 2017-05-30 10:55:55.802 plugin-container[1644:7502] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:55:55 INFO - PROCESS | 1641 | __metrics Screen width/height:1600/1200 10:55:55 INFO - PROCESS | 1641 | colorDepth:24 10:55:55 INFO - PROCESS | 1641 | Browser inner width/height: 1024/689 10:55:55 INFO - PROCESS | 1641 | __metrics 10:55:55 ERROR - PROCESS | 1641 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 10:55:56 INFO - TEST-INFO | 1641: exit 0 10:55:56 INFO - Browser initialized. 10:55:56 INFO - Running cycle 1/1 for dromaeo_css test... 10:55:56 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpiq48eX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:55:59 INFO - PROCESS | 1645 | 2017-05-30 10:55:59.004 plugin-container[1646:7639] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4243, name = 'com.apple.tsm.portname' 10:55:59 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:59 INFO - PROCESS | 1645 | 2017-05-30 10:55:59.004 plugin-container[1646:7639] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4517, name = 'com.apple.CFPasteboardClient' 10:55:59 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:55:59 INFO - PROCESS | 1645 | 2017-05-30 10:55:59.004 plugin-container[1646:7639] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:56:08 INFO - PROCESS | 1645 | RSS: Main: 174346240 10:56:08 INFO - PROCESS | 1645 | 10:59:11 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 10:59:13 INFO - PROCESS | 1645 | RSS: Main: 194510848 10:59:13 INFO - PROCESS | 1645 | 11:02:15 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 11:02:16 INFO - PROCESS | 1645 | RSS: Main: 201924608 11:02:16 INFO - PROCESS | 1645 | 11:05:18 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 11:05:18 INFO - PROCESS | 1645 | RSS: Main: 204144640 11:05:18 INFO - PROCESS | 1645 | 11:08:20 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 11:08:21 INFO - PROCESS | 1645 | RSS: Main: 207519744 11:08:21 INFO - PROCESS | 1645 | 11:11:24 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 11:11:25 INFO - PROCESS | 1645 | RSS: Main: 209944576 11:11:25 INFO - PROCESS | 1645 | 11:14:27 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 11:14:28 INFO - PROCESS | 1645 | RSS: Main: 207982592 11:14:28 INFO - PROCESS | 1645 | 11:14:28 INFO - PROCESS | 1645 | __start_tp_report 11:14:28 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 11:14:28 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 11:14:28 INFO - PROCESS | 1645 | |i|pagename|runs| 11:14:28 INFO - PROCESS | 1645 | |0;dojo.html;2985.148933839819;3608.4406916927874;3628.8548458061678;3645.7265705072127;3672.2288319452914;6151.938480615198;6157.261128664555;6174.876502469948;6248.343923887995;6260.906086408708;4430;5750.4761238271785;6153;6197.535184861131;6266.530010249238;4443.488998765135;5544.556435485151;5678.148277758328;5690.516306113986;5724.513416359614;5697.373288938214;5701.74342154602;5705.743241554119;5714.628549144292;5721.942780572203;5460.235567020621;5474.42518535554;5590.301212348457;5767.567432442563;5802;5679.4036626154275;5703.800366987177;5733.856653583652;5739.253896993385;5794.536437085023;5546.195801608785;5602.467765562305;5603.551715862721;5636.323641163046;5667.064934285838;5496.587755918322;6007;6146.231721034871;6221;6252.31224565302;6335.429811317003;6341.968290158566;6347.96826015867;6394.23269207698;6422.454091402236;6636.236832764197;6664.067030615718;6664.266930637626;6673.432758215557;6678.332166783332;5195.480451954774;6230.408111229426;6278.215223097113;6321.431071203568;6332.905006424908;6363.809085727436;6434.581752186186;6458.773942911976;6705.329467053256;6786;6372.649504277217;6432.099506069154;6455.031745238158;6455.289918109004;6458.54790164684;6519.5436319457185;6588.604683718896;6620.238672552622;6639.535232533773;6644.269130395653;6261.217347831521;6476.967615161988;6486.54594178403;6506.284308726036;6516.348365163446;6336.303006669263;6346.079818426302;6365.363463653597;6370.203724534434;6396.712147953353;5295.4969277918535;5309.973450132802;5328.786848526093;5333;5342.866428339323;3939.2712348215673;4678.8596342109795;4694.600958918462;4706.10583989041;4713.67004309695;5993.0710739835395;6138.602983404986;6216.316205217422;6225.097360882737;6312.715927783261;6607.933920660828;6617.073609694647;6640.667966601651;6650.767223147167;6658.134442522538;6263.686815659199;6273.341299163613;6305.653189074553;6314.431701146918;6322.747090116436;5591.217229587861;5618.91571626426;5717.199592057115;5829.766809327664;5838.9124163137585;6568.113304703804;6598.63707496102;6680.03139544773;6711.161104861892;6715.664216789043;6588.176477940257;6598.406143447113;6604.933950660432;6615.206175259;6630.408701911603;6687.261312058997;6788.796336109925;6814;6826.044353790573;6838.965805170942;6267.2479302484;6355.841103972438;6423;6445.16212892321;6451.70967306463;5546.251256080377;6314.8317561251315;6619.371159739817;6636.203655561363;6648.800535983928;6205.348438414082;6277.96861015692;6305.90541141874;6354.17395738551;6455.48356131499;6474.5791523550815;6477.449416799436;6478.060681201294;6490;6498.090267362479;6213.2233470816145;6217.782377616762;6230.096635987847;6236.126942228182;6260.40526150015;6366.331535188739;6386.233651961795;6477.255115661571;6477.319881412383;6489.837754056186;6294.1188233648245;6306.432421082125;6335.683215839282;6336.619802811846;6348.555601107878 11:14:28 INFO - PROCESS | 1645 | |1;ext.html;831.2850948184562;851.1488511488511;985.4136788610774;990.0940639315027;991.9355241909279;31970;34523.82738086312;34623.96128116157;42056.57943420565;42069.57930420695;40123.79628611139;40308.19383612326;40402.595974040254;40480.19039619206;40485;7284.235155308683;10966.396848173346;11399.601013964513;11591.478383472764;11592.130590205727;9518.428894266332;11340.092792576615;11450.770984580304;11458;11458.484368203452;11389.829152562717;11411.771764564704;11420.828687569694;11446;11450.71373215672;11360.204785665006;11367.658970230907;11376.772464550746;11429.542818287258;11449.885501145007;11079.944600277027;11084.944575277153;11149.16381271408;11151.944240278746;11166.944165279121;35471.82264088663;35562.64437355619;35566.822165889265;35569.46645800315;35677.64322356769;3894.396368562849;3910.3156947534067;3917.8628747993966;3920.2551515212303;3934.2721596504452;25996.87001564999;26167.476650467073;26621.866890665617;26676.46647067067;27112;2835.376217232206;3183.442897492953;3190.8245046522434;3279.262166012652;3290.7860989035635;361503;362164;362339;362622;363571;36877.26245475075;38547;43140;44160.558394416286;44225.55774442279;46164.30753538699;46610.76694616505;46668;46772.298415523794;46881.29678054832;14374;14549.854501455062;14620.85379146195;14622.976391652483;14639.92680036593;10054.346467479736;12551.560695375618;12567.994560435143;12596.685082873002;12606.369681516071;12222.694432639077;12264.202826816228;12271.57049503263;12286.385680715928;12313.815292770616;5805;5811.651300921958;5818.738156782955;5819;5820.35976042635;1110.8944650258213;1132.4960392625367;1184.1888306510068;1222.2727477151082;1261.5080118753694;23313;28563.28591785222;29305;30425.69574304273;30566.847165764473;893.9910600894038;1014.4876837197169;1021.3514418344256;1024.954546362706;1031.4791030529534;11683.532658693557;12087.153899226969;12088.697782555506;12091.818622720666;12174.573889913987;488688;494706;496242;496484;499751;47714;47881;47984.760076199396;47986.28020579694;48001.519984800405;1435.612384656137;1437.856214378554;1482.392219190127;1489.2107183192925;1497.9325930333162;22369.217077402212;22462.88768556147;22667.506574276485;22787.31638050861;23277.0689172438;41967.370489443296;42237.94405139896;42260.36609450799;42278.577214228695;42355.7882210587;476325.2367476281;477277.6136119297;477479;478306;478421;29317.41365172727;29574.260643484085;30970.380592387577;31686.207844804063;33021.17447063843;39979.54147072958;40391;40759.388609170295;41047.97380065522;41287;25869.870650646626;27406.5889011661;29495.705042950154;29821;30418;28723.569146463236;28892.566611500424;29005.854970725013;29609.40781184321;29805.552916705834;28669.28326791847;32586;33864.66135338615;34283.628654854525;34294.48558271578;28482;29050.418991619626;29250;29272.707272926997;29769.106926792232;22344.21795237159;23685;23844.284671459885;23977.28068157958;23979 11:14:28 INFO - PROCESS | 1645 | |2;jquery.html;347.9110384496526;398.2930298719773;408.1612286750726;409.19797197492915;450.29303992731406;20616.866072366032;21205.939703014865;21538.21822890412;21842.235521756742;22156.446088847766;22623.321300361014;23276.534469310605;23398.64902026471;23412;23427.76572234277;5939.70301484924;6599.934000660004;6944.131983502062;6982.860342793141;6991;6129.049997250433;6519.445847102978;7086;7146.106736657918;7167.4266058715175;6672.132622759034;6744.730210791587;7113.146422429316;7147.642617869112;7166.570005799643;6823.1812182537915;6887;6899.068625735536;6908.1364829396325;6927.618980956046;6396.3283855194995;6805.285445028299;6869.690863911136;6872.484563657696;6874.65626718667;27534.862325688442;27605.30986725348;27616.619169041467;27759.167224983285;27915.16254512368;3487.89536313911;3517.3317069756663;3532.6290739472242;3539.2213712983103;3700.4079347304573;14300.713985720331;16394.918025409916;17050.89169203998;17204.827951720446;17584.824151758574;1846.3722334406232;2152.235956235545;2183.617866873307;2211.148707747529;2221.4557433428913;140806;141241.8813717795;142220.57779422175;142814.99999999895;145409.27295363456;22366.217182398865;22644.99999999967;22711.659325110137;23134.884325578263;23136.876521669696;34660.82669586686;35225.64774352275;35935.92192234237;35984.28031439409;35995;11328.94335528317;11525.135614828921;11575.594854180063;11773.352465614473;11890.940545297219;9514.238860891111;9527.38072025316;9529.952350238205;9668.743063401713;9679.70960871175;10343.793124137475;10654;10678;10693.465326733633;10794.946025269823;4527.139843429738;4537.160625284333;4542.750743545552;4567.5432456754725;4575.643543575062;1050.2123407444417;1103.8013157631704;1108.0636861851722;1111.6498303034518;1112.1214240749878;22217.11131554752;22975.770242297698;23497;24268.87865560696;24755;936.513013233115;946.8579713043099;947.9763005924907;971.3152227679469;975.4439969217478;6943.479239057091;7091.964540177266;7127.4654400920135;7170.96414517924;7361.337479626859;746147.2692636502;777252;789115;799087;811985;131104.34447827892;141229;145407.36481588046;148851.25574372275;158353;1343.4223283987978;1356.5387768158873;1394.1217033268977;1394.8186735724291;1396.0716123777777;16168.78734094924;18479;19590.314338998636;20516.589668206852;20723.378298651063;55002.44997549973;67412;68744.65627671829;69534.65232673804;70655;95955;95975;96448.51775741077;98676;99053;8961.551922403984;9571.952140239255;10198.541065651914;11661.76676466483;11668.416579171177;12437.689057773629;12708.745825083262;12820.358982051046;13165.60503184906;13169.934150329187;11172.944135279271;11251.381174035507;11445.771084578428;11472.713182170513;11561.942190289332;10139.138173255062;10348.896511034793;10537.104346130665;10910;11031.393273370042;11741.412929353328;11859.466324015268;11953.282803031845;12019.699507512381;12148.21036632616;11567;11692.35692036946;11762.117841162119;12030.218035827644;12094.697632559257;11495.425228738362;11603.6518904433;11673.941630291794;11836.349000805038;11865.5847045353 11:14:28 INFO - PROCESS | 1645 | |3;mootools.html;1390.3048475762118;1418.751718449271;1606.084531816865;1643.8000259810337;1649.2083799776103;9879.456629885362;10700.839487407695;11514.884851151486;11785.057195424346;11936.164468487199;11483.138764592648;11616.477258523366;11699.590514332;11712;11717.121215908799;2324.593196190664;2342.80086192673;2351.9412014699597;2355.199232261031;2355.8468699534474;2399.78401943825;2475.096589744739;2483.2301986384277;2484.757621189405;2500.1374525788665;2410.6986626671664;2466.7409921958115;2477.2196758021255;2478.8140889433275;2485.8135639826996;2389.749076346976;2443.4502236996623;2451.3626457121186;2456.8894399751966;2464.260721783458;2041.8672786268994;2448.5592593333195;2453.509298140379;2465.4822487277693;2467.543504451682;9560.665376711851;11423.771524569545;11604.535818567247;11645.592404265895;11656.601207855027;5013.6490445668815;5840.182374467536;5919;6012.488938440238;6017.308009578911;3384.849151288574;3429.742769292313;3688.428293614494;3740.214554943465;3759.4172903200047;1250.1623911978995;1250.8123781432766;1262.1858901008798;1306.45129045801;1307.45740517686;13626.727465450636;14061.086029408056;15436.073835569901;15900.363985440568;16041;4689.85930422081;4756.2781375731265;4775.164346239394;4868.464468908417;4873.488283730228;12120.636380908587;12316.014718822473;12339.444724987397;12351.25892446438;12358.876411235953;5986.73059712314;6002.759889604367;6015.157877897098;6026.638401695912;6028.065649824284;5321.441248668912;5383.892322153535;5415.6479828811;5473.0969390051;5497.120460726264;3995.6603688686787;4024.6579040781276;4036.354183330653;4112.691548133842;4124.278251306009;914.2822884036;1055.8996895295088;1062.134360496193;1062.7502536903821;1077.3320541264309;799.4403917257943;943.2029934705163;963.2324684204423;966.1304825656965;1008.8688470498788;6481.351864813481;6681.832954176184;6777.288384719632;6787.6266805325195;6868.965655171693;1068.8663917010374;1096.3421946831998;1103.8454616353717;1124.7796141186905;1144.5879483385975;4208.263553878121;4291.356296555479;4296.828126874952;4379.934300985487;4430.712003719748;12578.433970471351;13979.720405591832;14005.509807156905;14552.981291309507;14554.854451455562;14155;14267.215303158218;14273.286335683175;14418.783718244234;14463.349149288308;8654.826903461895;10482.37105773656;10752.086072683753;10785.78428431427;10791.40647264408;6708.765193218214;6807.6725038617;6863.313668633296;6953.443724501926;7076.186238582629;11652.650420487398;12991.805122923353;12992.350382481027;13004.349782511026;13010;13102.672433189247;13185.472581097032;13206.273654948685;13309.669033096612;13360.465581376637;7375.778726638209;8444.577771111542;8802.559872006246;8805.867911981453;8810.118988101138;10440.216983726099;10485.423301718478;10559.419231942316;10590.993855583707;10678.62624808128;8968.82062358736;9093.363464557417;9156.26749860023;9159.267258619428;9171.724848254564;7445.70217191328;8914.821703566022;8934.374593778633;9027.684031058881;9139.314551408805;7076.610786406797;8053.718119865776;8132.75601731949;8142.389320800845;8228.136045715113;5588.0888357732965;5991.970040149771;5998.580099393175;6192;6204.348543402878;6032.155498230163;6068.21113255274;6213.099100630474;6276.246850377984;6342 11:14:28 INFO - PROCESS | 1645 | |4;prototype.html;374.29258701054994;394.26863168821836;394.6231349061646;400.2967373824377;405.07440498461006;4845.055214233226;4849.321095046692;4913.82801601944;5417.756200970956;5425.806322609024;4300.440942677447;4734.597559207463;5281.577473802096;5289.735513224343;5333.466653334664;4340.804663790137;4375.4311939447825;4396.516383197845;4399.5160532341415;4406.405135306724;3947.802280153085;4251.914961700764;4473.932891006638;4492.820287188509;4499.6850220484575;3561.519194908693;3922.7450215735885;4268.9573104268875;4417.293233082707;4457.481522939545;3602.873899413534;3802.99220706513;4327.978360108211;4386.670999675037;4394.6044855962955;3857.556381016191;4009.3985902114914;4015.859444919414;4084.448599439097;4085.387191921206;4732.9763351183365;5163.845084647466;5181.870453238662;5197.7401129943355;5214.60890433219;4178.603032711888;4462.8884277892985;4504.346869703874;4698.214678422212;4728.338032675429;5678.233438485834;5696.892149904562;5736.8852622947725;5748.568857335675;5776.913346299809;4272.273713468718;4348.673459699077;4520.570545798144;4544.545545445429;4583.960137738708;5620.606557540974;5641.294838145232;5790.2762154730235;5854.297484301868;5929.762809487615;4364.8433165211245;4392.363107349416;5031.345925029723;5079.87300317495;5168.379794424618;4421.646268298528;5230;5248.3701955765555;5341.023821186186;5341.813036543703;4886.877828054327;5187.299714538489;5311.7344132793205;5410.702411367334;5500.972495137578;4850.320955066293;5628.85927851807;5643.843012182575;5667.574931880125;5673.468163595806;1534.77745726869;1579.344572002629;1621.0192833335661;1696.575856035991;1811.4565630310854;1252.949882004728;1323.0407954233103;1330.9020058451622;1379.4964837834318;1454.7236025155187;4711.7879695413785;4990.479807697162;5209.958008398336;5353.705546194997;5356.91964620531;4916.336294600254;5272.497338258578;5416.647917885325;5505.9174112387545;5943.286805583358;4431.091626216617;4849.811796109976;4966.081274964144;4983.177775667047;5112.667676601009;2421.055788242587;2673.799465040091;2939.9265018374713;3020.3808219314988;3084.8766049358223;5000.424951130666;5233.6074794389815;5363.463653634605;6132.846678833065;6166.753329866845;5100.362454693163;5447.428020057916;5871.970640146772;5889.882202355929;6047.879042419215;5661.745221464962;6132.908006379907;6135.447809697149;6141.293751218667;6167.599106058092;5275.525202731619;5567.826338986739;5821.301443826768;5932.406759324033;5967.46292833647;4795.064962332452;5140.665856719451;5257;5685.204071430086;5808.390119037609;4800.91160345251;5026.899462010812;5115.820946267012;5151.613628977917;5833.329167145833;4357.4117494139155;4402.427684401007;4905.607551395951;5107.387113546398;5145.555860966011;4837.879053023703;4971.072852446527;5817.476427121579;5897.587168898136;5903.114532820111;4696.788644510937;5468.316460442445;5763.596548241583;5775.48020678141;5785.942140578541;5717.1995920570325;5788.53691704671;5832.854178645568;5846.269216347957;5874.500667443144;5223.738813059256;5444.237806707143;5526.778928842801;5555.361168455234;5684.261046063985;5077.873053173553;5081.644284900021;5111.3355263815465;5700.629459085146;5807.070868660994;4581.977090114528;4869.586085182799;4949.698390248552;4994.450610432777;5410.673410433394 11:14:28 INFO - PROCESS | 1645 | |5;yui.html;762.8855671649255;770.8650986077439;800.8558459477296;837.505871535794;892.3396686452023;14818.7404070654;14876.925615371922;17681.64636707265;17714.379996700118;17825.197866096023;15893.682126357495;15918.920405397987;16201.432949846758;16295.348186072573;16398.918005409985;935.8175155844613;961.471190845036;962.5860879821674;963.8120566489538;965.3194497878978;972.65470757881;994.1599348550463;1026.0611540440489;1026.4816267784759;1033.7622346860226;1052.7578656908915;1075.8977897099762;1077.983830242547;1080.2816127275353;1081.7025318037524;1054.1092776603757;1068.9946550267198;1070.3203465799234;1072.1744256922204;1074.1514203779004;791.2087912087912;794.8410317936435;795.0538858758059;795.4312666443462;796.8884356190138;17341.219645116;17395.826041739674;17422.21600028002;18267.817321826744;18584.721229181574;14648.121112733164;14674;14828.3327250274;14834.10995340283;14850.480233191895;10270.845937310947;10308.381497110197;10576.418296993663;10580.470976451224;10615;3783.16770310534;3924.156306394139;3937.370020796659;4049.797510124482;4056.2293164298985;110659;126542;127123;129130.35434822766;131522;10400.687979360631;10930.63367079115;11332.943335283271;11488.138389620812;11488.655340339803;24059.51880962371;24185.879070604533;24230.394240144138;24357;24582.139625113035;8215.753527394188;8255.587220638945;8289.336853051862;8311.877896483897;8338.374621903382;4576.199165146086;4606.608438282784;4639.675222734376;4677.321788340671;4744.027474367746;3608.80151591665;3715.20123173519;3724.5902950675404;3736.645018723217;3736.981315093407;654.1006116589689;669.334012657409;680.9216940051858;694.8783962806489;696.4985210648325;724.8043028382413;733.600187897597;754.3550264523753;777.149021821105;780.8945792318078;8793.428427152214;9891.752706182258;10808.297460665031;10961.616343427942;11074.114070874311;730.989035164473;741.4772585327331;748.8389996150854;752.834376437183;753.2128925273103;4319.114581510783;5201.687898726089;5262.657927234717;5303.761330740126;5310.893782124336;88722;90168;109486;110251.34622980662;111846.44076779563;80553;81438;81922.59038704887;89004.6649300261;89507;62172;63193.684031580466;65934.67032664806;70078.59842803115;75669;10992.739702103305;11103.723071846842;11289.751681298825;11409.315441073562;11586.71033224176;41341;42312.576874230865;42809.14381712286;51403.42982850768;53594.73202633962;63606.04590931232;64724;66232;66715;67972.3202767966;13158.671033223864;13254.53609123676;13338.333083345988;13439.260840653858;13454.93272533631;14966;15029.248537573296;15183.089014659156;15384.923075384551;16235.188240588159;11291.266067705574;14096.647583810487;14631.048981816148;14734.852651473348;14944.178070206242;13585.320733963064;13719.45122195101;14312.928435358173;14334.928325358724;14406.351714172677;13139.60581182566;13756.931215344259;14148.009639325148;14243.145411275356;14288.42846286137;10287.215131723526;10425.426601536989;10822.56709731602;11072.224944254147;11119.999200072032;11035.172362072715;11309.095272378354;11381.715457113638;11462.82805757931;11573.363465009505 11:14:28 INFO - PROCESS | 1645 | __end_tp_report 11:14:28 INFO - PROCESS | 1645 | __start_cc_report 11:14:28 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,396 11:14:28 INFO - PROCESS | 1645 | __end_cc_report 11:14:28 INFO - PROCESS | 1645 | __startTimestamp1496168068195__endTimestamp 11:14:28 INFO - PROCESS | 1645 | 11:14:28 INFO - PROCESS | 1645 | ------- Summary: start ------- 11:14:28 INFO - PROCESS | 1645 | Number of tests: 6 11:14:28 INFO - PROCESS | 1645 | 11:14:28 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:6071.74 Median:6306.43 stddev:691.81 (11.0%) stddev-sans-first:650.18 11:14:28 INFO - PROCESS | 1645 | Values: 2985.1 3608.4 3628.9 3645.7 3672.2 6151.9 6157.3 6174.9 6248.3 6260.9 4430.0 5750.5 6153.0 6197.5 6266.5 4443.5 5544.6 5678.1 5690.5 5724.5 5697.4 5701.7 5705.7 5714.6 5721.9 5460.2 5474.4 5590.3 5767.6 5802.0 5679.4 5703.8 5733.9 5739.3 5794.5 5546.2 5602.5 5603.6 5636.3 5667.1 5496.6 6007.0 6146.2 6221.0 6252.3 6335.4 6342.0 6348.0 6394.2 6422.5 6636.2 6664.1 6664.3 6673.4 6678.3 5195.5 6230.4 6278.2 6321.4 6332.9 6363.8 6434.6 6458.8 6705.3 6786.0 6372.6 6432.1 6455.0 6455.3 6458.5 6519.5 6588.6 6620.2 6639.5 6644.3 6261.2 6477.0 6486.5 6506.3 6516.3 6336.3 6346.1 6365.4 6370.2 6396.7 5295.5 5310.0 5328.8 5333.0 5342.9 3939.3 4678.9 4694.6 4706.1 4713.7 5993.1 6138.6 6216.3 6225.1 6312.7 6607.9 6617.1 6640.7 6650.8 6658.1 6263.7 6273.3 6305.7 6314.4 6322.7 5591.2 5618.9 5717.2 5829.8 5838.9 6568.1 6598.6 6680.0 6711.2 6715.7 6588.2 6598.4 6604.9 6615.2 6630.4 6687.3 6788.8 6814.0 6826.0 6839.0 6267.2 6355.8 6423.0 6445.2 6451.7 5546.3 6314.8 6619.4 6636.2 6648.8 6205.3 6278.0 6305.9 6354.2 6455.5 6474.6 6477.4 6478.1 6490.0 6498.1 6213.2 6217.8 6230.1 6236.1 6260.4 6366.3 6386.2 6477.3 6477.3 6489.8 6294.1 6306.4 6335.7 6336.6 6348.6 11:14:28 INFO - PROCESS | 1645 | 11:14:28 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:56744.55 Median:29787.33 stddev:119487.48 (401.1%) stddev-sans-first:119748.92 11:14:28 INFO - PROCESS | 1645 | Values: 831.3 851.1 985.4 990.1 991.9 31970.0 34523.8 34624.0 42056.6 42069.6 40123.8 40308.2 40402.6 40480.2 40485.0 7284.2 10966.4 11399.6 11591.5 11592.1 9518.4 11340.1 11450.8 11458.0 11458.5 11389.8 11411.8 11420.8 11446.0 11450.7 11360.2 11367.7 11376.8 11429.5 11449.9 11079.9 11084.9 11149.2 11151.9 11166.9 35471.8 35562.6 35566.8 35569.5 35677.6 3894.4 3910.3 3917.9 3920.3 3934.3 25996.9 26167.5 26621.9 26676.5 27112.0 2835.4 3183.4 3190.8 3279.3 3290.8 361503.0 362164.0 362339.0 362622.0 363571.0 36877.3 38547.0 43140.0 44160.6 44225.6 46164.3 46610.8 46668.0 46772.3 46881.3 14374.0 14549.9 14620.9 14623.0 14639.9 10054.3 12551.6 12568.0 12596.7 12606.4 12222.7 12264.2 12271.6 12286.4 12313.8 5805.0 5811.7 5818.7 5819.0 5820.4 1110.9 1132.5 1184.2 1222.3 1261.5 23313.0 28563.3 29305.0 30425.7 30566.8 894.0 1014.5 1021.4 1025.0 1031.5 11683.5 12087.2 12088.7 12091.8 12174.6 488688.0 494706.0 496242.0 496484.0 499751.0 47714.0 47881.0 47984.8 47986.3 48001.5 1435.6 1437.9 1482.4 1489.2 1497.9 22369.2 22462.9 22667.5 22787.3 23277.1 41967.4 42237.9 42260.4 42278.6 42355.8 476325.2 477277.6 477479.0 478306.0 478421.0 29317.4 29574.3 30970.4 31686.2 33021.2 39979.5 40391.0 40759.4 41048.0 41287.0 25869.9 27406.6 29495.7 29821.0 30418.0 28723.6 28892.6 29005.9 29609.4 29805.6 28669.3 32586.0 33864.7 34283.6 34294.5 28482.0 29050.4 29250.0 29272.7 29769.1 22344.2 23685.0 23844.3 23977.3 23979.0 11:14:28 INFO - PROCESS | 1645 | 11:14:28 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:44262.81 Median:23206.71 stddev:130254.67 (561.3%) stddev-sans-first:130578.33 11:14:28 INFO - PROCESS | 1645 | Values: 347.9 398.3 408.2 409.2 450.3 20616.9 21205.9 21538.2 21842.2 22156.4 22623.3 23276.5 23398.6 23412.0 23427.8 5939.7 6599.9 6944.1 6982.9 6991.0 6129.0 6519.4 7086.0 7146.1 7167.4 6672.1 6744.7 7113.1 7147.6 7166.6 6823.2 6887.0 6899.1 6908.1 6927.6 6396.3 6805.3 6869.7 6872.5 6874.7 27534.9 27605.3 27616.6 27759.2 27915.2 3487.9 3517.3 3532.6 3539.2 3700.4 14300.7 16394.9 17050.9 17204.8 17584.8 1846.4 2152.2 2183.6 2211.1 2221.5 140806.0 141241.9 142220.6 142815.0 145409.3 22366.2 22645.0 22711.7 23134.9 23136.9 34660.8 35225.6 35935.9 35984.3 35995.0 11328.9 11525.1 11575.6 11773.4 11890.9 9514.2 9527.4 9530.0 9668.7 9679.7 10343.8 10654.0 10678.0 10693.5 10794.9 4527.1 4537.2 4542.8 4567.5 4575.6 1050.2 1103.8 1108.1 1111.6 1112.1 22217.1 22975.8 23497.0 24268.9 24755.0 936.5 946.9 948.0 971.3 975.4 6943.5 7092.0 7127.5 7171.0 7361.3 746147.3 777252.0 789115.0 799087.0 811985.0 131104.3 141229.0 145407.4 148851.3 158353.0 1343.4 1356.5 1394.1 1394.8 1396.1 16168.8 18479.0 19590.3 20516.6 20723.4 55002.4 67412.0 68744.7 69534.7 70655.0 95955.0 95975.0 96448.5 98676.0 99053.0 8961.6 9572.0 10198.5 11661.8 11668.4 12437.7 12708.7 12820.4 13165.6 13169.9 11172.9 11251.4 11445.8 11472.7 11561.9 10139.1 10348.9 10537.1 10910.0 11031.4 11741.4 11859.5 11953.3 12019.7 12148.2 11567.0 11692.4 11762.1 12030.2 12094.7 11495.4 11603.7 11673.9 11836.3 11865.6 11:14:28 INFO - PROCESS | 1645 | 11:14:28 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:6896.35 Median:2476.16 stddev:4338.59 (175.2%) stddev-sans-first:4331.04 11:14:28 INFO - PROCESS | 1645 | Values: 1390.3 1418.8 1606.1 1643.8 1649.2 9879.5 10700.8 11514.9 11785.1 11936.2 11483.1 11616.5 11699.6 11712.0 11717.1 2324.6 2342.8 2351.9 2355.2 2355.8 2399.8 2475.1 2483.2 2484.8 2500.1 2410.7 2466.7 2477.2 2478.8 2485.8 2389.7 2443.5 2451.4 2456.9 2464.3 2041.9 2448.6 2453.5 2465.5 2467.5 9560.7 11423.8 11604.5 11645.6 11656.6 5013.6 5840.2 5919.0 6012.5 6017.3 3384.8 3429.7 3688.4 3740.2 3759.4 1250.2 1250.8 1262.2 1306.5 1307.5 13626.7 14061.1 15436.1 15900.4 16041.0 4689.9 4756.3 4775.2 4868.5 4873.5 12120.6 12316.0 12339.4 12351.3 12358.9 5986.7 6002.8 6015.2 6026.6 6028.1 5321.4 5383.9 5415.6 5473.1 5497.1 3995.7 4024.7 4036.4 4112.7 4124.3 914.3 1055.9 1062.1 1062.8 1077.3 799.4 943.2 963.2 966.1 1008.9 6481.4 6681.8 6777.3 6787.6 6869.0 1068.9 1096.3 1103.8 1124.8 1144.6 4208.3 4291.4 4296.8 4379.9 4430.7 12578.4 13979.7 14005.5 14553.0 14554.9 14155.0 14267.2 14273.3 14418.8 14463.3 8654.8 10482.4 10752.1 10785.8 10791.4 6708.8 6807.7 6863.3 6953.4 7076.2 11652.7 12991.8 12992.4 13004.3 13010.0 13102.7 13185.5 13206.3 13309.7 13360.5 7375.8 8444.6 8802.6 8805.9 8810.1 10440.2 10485.4 10559.4 10591.0 10678.6 8968.8 9093.4 9156.3 9159.3 9171.7 7445.7 8914.8 8934.4 9027.7 9139.3 7076.6 8053.7 8132.8 8142.4 8228.1 5588.1 5992.0 5998.6 6192.0 6204.3 6032.2 6068.2 6213.1 6276.2 6342.0 11:14:28 INFO - PROCESS | 1645 | 11:14:28 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4717.01 Median:5091.00 stddev:1295.41 (25.4%) stddev-sans-first:1257.37 11:14:28 INFO - PROCESS | 1645 | Values: 374.3 394.3 394.6 400.3 405.1 4845.1 4849.3 4913.8 5417.8 5425.8 4300.4 4734.6 5281.6 5289.7 5333.5 4340.8 4375.4 4396.5 4399.5 4406.4 3947.8 4251.9 4473.9 4492.8 4499.7 3561.5 3922.7 4269.0 4417.3 4457.5 3602.9 3803.0 4328.0 4386.7 4394.6 3857.6 4009.4 4015.9 4084.4 4085.4 4733.0 5163.8 5181.9 5197.7 5214.6 4178.6 4462.9 4504.3 4698.2 4728.3 5678.2 5696.9 5736.9 5748.6 5776.9 4272.3 4348.7 4520.6 4544.5 4584.0 5620.6 5641.3 5790.3 5854.3 5929.8 4364.8 4392.4 5031.3 5079.9 5168.4 4421.6 5230.0 5248.4 5341.0 5341.8 4886.9 5187.3 5311.7 5410.7 5501.0 4850.3 5628.9 5643.8 5667.6 5673.5 1534.8 1579.3 1621.0 1696.6 1811.5 1252.9 1323.0 1330.9 1379.5 1454.7 4711.8 4990.5 5210.0 5353.7 5356.9 4916.3 5272.5 5416.6 5505.9 5943.3 4431.1 4849.8 4966.1 4983.2 5112.7 2421.1 2673.8 2939.9 3020.4 3084.9 5000.4 5233.6 5363.5 6132.8 6166.8 5100.4 5447.4 5872.0 5889.9 6047.9 5661.7 6132.9 6135.4 6141.3 6167.6 5275.5 5567.8 5821.3 5932.4 5967.5 4795.1 5140.7 5257.0 5685.2 5808.4 4800.9 5026.9 5115.8 5151.6 5833.3 4357.4 4402.4 4905.6 5107.4 5145.6 4837.9 4971.1 5817.5 5897.6 5903.1 4696.8 5468.3 5763.6 5775.5 5785.9 5717.2 5788.5 5832.9 5846.3 5874.5 5223.7 5444.2 5526.8 5555.4 5684.3 5077.9 5081.6 5111.3 5700.6 5807.1 4582.0 4869.6 4949.7 4994.5 5410.7 11:14:28 INFO - PROCESS | 1645 | 11:14:28 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:20837.77 Median:17769.79 stddev:30054.15 (169.1%) stddev-sans-first:30100.65 11:14:28 INFO - PROCESS | 1645 | Values: 762.9 770.9 800.9 837.5 892.3 14818.7 14876.9 17681.6 17714.4 17825.2 15893.7 15918.9 16201.4 16295.3 16398.9 935.8 961.5 962.6 963.8 965.3 972.7 994.2 1026.1 1026.5 1033.8 1052.8 1075.9 1078.0 1080.3 1081.7 1054.1 1069.0 1070.3 1072.2 1074.2 791.2 794.8 795.1 795.4 796.9 17341.2 17395.8 17422.2 18267.8 18584.7 14648.1 14674.0 14828.3 14834.1 14850.5 10270.8 10308.4 10576.4 10580.5 10615.0 3783.2 3924.2 3937.4 4049.8 4056.2 110659.0 126542.0 127123.0 129130.4 131522.0 10400.7 10930.6 11332.9 11488.1 11488.7 24059.5 24185.9 24230.4 24357.0 24582.1 8215.8 8255.6 8289.3 8311.9 8338.4 4576.2 4606.6 4639.7 4677.3 4744.0 3608.8 3715.2 3724.6 3736.6 3737.0 654.1 669.3 680.9 694.9 696.5 724.8 733.6 754.4 777.1 780.9 8793.4 9891.8 10808.3 10961.6 11074.1 731.0 741.5 748.8 752.8 753.2 4319.1 5201.7 5262.7 5303.8 5310.9 88722.0 90168.0 109486.0 110251.3 111846.4 80553.0 81438.0 81922.6 89004.7 89507.0 62172.0 63193.7 65934.7 70078.6 75669.0 10992.7 11103.7 11289.8 11409.3 11586.7 41341.0 42312.6 42809.1 51403.4 53594.7 63606.0 64724.0 66232.0 66715.0 67972.3 13158.7 13254.5 13338.3 13439.3 13454.9 14966.0 15029.2 15183.1 15384.9 16235.2 11291.3 14096.6 14631.0 14734.9 14944.2 13585.3 13719.5 14312.9 14334.9 14406.4 13139.6 13756.9 14148.0 14243.1 14288.4 10287.2 10425.4 10822.6 11072.2 11120.0 11035.2 11309.1 11381.7 11462.8 11573.4 11:14:28 INFO - PROCESS | 1645 | -------- Summary: end -------- 11:14:28 INFO - PROCESS | 1645 | 11:14:28 INFO - TEST-INFO | 1645: exit 0 11:14:28 INFO - TEST-OK | dromaeo_css | took 1115961ms 11:14:28 INFO - TEST-START | kraken 11:14:28 INFO - operating with platform_type : mac_ 11:14:28 INFO - Initialising browser for kraken test... 11:14:28 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpC0WSjL/profile http://localhost:49224/getInfo.html) 11:14:31 INFO - PROCESS | 1657 | 2017-05-30 11:14:31.527 plugin-container[1659:10162] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 11:14:31 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:14:31 INFO - PROCESS | 1657 | 2017-05-30 11:14:31.528 plugin-container[1659:10162] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c03, name = 'com.apple.CFPasteboardClient' 11:14:31 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:14:31 INFO - PROCESS | 1657 | 2017-05-30 11:14:31.528 plugin-container[1659:10162] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:14:31 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 11:14:31 INFO - PROCESS | 1657 | colorDepth:24 11:14:31 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 11:14:31 INFO - PROCESS | 1657 | __metrics 11:14:31 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 11:14:32 INFO - TEST-INFO | 1657: exit 0 11:14:32 INFO - Browser initialized. 11:14:32 INFO - Running cycle 1/1 for kraken test... 11:14:32 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/T/tmpC0WSjL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:14:34 INFO - PROCESS | 1660 | 2017-05-30 11:14:34.573 plugin-container[1661:10288] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 11:14:34 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:14:34 INFO - PROCESS | 1660 | 2017-05-30 11:14:34.573 plugin-container[1661:10288] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 11:14:34 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:14:34 INFO - PROCESS | 1660 | 2017-05-30 11:14:34.573 plugin-container[1661:10288] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:14:44 INFO - PROCESS | 1660 | RSS: Main: 174166016 11:14:44 INFO - PROCESS | 1660 | 11:14:47 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 11:14:48 INFO - PROCESS | 1660 | RSS: Main: 187650048 11:14:48 INFO - PROCESS | 1660 | 11:14:50 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 11:14:51 INFO - PROCESS | 1660 | RSS: Main: 195629056 11:14:51 INFO - PROCESS | 1660 | 11:14:54 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 11:14:55 INFO - PROCESS | 1660 | RSS: Main: 199340032 11:14:55 INFO - PROCESS | 1660 | 11:14:57 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 11:14:57 INFO - PROCESS | 1660 | RSS: Main: 199675904 11:14:57 INFO - PROCESS | 1660 | 11:14:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:15:00 INFO - PROCESS | 1660 | RSS: Main: 199667712 11:15:00 INFO - PROCESS | 1660 | 11:15:03 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 11:15:04 INFO - PROCESS | 1660 | RSS: Main: 203481088 11:15:04 INFO - PROCESS | 1660 | 11:15:07 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 11:15:08 INFO - PROCESS | 1660 | RSS: Main: 203022336 11:15:08 INFO - PROCESS | 1660 | 11:15:11 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 11:15:11 INFO - PROCESS | 1660 | RSS: Main: 204623872 11:15:11 INFO - PROCESS | 1660 | 11:15:13 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:15:14 INFO - PROCESS | 1660 | RSS: Main: 204185600 11:15:14 INFO - PROCESS | 1660 | 11:15:15 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:15:16 INFO - PROCESS | 1660 | RSS: Main: 204185600 11:15:16 INFO - PROCESS | 1660 | 11:15:18 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:15:19 INFO - PROCESS | 1660 | RSS: Main: 204201984 11:15:19 INFO - PROCESS | 1660 | 11:15:22 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:15:23 INFO - PROCESS | 1660 | RSS: Main: 204578816 11:15:23 INFO - PROCESS | 1660 | 11:15:25 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:15:26 INFO - PROCESS | 1660 | RSS: Main: 204681216 11:15:26 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 11:15:28 INFO - PROCESS | 1660 | RSS: Main: 206827520 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | __start_tp_report 11:15:28 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 11:15:28 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 11:15:28 INFO - PROCESS | 1660 | |i|pagename|runs| 11:15:28 INFO - PROCESS | 1660 | |0;ai-astar;106;118;118;128;171;165;118;118;117;119 11:15:28 INFO - PROCESS | 1660 | |1;audio-beat-detection;141;131;112;145;112;112;113;111;128;124 11:15:28 INFO - PROCESS | 1660 | |2;audio-dft;203;195;196;200;237;285;196;195;196;195 11:15:28 INFO - PROCESS | 1660 | |3;audio-fft;79;59;63;89;64;58;64;61;73;61 11:15:28 INFO - PROCESS | 1660 | |4;audio-oscillator;80;68;84;74;73;71;79;73;58;96 11:15:28 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;91;99;136;80;137;132;90;91;91;128 11:15:28 INFO - PROCESS | 1660 | |6;imaging-darkroom;146;115;155;154;97;97;106;96;97;131 11:15:28 INFO - PROCESS | 1660 | |7;imaging-desaturate;90;94;92;104;93;90;91;92;92;90 11:15:28 INFO - PROCESS | 1660 | |8;json-parse-financial;65;65;64;67;66;67;67;68;69;68 11:15:28 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;64;90;68;62;53;59;85;58;60;59 11:15:28 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;81;86;86;86;85;80;86;83;87;90 11:15:28 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;142;165;141;133;139;150;176;141;186;127 11:15:28 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;197;173;165;165;166;165;161;155;155;156 11:15:28 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;66;65;66;64;69;77;81;84;57;59 11:15:28 INFO - PROCESS | 1660 | __end_tp_report 11:15:28 INFO - PROCESS | 1660 | __start_cc_report 11:15:28 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1010 11:15:28 INFO - PROCESS | 1660 | __end_cc_report 11:15:28 INFO - PROCESS | 1660 | __startTimestamp1496168128591__endTimestamp 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | ------- Summary: start ------- 11:15:28 INFO - PROCESS | 1660 | Number of tests: 14 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:127.80 Median:118.50 stddev:21.87 (18.5%) stddev-sans-first:21.72 11:15:28 INFO - PROCESS | 1660 | Values: 106.0 118.0 118.0 128.0 171.0 165.0 118.0 118.0 117.0 119.0 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:122.90 Median:126.00 stddev:12.93 (10.3%) stddev-sans-first:11.94 11:15:28 INFO - PROCESS | 1660 | Values: 141.0 131.0 112.0 145.0 112.0 112.0 113.0 111.0 128.0 124.0 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:209.80 Median:198.00 stddev:29.37 (14.8%) stddev-sans-first:31.05 11:15:28 INFO - PROCESS | 1660 | Values: 203.0 195.0 196.0 200.0 237.0 285.0 196.0 195.0 196.0 195.0 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:67.10 Median:64.00 stddev:10.08 (15.8%) stddev-sans-first:9.73 11:15:28 INFO - PROCESS | 1660 | Values: 79.0 59.0 63.0 89.0 64.0 58.0 64.0 61.0 73.0 61.0 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:76.50 stddev:10.12 (13.2%) stddev-sans-first:10.61 11:15:28 INFO - PROCESS | 1660 | Values: 80.0 68.0 84.0 74.0 73.0 71.0 79.0 73.0 58.0 96.0 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:107.50 Median:90.50 stddev:22.74 (25.1%) stddev-sans-first:23.32 11:15:28 INFO - PROCESS | 1660 | Values: 91.0 99.0 136.0 80.0 137.0 132.0 90.0 91.0 91.0 128.0 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:119.40 Median:125.50 stddev:24.85 (19.8%) stddev-sans-first:24.42 11:15:28 INFO - PROCESS | 1660 | Values: 146.0 115.0 155.0 154.0 97.0 97.0 106.0 96.0 97.0 131.0 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:92.80 Median:92.00 stddev:4.16 (4.5%) stddev-sans-first:4.28 11:15:28 INFO - PROCESS | 1660 | Values: 90.0 94.0 92.0 104.0 93.0 90.0 91.0 92.0 92.0 90.0 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:66.60 Median:67.00 stddev:1.58 (2.4%) stddev-sans-first:1.56 11:15:28 INFO - PROCESS | 1660 | Values: 65.0 65.0 64.0 67.0 66.0 67.0 67.0 68.0 69.0 68.0 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:65.80 Median:63.00 stddev:12.15 (19.3%) stddev-sans-first:12.86 11:15:28 INFO - PROCESS | 1660 | Values: 64.0 90.0 68.0 62.0 53.0 59.0 85.0 58.0 60.0 59.0 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:85.00 Median:86.00 stddev:2.94 (3.4%) stddev-sans-first:2.74 11:15:28 INFO - PROCESS | 1660 | Values: 81.0 86.0 86.0 86.0 85.0 80.0 86.0 83.0 87.0 90.0 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:150.00 Median:146.00 stddev:19.33 (13.2%) stddev-sans-first:20.28 11:15:28 INFO - PROCESS | 1660 | Values: 142.0 165.0 141.0 133.0 139.0 150.0 176.0 141.0 186.0 127.0 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.80 Median:165.00 stddev:12.38 (7.5%) stddev-sans-first:6.10 11:15:28 INFO - PROCESS | 1660 | Values: 197.0 173.0 165.0 165.0 166.0 165.0 161.0 155.0 155.0 156.0 11:15:28 INFO - PROCESS | 1660 | 11:15:28 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:67.50 stddev:9.04 (13.4%) stddev-sans-first:9.53 11:15:28 INFO - PROCESS | 1660 | Values: 66.0 65.0 66.0 64.0 69.0 77.0 81.0 84.0 57.0 59.0 11:15:28 INFO - PROCESS | 1660 | -------- Summary: end -------- 11:15:28 INFO - PROCESS | 1660 | 11:15:29 INFO - TEST-INFO | 1660: exit 0 11:15:29 INFO - TEST-OK | kraken | took 60744ms 11:15:29 INFO - SUITE-END | took 1176s 11:15:29 INFO - Completed test suite (00:19:36) 11:15:29 INFO - javascript benchmark 11:15:29 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7892.25389976007, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2985.148933839819, 3608.4406916927874, 3628.8548458061678, 3645.7265705072127, 3672.2288319452914, 6151.938480615198, 6157.261128664555, 6174.876502469948, 6248.343923887995, 6260.906086408708, 4430.0, 5750.4761238271785, 6153.0, 6197.535184861131, 6266.530010249238, 4443.488998765135, 5544.556435485151, 5678.148277758328, 5690.516306113986, 5724.513416359614, 5697.373288938214, 5701.74342154602, 5705.743241554119, 5714.628549144292, 5721.942780572203, 5460.235567020621, 5474.42518535554, 5590.301212348457, 5767.567432442563, 5802.0, 5679.4036626154275, 5703.800366987177, 5733.856653583652, 5739.253896993385, 5794.536437085023, 5546.195801608785, 5602.467765562305, 5603.551715862721, 5636.323641163046, 5667.064934285838, 5496.587755918322, 6007.0, 6146.231721034871, 6221.0, 6252.31224565302, 6335.429811317003, 6341.968290158566, 6347.96826015867, 6394.23269207698, 6422.454091402236, 6636.236832764197, 6664.067030615718, 6664.266930637626, 6673.432758215557, 6678.332166783332, 5195.480451954774, 6230.408111229426, 6278.215223097113, 6321.431071203568, 6332.905006424908, 6363.809085727436, 6434.581752186186, 6458.773942911976, 6705.329467053256, 6786.0, 6372.649504277217, 6432.099506069154, 6455.031745238158, 6455.289918109004, 6458.54790164684, 6519.5436319457185, 6588.604683718896, 6620.238672552622, 6639.535232533773, 6644.269130395653, 6261.217347831521, 6476.967615161988, 6486.54594178403, 6506.284308726036, 6516.348365163446, 6336.303006669263, 6346.079818426302, 6365.363463653597, 6370.203724534434, 6396.712147953353, 5295.4969277918535, 5309.973450132802, 5328.786848526093, 5333.0, 5342.866428339323, 3939.2712348215673, 4678.8596342109795, 4694.600958918462, 4706.10583989041, 4713.67004309695, 5993.0710739835395, 6138.602983404986, 6216.316205217422, 6225.097360882737, 6312.715927783261, 6607.933920660828, 6617.073609694647, 6640.667966601651, 6650.767223147167, 6658.134442522538, 6263.686815659199, 6273.341299163613, 6305.653189074553, 6314.431701146918, 6322.747090116436, 5591.217229587861, 5618.91571626426, 5717.199592057115, 5829.766809327664, 5838.9124163137585, 6568.113304703804, 6598.63707496102, 6680.03139544773, 6711.161104861892, 6715.664216789043, 6588.176477940257, 6598.406143447113, 6604.933950660432, 6615.206175259, 6630.408701911603, 6687.261312058997, 6788.796336109925, 6814.0, 6826.044353790573, 6838.965805170942, 6267.2479302484, 6355.841103972438, 6423.0, 6445.16212892321, 6451.70967306463, 5546.251256080377, 6314.8317561251315, 6619.371159739817, 6636.203655561363, 6648.800535983928, 6205.348438414082, 6277.96861015692, 6305.90541141874, 6354.17395738551, 6455.48356131499, 6474.5791523550815, 6477.449416799436, 6478.060681201294, 6490.0, 6498.090267362479, 6213.2233470816145, 6217.782377616762, 6230.096635987847, 6236.126942228182, 6260.40526150015, 6366.331535188739, 6386.233651961795, 6477.255115661571, 6477.319881412383, 6489.837754056186, 6294.1188233648245, 6306.432421082125, 6335.683215839282, 6336.619802811846, 6348.555601107878], "value": 6028.403907033645, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [831.2850948184562, 851.1488511488511, 985.4136788610774, 990.0940639315027, 991.9355241909279, 31970.0, 34523.82738086312, 34623.96128116157, 42056.57943420565, 42069.57930420695, 40123.79628611139, 40308.19383612326, 40402.595974040254, 40480.19039619206, 40485.0, 7284.235155308683, 10966.396848173346, 11399.601013964513, 11591.478383472764, 11592.130590205727, 9518.428894266332, 11340.092792576615, 11450.770984580304, 11458.0, 11458.484368203452, 11389.829152562717, 11411.771764564704, 11420.828687569694, 11446.0, 11450.71373215672, 11360.204785665006, 11367.658970230907, 11376.772464550746, 11429.542818287258, 11449.885501145007, 11079.944600277027, 11084.944575277153, 11149.16381271408, 11151.944240278746, 11166.944165279121, 35471.82264088663, 35562.64437355619, 35566.822165889265, 35569.46645800315, 35677.64322356769, 3894.396368562849, 3910.3156947534067, 3917.8628747993966, 3920.2551515212303, 3934.2721596504452, 25996.87001564999, 26167.476650467073, 26621.866890665617, 26676.46647067067, 27112.0, 2835.376217232206, 3183.442897492953, 3190.8245046522434, 3279.262166012652, 3290.7860989035635, 361503.0, 362164.0, 362339.0, 362622.0, 363571.0, 36877.26245475075, 38547.0, 43140.0, 44160.558394416286, 44225.55774442279, 46164.30753538699, 46610.76694616505, 46668.0, 46772.298415523794, 46881.29678054832, 14374.0, 14549.854501455062, 14620.85379146195, 14622.976391652483, 14639.92680036593, 10054.346467479736, 12551.560695375618, 12567.994560435143, 12596.685082873002, 12606.369681516071, 12222.694432639077, 12264.202826816228, 12271.57049503263, 12286.385680715928, 12313.815292770616, 5805.0, 5811.651300921958, 5818.738156782955, 5819.0, 5820.35976042635, 1110.8944650258213, 1132.4960392625367, 1184.1888306510068, 1222.2727477151082, 1261.5080118753694, 23313.0, 28563.28591785222, 29305.0, 30425.69574304273, 30566.847165764473, 893.9910600894038, 1014.4876837197169, 1021.3514418344256, 1024.954546362706, 1031.4791030529534, 11683.532658693557, 12087.153899226969, 12088.697782555506, 12091.818622720666, 12174.573889913987, 488688.0, 494706.0, 496242.0, 496484.0, 499751.0, 47714.0, 47881.0, 47984.760076199396, 47986.28020579694, 48001.519984800405, 1435.612384656137, 1437.856214378554, 1482.392219190127, 1489.2107183192925, 1497.9325930333162, 22369.217077402212, 22462.88768556147, 22667.506574276485, 22787.31638050861, 23277.0689172438, 41967.370489443296, 42237.94405139896, 42260.36609450799, 42278.577214228695, 42355.7882210587, 476325.2367476281, 477277.6136119297, 477479.0, 478306.0, 478421.0, 29317.41365172727, 29574.260643484085, 30970.380592387577, 31686.207844804063, 33021.17447063843, 39979.54147072958, 40391.0, 40759.388609170295, 41047.97380065522, 41287.0, 25869.870650646626, 27406.5889011661, 29495.705042950154, 29821.0, 30418.0, 28723.569146463236, 28892.566611500424, 29005.854970725013, 29609.40781184321, 29805.552916705834, 28669.28326791847, 32586.0, 33864.66135338615, 34283.628654854525, 34294.48558271578, 28482.0, 29050.418991619626, 29250.0, 29272.707272926997, 29769.106926792232, 22344.21795237159, 23685.0, 23844.284671459885, 23977.28068157958, 23979.0], "value": 18436.930321495787, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.9110384496526, 398.2930298719773, 408.1612286750726, 409.19797197492915, 450.29303992731406, 20616.866072366032, 21205.939703014865, 21538.21822890412, 21842.235521756742, 22156.446088847766, 22623.321300361014, 23276.534469310605, 23398.64902026471, 23412.0, 23427.76572234277, 5939.70301484924, 6599.934000660004, 6944.131983502062, 6982.860342793141, 6991.0, 6129.049997250433, 6519.445847102978, 7086.0, 7146.106736657918, 7167.4266058715175, 6672.132622759034, 6744.730210791587, 7113.146422429316, 7147.642617869112, 7166.570005799643, 6823.1812182537915, 6887.0, 6899.068625735536, 6908.1364829396325, 6927.618980956046, 6396.3283855194995, 6805.285445028299, 6869.690863911136, 6872.484563657696, 6874.65626718667, 27534.862325688442, 27605.30986725348, 27616.619169041467, 27759.167224983285, 27915.16254512368, 3487.89536313911, 3517.3317069756663, 3532.6290739472242, 3539.2213712983103, 3700.4079347304573, 14300.713985720331, 16394.918025409916, 17050.89169203998, 17204.827951720446, 17584.824151758574, 1846.3722334406232, 2152.235956235545, 2183.617866873307, 2211.148707747529, 2221.4557433428913, 140806.0, 141241.8813717795, 142220.57779422175, 142814.99999999895, 145409.27295363456, 22366.217182398865, 22644.99999999967, 22711.659325110137, 23134.884325578263, 23136.876521669696, 34660.82669586686, 35225.64774352275, 35935.92192234237, 35984.28031439409, 35995.0, 11328.94335528317, 11525.135614828921, 11575.594854180063, 11773.352465614473, 11890.940545297219, 9514.238860891111, 9527.38072025316, 9529.952350238205, 9668.743063401713, 9679.70960871175, 10343.793124137475, 10654.0, 10678.0, 10693.465326733633, 10794.946025269823, 4527.139843429738, 4537.160625284333, 4542.750743545552, 4567.5432456754725, 4575.643543575062, 1050.2123407444417, 1103.8013157631704, 1108.0636861851722, 1111.6498303034518, 1112.1214240749878, 22217.11131554752, 22975.770242297698, 23497.0, 24268.87865560696, 24755.0, 936.513013233115, 946.8579713043099, 947.9763005924907, 971.3152227679469, 975.4439969217478, 6943.479239057091, 7091.964540177266, 7127.4654400920135, 7170.96414517924, 7361.337479626859, 746147.2692636502, 777252.0, 789115.0, 799087.0, 811985.0, 131104.34447827892, 141229.0, 145407.36481588046, 148851.25574372275, 158353.0, 1343.4223283987978, 1356.5387768158873, 1394.1217033268977, 1394.8186735724291, 1396.0716123777777, 16168.78734094924, 18479.0, 19590.314338998636, 20516.589668206852, 20723.378298651063, 55002.44997549973, 67412.0, 68744.65627671829, 69534.65232673804, 70655.0, 95955.0, 95975.0, 96448.51775741077, 98676.0, 99053.0, 8961.551922403984, 9571.952140239255, 10198.541065651914, 11661.76676466483, 11668.416579171177, 12437.689057773629, 12708.745825083262, 12820.358982051046, 13165.60503184906, 13169.934150329187, 11172.944135279271, 11251.381174035507, 11445.771084578428, 11472.713182170513, 11561.942190289332, 10139.138173255062, 10348.896511034793, 10537.104346130665, 10910.0, 11031.393273370042, 11741.412929353328, 11859.466324015268, 11953.282803031845, 12019.699507512381, 12148.21036632616, 11567.0, 11692.35692036946, 11762.117841162119, 12030.218035827644, 12094.697632559257, 11495.425228738362, 11603.6518904433, 11673.941630291794, 11836.349000805038, 11865.5847045353], "value": 11934.087664553594, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1390.3048475762118, 1418.751718449271, 1606.084531816865, 1643.8000259810337, 1649.2083799776103, 9879.456629885362, 10700.839487407695, 11514.884851151486, 11785.057195424346, 11936.164468487199, 11483.138764592648, 11616.477258523366, 11699.590514332, 11712.0, 11717.121215908799, 2324.593196190664, 2342.80086192673, 2351.9412014699597, 2355.199232261031, 2355.8468699534474, 2399.78401943825, 2475.096589744739, 2483.2301986384277, 2484.757621189405, 2500.1374525788665, 2410.6986626671664, 2466.7409921958115, 2477.2196758021255, 2478.8140889433275, 2485.8135639826996, 2389.749076346976, 2443.4502236996623, 2451.3626457121186, 2456.8894399751966, 2464.260721783458, 2041.8672786268994, 2448.5592593333195, 2453.509298140379, 2465.4822487277693, 2467.543504451682, 9560.665376711851, 11423.771524569545, 11604.535818567247, 11645.592404265895, 11656.601207855027, 5013.6490445668815, 5840.182374467536, 5919.0, 6012.488938440238, 6017.308009578911, 3384.849151288574, 3429.742769292313, 3688.428293614494, 3740.214554943465, 3759.4172903200047, 1250.1623911978995, 1250.8123781432766, 1262.1858901008798, 1306.45129045801, 1307.45740517686, 13626.727465450636, 14061.086029408056, 15436.073835569901, 15900.363985440568, 16041.0, 4689.85930422081, 4756.2781375731265, 4775.164346239394, 4868.464468908417, 4873.488283730228, 12120.636380908587, 12316.014718822473, 12339.444724987397, 12351.25892446438, 12358.876411235953, 5986.73059712314, 6002.759889604367, 6015.157877897098, 6026.638401695912, 6028.065649824284, 5321.441248668912, 5383.892322153535, 5415.6479828811, 5473.0969390051, 5497.120460726264, 3995.6603688686787, 4024.6579040781276, 4036.354183330653, 4112.691548133842, 4124.278251306009, 914.2822884036, 1055.8996895295088, 1062.134360496193, 1062.7502536903821, 1077.3320541264309, 799.4403917257943, 943.2029934705163, 963.2324684204423, 966.1304825656965, 1008.8688470498788, 6481.351864813481, 6681.832954176184, 6777.288384719632, 6787.6266805325195, 6868.965655171693, 1068.8663917010374, 1096.3421946831998, 1103.8454616353717, 1124.7796141186905, 1144.5879483385975, 4208.263553878121, 4291.356296555479, 4296.828126874952, 4379.934300985487, 4430.712003719748, 12578.433970471351, 13979.720405591832, 14005.509807156905, 14552.981291309507, 14554.854451455562, 14155.0, 14267.215303158218, 14273.286335683175, 14418.783718244234, 14463.349149288308, 8654.826903461895, 10482.37105773656, 10752.086072683753, 10785.78428431427, 10791.40647264408, 6708.765193218214, 6807.6725038617, 6863.313668633296, 6953.443724501926, 7076.186238582629, 11652.650420487398, 12991.805122923353, 12992.350382481027, 13004.349782511026, 13010.0, 13102.672433189247, 13185.472581097032, 13206.273654948685, 13309.669033096612, 13360.465581376637, 7375.778726638209, 8444.577771111542, 8802.559872006246, 8805.867911981453, 8810.118988101138, 10440.216983726099, 10485.423301718478, 10559.419231942316, 10590.993855583707, 10678.62624808128, 8968.82062358736, 9093.363464557417, 9156.26749860023, 9159.267258619428, 9171.724848254564, 7445.70217191328, 8914.821703566022, 8934.374593778633, 9027.684031058881, 9139.314551408805, 7076.610786406797, 8053.718119865776, 8132.75601731949, 8142.389320800845, 8228.136045715113, 5588.0888357732965, 5991.970040149771, 5998.580099393175, 6192.0, 6204.348543402878, 6032.155498230163, 6068.21113255274, 6213.099100630474, 6276.246850377984, 6342.0], "value": 5257.169048860361, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [374.29258701054994, 394.26863168821836, 394.6231349061646, 400.2967373824377, 405.07440498461006, 4845.055214233226, 4849.321095046692, 4913.82801601944, 5417.756200970956, 5425.806322609024, 4300.440942677447, 4734.597559207463, 5281.577473802096, 5289.735513224343, 5333.466653334664, 4340.804663790137, 4375.4311939447825, 4396.516383197845, 4399.5160532341415, 4406.405135306724, 3947.802280153085, 4251.914961700764, 4473.932891006638, 4492.820287188509, 4499.6850220484575, 3561.519194908693, 3922.7450215735885, 4268.9573104268875, 4417.293233082707, 4457.481522939545, 3602.873899413534, 3802.99220706513, 4327.978360108211, 4386.670999675037, 4394.6044855962955, 3857.556381016191, 4009.3985902114914, 4015.859444919414, 4084.448599439097, 4085.387191921206, 4732.9763351183365, 5163.845084647466, 5181.870453238662, 5197.7401129943355, 5214.60890433219, 4178.603032711888, 4462.8884277892985, 4504.346869703874, 4698.214678422212, 4728.338032675429, 5678.233438485834, 5696.892149904562, 5736.8852622947725, 5748.568857335675, 5776.913346299809, 4272.273713468718, 4348.673459699077, 4520.570545798144, 4544.545545445429, 4583.960137738708, 5620.606557540974, 5641.294838145232, 5790.2762154730235, 5854.297484301868, 5929.762809487615, 4364.8433165211245, 4392.363107349416, 5031.345925029723, 5079.87300317495, 5168.379794424618, 4421.646268298528, 5230.0, 5248.3701955765555, 5341.023821186186, 5341.813036543703, 4886.877828054327, 5187.299714538489, 5311.7344132793205, 5410.702411367334, 5500.972495137578, 4850.320955066293, 5628.85927851807, 5643.843012182575, 5667.574931880125, 5673.468163595806, 1534.77745726869, 1579.344572002629, 1621.0192833335661, 1696.575856035991, 1811.4565630310854, 1252.949882004728, 1323.0407954233103, 1330.9020058451622, 1379.4964837834318, 1454.7236025155187, 4711.7879695413785, 4990.479807697162, 5209.958008398336, 5353.705546194997, 5356.91964620531, 4916.336294600254, 5272.497338258578, 5416.647917885325, 5505.9174112387545, 5943.286805583358, 4431.091626216617, 4849.811796109976, 4966.081274964144, 4983.177775667047, 5112.667676601009, 2421.055788242587, 2673.799465040091, 2939.9265018374713, 3020.3808219314988, 3084.8766049358223, 5000.424951130666, 5233.6074794389815, 5363.463653634605, 6132.846678833065, 6166.753329866845, 5100.362454693163, 5447.428020057916, 5871.970640146772, 5889.882202355929, 6047.879042419215, 5661.745221464962, 6132.908006379907, 6135.447809697149, 6141.293751218667, 6167.599106058092, 5275.525202731619, 5567.826338986739, 5821.301443826768, 5932.406759324033, 5967.46292833647, 4795.064962332452, 5140.665856719451, 5257.0, 5685.204071430086, 5808.390119037609, 4800.91160345251, 5026.899462010812, 5115.820946267012, 5151.613628977917, 5833.329167145833, 4357.4117494139155, 4402.427684401007, 4905.607551395951, 5107.387113546398, 5145.555860966011, 4837.879053023703, 4971.072852446527, 5817.476427121579, 5897.587168898136, 5903.114532820111, 4696.788644510937, 5468.316460442445, 5763.596548241583, 5775.48020678141, 5785.942140578541, 5717.1995920570325, 5788.53691704671, 5832.854178645568, 5846.269216347957, 5874.500667443144, 5223.738813059256, 5444.237806707143, 5526.778928842801, 5555.361168455234, 5684.261046063985, 5077.873053173553, 5081.644284900021, 5111.3355263815465, 5700.629459085146, 5807.070868660994, 4581.977090114528, 4869.586085182799, 4949.698390248552, 4994.450610432777, 5410.673410433394], "value": 4351.061912498837, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [762.8855671649255, 770.8650986077439, 800.8558459477296, 837.505871535794, 892.3396686452023, 14818.7404070654, 14876.925615371922, 17681.64636707265, 17714.379996700118, 17825.197866096023, 15893.682126357495, 15918.920405397987, 16201.432949846758, 16295.348186072573, 16398.918005409985, 935.8175155844613, 961.471190845036, 962.5860879821674, 963.8120566489538, 965.3194497878978, 972.65470757881, 994.1599348550463, 1026.0611540440489, 1026.4816267784759, 1033.7622346860226, 1052.7578656908915, 1075.8977897099762, 1077.983830242547, 1080.2816127275353, 1081.7025318037524, 1054.1092776603757, 1068.9946550267198, 1070.3203465799234, 1072.1744256922204, 1074.1514203779004, 791.2087912087912, 794.8410317936435, 795.0538858758059, 795.4312666443462, 796.8884356190138, 17341.219645116, 17395.826041739674, 17422.21600028002, 18267.817321826744, 18584.721229181574, 14648.121112733164, 14674.0, 14828.3327250274, 14834.10995340283, 14850.480233191895, 10270.845937310947, 10308.381497110197, 10576.418296993663, 10580.470976451224, 10615.0, 3783.16770310534, 3924.156306394139, 3937.370020796659, 4049.797510124482, 4056.2293164298985, 110659.0, 126542.0, 127123.0, 129130.35434822766, 131522.0, 10400.687979360631, 10930.63367079115, 11332.943335283271, 11488.138389620812, 11488.655340339803, 24059.51880962371, 24185.879070604533, 24230.394240144138, 24357.0, 24582.139625113035, 8215.753527394188, 8255.587220638945, 8289.336853051862, 8311.877896483897, 8338.374621903382, 4576.199165146086, 4606.608438282784, 4639.675222734376, 4677.321788340671, 4744.027474367746, 3608.80151591665, 3715.20123173519, 3724.5902950675404, 3736.645018723217, 3736.981315093407, 654.1006116589689, 669.334012657409, 680.9216940051858, 694.8783962806489, 696.4985210648325, 724.8043028382413, 733.600187897597, 754.3550264523753, 777.149021821105, 780.8945792318078, 8793.428427152214, 9891.752706182258, 10808.297460665031, 10961.616343427942, 11074.114070874311, 730.989035164473, 741.4772585327331, 748.8389996150854, 752.834376437183, 753.2128925273103, 4319.114581510783, 5201.687898726089, 5262.657927234717, 5303.761330740126, 5310.893782124336, 88722.0, 90168.0, 109486.0, 110251.34622980662, 111846.44076779563, 80553.0, 81438.0, 81922.59038704887, 89004.6649300261, 89507.0, 62172.0, 63193.684031580466, 65934.67032664806, 70078.59842803115, 75669.0, 10992.739702103305, 11103.723071846842, 11289.751681298825, 11409.315441073562, 11586.71033224176, 41341.0, 42312.576874230865, 42809.14381712286, 51403.42982850768, 53594.73202633962, 63606.04590931232, 64724.0, 66232.0, 66715.0, 67972.3202767966, 13158.671033223864, 13254.53609123676, 13338.333083345988, 13439.260840653858, 13454.93272533631, 14966.0, 15029.248537573296, 15183.089014659156, 15384.923075384551, 16235.188240588159, 11291.266067705574, 14096.647583810487, 14631.048981816148, 14734.852651473348, 14944.178070206242, 13585.320733963064, 13719.45122195101, 14312.928435358173, 14334.928325358724, 14406.351714172677, 13139.60581182566, 13756.931215344259, 14148.009639325148, 14243.145411275356, 14288.42846286137, 10287.215131723526, 10425.426601536989, 10822.56709731602, 11072.224944254147, 11119.999200072032, 11035.172362072715, 11309.095272378354, 11381.715457113638, 11462.82805757931, 11573.363465009505], "value": 7964.162984622433, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1524.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 118.0, 118.0, 128.0, 171.0, 165.0, 118.0, 118.0, 117.0, 119.0], "value": 127.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 131.0, 112.0, 145.0, 112.0, 112.0, 113.0, 111.0, 128.0, 124.0], "value": 122.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 195.0, 196.0, 200.0, 237.0, 285.0, 196.0, 195.0, 196.0, 195.0], "value": 209.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 63.0, 89.0, 64.0, 58.0, 64.0, 61.0, 73.0, 61.0], "value": 67.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 68.0, 84.0, 74.0, 73.0, 71.0, 79.0, 73.0, 58.0, 96.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 99.0, 136.0, 80.0, 137.0, 132.0, 90.0, 91.0, 91.0, 128.0], "value": 107.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 155.0, 154.0, 97.0, 97.0, 106.0, 96.0, 97.0, 131.0], "value": 119.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 92.0, 104.0, 93.0, 90.0, 91.0, 92.0, 92.0, 90.0], "value": 92.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 67.0, 66.0, 67.0, 67.0, 68.0, 69.0, 68.0], "value": 66.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 90.0, 68.0, 62.0, 53.0, 59.0, 85.0, 58.0, 60.0, 59.0], "value": 65.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 86.0, 86.0, 86.0, 85.0, 80.0, 86.0, 83.0, 87.0, 90.0], "value": 85.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 165.0, 141.0, 133.0, 139.0, 150.0, 176.0, 141.0, 186.0, 127.0], "value": 150.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 173.0, 165.0, 165.0, 166.0, 165.0, 161.0, 155.0, 155.0, 156.0], "value": 165.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 66.0, 64.0, 69.0, 77.0, 81.0, 84.0, 57.0, 59.0], "value": 68.8, "unit": "score"}]}]} 11:15:29 INFO - Return code: 0 11:15:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:15:29 ERROR - # TBPL SUCCESS # 11:15:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:15:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:15:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:15:29 INFO - 2017-05-30 11:15:29.940 screenresolution[1665:10681] starting screenresolution argv=screenresolution get 11:15:29 INFO - 2017-05-30 11:15:29.942 screenresolution[1665:10681] Display 0: 1600x1200x32@60 11:15:29 INFO - 2017-05-30 11:15:29.948 screenresolution[1666:10684] starting screenresolution argv=screenresolution list 11:15:29 INFO - Available Modes on Display 0 11:15:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:15:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:15:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:15:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:15:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:15:30 INFO - Intel Iris: 11:15:30 INFO - Chipset Model: Intel Iris 11:15:30 INFO - Type: GPU 11:15:30 INFO - Bus: Built-In 11:15:30 INFO - VRAM (Dynamic, Max): 1536 MB 11:15:30 INFO - Vendor: Intel (0x8086) 11:15:30 INFO - Device ID: 0x0a2e 11:15:30 INFO - Revision ID: 0x0009 11:15:30 INFO - Displays: 11:15:30 INFO - Display: 11:15:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:15:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:15:30 INFO - Display Serial Number: 1600x1200 60 11:15:30 INFO - Main Display: Yes 11:15:30 INFO - Mirror: Off 11:15:30 INFO - Online: Yes 11:15:30 INFO - Rotation: Supported 11:15:30 INFO - Return code: 0 11:15:30 INFO - Running post-action listener: _resource_record_post_action 11:15:30 INFO - [mozharness: 2017-05-30 18:15:30.183454Z] Finished run-tests step (success) 11:15:30 INFO - Running post-run listener: _resource_record_post_run 11:15:30 INFO - Total resource usage - Wall time: 1192s; CPU: 55.0%; Read bytes: 40802304; Write bytes: 425376768; Read time: 257; Write time: 1427 11:15:30 INFO - TinderboxPrint: CPU usage
54.5% 11:15:30 INFO - TinderboxPrint: I/O read bytes / time
40,802,304 / 257 11:15:30 INFO - TinderboxPrint: I/O write bytes / time
425,376,768 / 1,427 11:15:30 INFO - TinderboxPrint: CPU idle
2,168.3 (45.5%) 11:15:30 INFO - TinderboxPrint: CPU system
959.2 (20.1%) 11:15:30 INFO - TinderboxPrint: CPU user
1,642.1 (34.4%) 11:15:30 INFO - TinderboxPrint: Swap in / out
162,336,768 / 0 11:15:30 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 119802368; Write bytes: 113127424; Read time: 10890; Write time: 239 11:15:30 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 38676480; Write bytes: 301177856; Read time: 238; Write time: 1152 11:15:30 INFO - Running post-run listener: _upload_blobber_files 11:15:30 INFO - Blob upload gear active. 11:15:30 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:15:30 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 11:15:30 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'] 11:15:30 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 11:15:31 INFO - (blobuploader) - INFO - Open directory for files ... 11:15:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:15:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:15:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:32 INFO - (blobuploader) - INFO - Done attempting. 11:15:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:15:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:15:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:33 INFO - (blobuploader) - INFO - Done attempting. 11:15:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:15:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:15:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:34 INFO - (blobuploader) - INFO - Done attempting. 11:15:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:15:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:15:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:15:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:15:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:15:36 INFO - (blobuploader) - INFO - Done attempting. 11:15:36 INFO - (blobuploader) - INFO - Iteration through files over. 11:15:36 INFO - Return code: 0 11:15:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:15:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:15:36 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/16a47df018a25a973b95ad131eb39d1e338206679d15dc4fcaaaef2a0d6da26870ee8ca106cb898dff1296cd039928c2f4cf5d2b41d200bd10c9142f26499f51", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78ad7702ea68a7fe3cacb160d5673a29aebb591e408bca7d2e42c1d58f0d7c7df7921cba157b3470d9c115caff8efa1c90864fb1413d7fa7448b1447f96625b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ae2c13dd559cb06473a2698e54674c00a24d4068732a535fa5812e86ab8d047475759dcffb2830574dd8d39edcc77b0c83d6f1f4dba712df0735540843bf3e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/649eb3678ed85660bf353919eded31a97a2b7406de31e48757be4d8fd91d939649f8df8f8fbca78de6342fb2280eebe5e26b1e6f396eedd542e5e175a98ccbeb"} 11:15:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:15:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:15:36 INFO - Contents: 11:15:36 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/16a47df018a25a973b95ad131eb39d1e338206679d15dc4fcaaaef2a0d6da26870ee8ca106cb898dff1296cd039928c2f4cf5d2b41d200bd10c9142f26499f51", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78ad7702ea68a7fe3cacb160d5673a29aebb591e408bca7d2e42c1d58f0d7c7df7921cba157b3470d9c115caff8efa1c90864fb1413d7fa7448b1447f96625b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ae2c13dd559cb06473a2698e54674c00a24d4068732a535fa5812e86ab8d047475759dcffb2830574dd8d39edcc77b0c83d6f1f4dba712df0735540843bf3e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/649eb3678ed85660bf353919eded31a97a2b7406de31e48757be4d8fd91d939649f8df8f8fbca78de6342fb2280eebe5e26b1e6f396eedd542e5e175a98ccbeb"} 11:15:36 INFO - Running post-run listener: copy_logs_to_upload_dir 11:15:36 INFO - Copying logs to upload dir... 11:15:36 INFO - mkdir: /builds/slave/test/build/upload/logs 11:15:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.535575 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-30 11:15:36.465361) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 11:15:36.469484) ========= 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.9IspgMenBs/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.G2C0WOJdrK/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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/16a47df018a25a973b95ad131eb39d1e338206679d15dc4fcaaaef2a0d6da26870ee8ca106cb898dff1296cd039928c2f4cf5d2b41d200bd10c9142f26499f51", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78ad7702ea68a7fe3cacb160d5673a29aebb591e408bca7d2e42c1d58f0d7c7df7921cba157b3470d9c115caff8efa1c90864fb1413d7fa7448b1447f96625b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ae2c13dd559cb06473a2698e54674c00a24d4068732a535fa5812e86ab8d047475759dcffb2830574dd8d39edcc77b0c83d6f1f4dba712df0735540843bf3e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/649eb3678ed85660bf353919eded31a97a2b7406de31e48757be4d8fd91d939649f8df8f8fbca78de6342fb2280eebe5e26b1e6f396eedd542e5e175a98ccbeb"} build_url:https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011275 build_url: 'https://queue.taskcluster.net/v1/task/YyPvXL3aSWGvy3TjTWz9sw/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/16a47df018a25a973b95ad131eb39d1e338206679d15dc4fcaaaef2a0d6da26870ee8ca106cb898dff1296cd039928c2f4cf5d2b41d200bd10c9142f26499f51", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78ad7702ea68a7fe3cacb160d5673a29aebb591e408bca7d2e42c1d58f0d7c7df7921cba157b3470d9c115caff8efa1c90864fb1413d7fa7448b1447f96625b1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ae2c13dd559cb06473a2698e54674c00a24d4068732a535fa5812e86ab8d047475759dcffb2830574dd8d39edcc77b0c83d6f1f4dba712df0735540843bf3e8", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/649eb3678ed85660bf353919eded31a97a2b7406de31e48757be4d8fd91d939649f8df8f8fbca78de6342fb2280eebe5e26b1e6f396eedd542e5e175a98ccbeb"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 11:15:36.506589) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:15:36.507651) ========= 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.9IspgMenBs/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.G2C0WOJdrK/Listeners TMPDIR=/var/folders/6f/7g8vgtdj6xxdrmc14l087qw800000w/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.005144 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:15:36.558138) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 11:15:36.558499) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 11:15:36.558846) ========= ========= Total master_lag: 0.18 =========