builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0297 starttime: 1496430943.71 results: success (0) buildid: 20170602110524 builduid: de1a62774bea409b9143098cda8d34a0 revision: d47466614ea1ea1a4d9c64ff943a0a3670291529 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 12:15:43.709815) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 12:15:43.710222) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 12:15:43.710495) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 12:15:43.735468) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 12:15:43.735790) ========= 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.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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-06-02 12:15:43-- 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% 194M=0s 2017-06-02 12:15:43 (194 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.173195 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 12:15:43.932270) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 12:15:43.932584) ========= 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.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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.041536 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 12:15:43.994833) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-06-02 12:15:43.995191) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d47466614ea1ea1a4d9c64ff943a0a3670291529 --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 d47466614ea1ea1a4d9c64ff943a0a3670291529 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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-06-02 12:15:44,093 truncating revision to first 12 chars 2017-06-02 12:15:44,094 Setting DEBUG logging. 2017-06-02 12:15:44,094 attempt 1/10 2017-06-02 12:15:44,094 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d47466614ea1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-02 12:15:45,622 unpacking tar archive at: mozilla-beta-d47466614ea1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.812361 ========= master_lag: 2.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-06-02 12:15:47.850945) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 12:15:47.851317) ========= script_repo_revision: d47466614ea1ea1a4d9c64ff943a0a3670291529 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 12:15:47.851728) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 12:15:47.852008) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 12:15:47.875566) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-06-02 12:15:47.875943) ========= /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.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:15:48 INFO - MultiFileLogger online at 20170602 12:15:48 in /builds/slave/test 12:15:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 12:15:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:15:48 INFO - {'append_to_log': False, 12:15:48 INFO - 'base_work_dir': '/builds/slave/test', 12:15:48 INFO - 'blob_upload_branch': 'Mozilla-Beta', 12:15:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:15:48 INFO - 'branch': 'Mozilla-Beta', 12:15:48 INFO - 'buildbot_json_path': 'buildprops.json', 12:15:48 INFO - 'config_files': ('talos/mac_config.py',), 12:15:48 INFO - 'default_actions': ('clobber', 12:15:48 INFO - 'read-buildbot-config', 12:15:48 INFO - 'download-and-extract', 12:15:48 INFO - 'populate-webroot', 12:15:48 INFO - 'create-virtualenv', 12:15:48 INFO - 'install', 12:15:48 INFO - 'run-tests'), 12:15:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:15:48 INFO - 'download_minidump_stackwalk': True, 12:15:48 INFO - 'download_symbols': 'ondemand', 12:15:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:15:48 INFO - 'tooltool.py': '/tools/tooltool.py', 12:15:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:15:48 INFO - '/tools/misc-python/virtualenv.py')}, 12:15:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:15:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:15:48 INFO - 'gecko_profile': False, 12:15:48 INFO - 'gecko_profile_interval': 0, 12:15:48 INFO - 'installer_path': 'installer.exe', 12:15:48 INFO - 'log_level': 'info', 12:15:48 INFO - 'log_name': 'talos', 12:15:48 INFO - 'log_to_console': True, 12:15:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:15:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:15:48 INFO - 'opt_config_files': (), 12:15:48 INFO - 'pip_index': False, 12:15:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:15:48 INFO - 'cmd': ('bash', 12:15:48 INFO - '-c', 12:15:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:15:48 INFO - 'enabled': True, 12:15:48 INFO - 'halt_on_failure': False, 12:15:48 INFO - 'name': 'check_screen_resolution'},), 12:15:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:15:48 INFO - 'cmd': ('bash', 12:15:48 INFO - '-c', 12:15:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:15:48 INFO - 'enabled': True, 12:15:48 INFO - 'halt_on_failure': False, 12:15:48 INFO - 'name': 'check_screen_resolution'},), 12:15:48 INFO - 'run_cmd_checks_enabled': True, 12:15:48 INFO - 'suite': 'dromaeojs-e10s', 12:15:48 INFO - 'system_bits': '32', 12:15:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:15:48 INFO - 'title': 't-yosemite-r7-0297', 12:15:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:15:48 INFO - 'use_talos_json': True, 12:15:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:15:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:15:48 INFO - 'work_dir': 'build'} 12:15:48 INFO - [mozharness: 2017-06-02 19:15:48.084296Z] Running clobber step. 12:15:48 INFO - Running pre-action listener: _resource_record_pre_action 12:15:48 INFO - Running main action method: clobber 12:15:48 INFO - rmtree: /builds/slave/test/build 12:15:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:15:50 INFO - Running post-action listener: _resource_record_post_action 12:15:50 INFO - [mozharness: 2017-06-02 19:15:50.542436Z] Finished clobber step (success) 12:15:50 INFO - [mozharness: 2017-06-02 19:15:50.542603Z] Running read-buildbot-config step. 12:15:50 INFO - Running pre-action listener: _resource_record_pre_action 12:15:50 INFO - Running main action method: read_buildbot_config 12:15:50 INFO - Using buildbot properties: 12:15:50 INFO - { 12:15:50 INFO - "project": "", 12:15:50 INFO - "product": "firefox", 12:15:50 INFO - "script_repo_revision": "production", 12:15:50 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 12:15:50 INFO - "repository": "", 12:15:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 12:15:50 INFO - "buildid": "20170602110524", 12:15:50 INFO - "pgo_build": "False", 12:15:50 INFO - "basedir": "/builds/slave/test", 12:15:50 INFO - "buildnumber": 11, 12:15:50 INFO - "slavename": "t-yosemite-r7-0297", 12:15:50 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 12:15:50 INFO - "platform": "yosemite_r7_devedition", 12:15:50 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 12:15:50 INFO - "branch": "mozilla-beta", 12:15:50 INFO - "repo_path": "releases/mozilla-beta", 12:15:50 INFO - "revision": "d47466614ea1ea1a4d9c64ff943a0a3670291529", 12:15:50 INFO - "stage_platform": "macosx64-devedition", 12:15:50 INFO - "builduid": "de1a62774bea409b9143098cda8d34a0", 12:15:50 INFO - "slavebuilddir": "test" 12:15:50 INFO - } 12:15:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 12:15:50 INFO - Running post-action listener: _resource_record_post_action 12:15:50 INFO - [mozharness: 2017-06-02 19:15:50.545938Z] Finished read-buildbot-config step (success) 12:15:50 INFO - [mozharness: 2017-06-02 19:15:50.546054Z] Running download-and-extract step. 12:15:50 INFO - Running pre-action listener: _resource_record_pre_action 12:15:50 INFO - Running main action method: download_and_extract 12:15:50 INFO - mkdir: /builds/slave/test/build/tests 12:15:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:15:50 INFO - trying https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 12:15:50 INFO - Downloading https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:15:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 12:15:51 INFO - Downloaded 1287 bytes. 12:15:51 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 12:15:51 INFO - Using the following test package requirements: 12:15:51 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:15:51 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 12:15:51 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:15:51 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:15:51 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 12:15:51 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:15:51 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 12:15:51 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 12:15:51 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:15:51 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 12:15:51 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 12:15:51 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:15:51 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 12:15:51 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:15:51 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 12:15:51 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:15:51 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 12:15:51 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 12:15:51 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 12:15:51 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 12:15:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:15:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:15:51 INFO - Fetch https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:15:53 INFO - Expected file size: 51968170 12:15:53 INFO - Obtained file size: 51968170 12:16:09 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 12:16:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 12:16:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 12:16:09 INFO - Fetch https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 12:16:11 INFO - Expected file size: 51968170 12:16:11 INFO - Obtained file size: 51968170 12:16:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 12:16:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 12:16:25 INFO - Fetch https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 12:16:26 INFO - Expected file size: 14404461 12:16:26 INFO - Obtained file size: 14404461 12:16: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')]} 12:16:27 INFO - trying https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:16:27 INFO - Downloading https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 12:16:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 12:16:29 INFO - Downloaded 74894766 bytes. 12:16:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:16:29 INFO - mkdir: /builds/slave/test/properties 12:16:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:16:29 INFO - Writing to file /builds/slave/test/properties/build_url 12:16:29 INFO - Contents: 12:16:29 INFO - build_url:https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 12:16:29 INFO - Running post-action listener: _resource_record_post_action 12:16:29 INFO - Running post-action listener: set_extra_try_arguments 12:16:29 INFO - [mozharness: 2017-06-02 19:16:29.778003Z] Finished download-and-extract step (success) 12:16:29 INFO - [mozharness: 2017-06-02 19:16:29.778146Z] Running populate-webroot step. 12:16:29 INFO - Running pre-action listener: _resource_record_pre_action 12:16:29 INFO - Running main action method: populate_webroot 12:16:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:16:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:16:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:16:29 INFO - '--tppagecycles', 12:16:29 INFO - '7'], 12:16:29 INFO - 'tests': ['tsvgm']}}, 12:16:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:16:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:16:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:16:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:16:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:16:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:16:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:16:29 INFO - 'talos_options': ['--disable-e10s'], 12:16:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:16:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:16:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:16:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:16:29 INFO - 'talos_options': ['--disable-e10s'], 12:16:29 INFO - 'tests': ['damp', 'tps']}, 12:16:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:16:29 INFO - 'tests': ['damp', 'tps']}, 12:16:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:16:29 INFO - 'tests': ['dromaeo_dom']}, 12:16:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:16:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:16:29 INFO - 'tests': ['basic_compositor_video']}, 12:16:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:16:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:16:29 INFO - 'tests': ['a11yr', 12:16:29 INFO - 'ts_paint', 12:16:29 INFO - 'tpaint', 12:16:29 INFO - 'sessionrestore', 12:16:29 INFO - 'sessionrestore_no_auto_restore', 12:16:29 INFO - 'tabpaint']}, 12:16:29 INFO - 'other-e10s': {'tests': ['a11yr', 12:16:29 INFO - 'ts_paint', 12:16:29 INFO - 'tpaint', 12:16:29 INFO - 'sessionrestore', 12:16:29 INFO - 'sessionrestore_no_auto_restore', 12:16:29 INFO - 'tabpaint']}, 12:16:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:16:29 INFO - 'ts_paint', 12:16:29 INFO - 'tpaint', 12:16:29 INFO - 'sessionrestore', 12:16:29 INFO - 'sessionrestore_no_auto_restore', 12:16:29 INFO - 'tabpaint']}, 12:16:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:16:29 INFO - 'ts_paint', 12:16:29 INFO - 'tpaint', 12:16:29 INFO - 'sessionrestore', 12:16:29 INFO - 'sessionrestore_no_auto_restore', 12:16:29 INFO - 'tabpaint']}, 12:16:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:16:29 INFO - 'tests': ['a11yr', 12:16:29 INFO - 'ts_paint', 12:16:29 INFO - 'tpaint', 12:16:29 INFO - 'sessionrestore', 12:16:29 INFO - 'sessionrestore_no_auto_restore', 12:16:29 INFO - 'tabpaint']}, 12:16:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:16:29 INFO - 'tests': ['a11yr', 12:16:29 INFO - 'ts_paint', 12:16:29 INFO - 'tpaint', 12:16:29 INFO - 'sessionrestore', 12:16:29 INFO - 'sessionrestore_no_auto_restore', 12:16:29 INFO - 'tabpaint']}, 12:16:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:16:29 INFO - 'tests': ['tsvgx', 12:16:29 INFO - 'tsvgr_opacity', 12:16:29 INFO - 'tart', 12:16:29 INFO - 'tscrollx', 12:16:29 INFO - 'cart', 12:16:29 INFO - 'tsvg_static']}, 12:16:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:16:29 INFO - 'tsvgr_opacity', 12:16:29 INFO - 'tart', 12:16:29 INFO - 'tscrollx', 12:16:29 INFO - 'cart', 12:16:29 INFO - 'tsvg_static']}, 12:16:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:16:29 INFO - 'talos_options': ['--disable-e10s'], 12:16:29 INFO - 'tests': ['tp5o']}, 12:16:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:16:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:16:29 INFO - 'talos_options': ['--disable-e10s', 12:16:29 INFO - '--xperf_path', 12:16:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:16:29 INFO - 'tests': ['tp5n']}, 12:16:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:16:29 INFO - 'talos_options': ['--xperf_path', 12:16:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:16:29 INFO - 'tests': ['tp5n']}}, 12:16:29 INFO - 'talos.zip': {'path': '', 12:16:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:16:29 INFO - Running post-action listener: _resource_record_post_action 12:16:29 INFO - [mozharness: 2017-06-02 19:16:29.797155Z] Finished populate-webroot step (success) 12:16:29 INFO - [mozharness: 2017-06-02 19:16:29.797288Z] Running create-virtualenv step. 12:16:29 INFO - Running pre-action listener: _resource_record_pre_action 12:16:29 INFO - Running main action method: create_virtualenv 12:16:29 INFO - Creating virtualenv /builds/slave/test/build/venv 12:16:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:16:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:16:29 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 12:16:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:16:29 INFO - Using real prefix '/tools/python27' 12:16:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:16:30 INFO - Installing distribute.............................................................................................................................................................................................done. 12:16:33 INFO - Installing pip.................done. 12:16:33 INFO - Return code: 0 12:16:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:16:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:16:33 INFO - Reading from file tmpfile_stdout 12:16:33 INFO - Output received: 12:16:33 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:16:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:33 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 0x10bcd9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcdc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5a9c7c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bab7d30>, '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 0x10b9dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10baacbe8>, '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.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:16:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:16:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:16:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7qZ4R6GBrk/Render', 12:16:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:33 INFO - 'HOME': '/Users/cltbld', 12:16:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:33 INFO - 'LOGNAME': 'cltbld', 12:16:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:33 INFO - 'MOZ_NO_REMOTE': '1', 12:16:33 INFO - 'NO_EM_RESTART': '1', 12:16:33 INFO - 'PAGER': '/bin/cat', 12:16:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:33 INFO - 'PWD': '/builds/slave/test', 12:16:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:33 INFO - 'SHELL': '/bin/bash', 12:16:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzZVd2Rka/Listeners', 12:16:33 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 12:16:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:33 INFO - 'USER': 'cltbld', 12:16:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:33 INFO - 'XPC_FLAGS': '0x0', 12:16:33 INFO - 'XPC_SERVICE_NAME': '0', 12:16:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:34 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:16:34 INFO - Cleaning up... 12:16:34 INFO - Return code: 0 12:16:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:34 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 0x10bcd9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcdc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5a9c7c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bab7d30>, '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 0x10b9dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10baacbe8>, '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.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:16:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:16:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:16:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7qZ4R6GBrk/Render', 12:16:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:34 INFO - 'HOME': '/Users/cltbld', 12:16:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:34 INFO - 'LOGNAME': 'cltbld', 12:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:34 INFO - 'MOZ_NO_REMOTE': '1', 12:16:34 INFO - 'NO_EM_RESTART': '1', 12:16:34 INFO - 'PAGER': '/bin/cat', 12:16:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:34 INFO - 'PWD': '/builds/slave/test', 12:16:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:34 INFO - 'SHELL': '/bin/bash', 12:16:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzZVd2Rka/Listeners', 12:16:34 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 12:16:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:34 INFO - 'USER': 'cltbld', 12:16:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:34 INFO - 'XPC_FLAGS': '0x0', 12:16:34 INFO - 'XPC_SERVICE_NAME': '0', 12:16:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:34 INFO - Downloading/unpacking psutil>=3.1.1 12:16: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 12:16: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 12:16: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 12:16: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 12:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:16:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:16:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:16:36 INFO - Installing collected packages: psutil 12:16:36 INFO - Running setup.py install for psutil 12:16:36 INFO - building 'psutil._psutil_osx' extension 12:16:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:16:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:16:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:16:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:16:37 INFO - building 'psutil._psutil_posix' extension 12:16:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:16:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:16:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:16:37 INFO - ^ 12:16:37 INFO - 1 warning generated. 12:16:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:16:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:16:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:16:37 INFO - Successfully installed psutil 12:16:37 INFO - Cleaning up... 12:16:37 INFO - Return code: 0 12:16:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:37 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 0x10bcd9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcdc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5a9c7c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bab7d30>, '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 0x10b9dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10baacbe8>, '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.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:16:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:16:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 12:16:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7qZ4R6GBrk/Render', 12:16:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:37 INFO - 'HOME': '/Users/cltbld', 12:16:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:37 INFO - 'LOGNAME': 'cltbld', 12:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:37 INFO - 'MOZ_NO_REMOTE': '1', 12:16:37 INFO - 'NO_EM_RESTART': '1', 12:16:37 INFO - 'PAGER': '/bin/cat', 12:16:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:37 INFO - 'PWD': '/builds/slave/test', 12:16:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:37 INFO - 'SHELL': '/bin/bash', 12:16:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzZVd2Rka/Listeners', 12:16:37 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 12:16:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:37 INFO - 'USER': 'cltbld', 12:16:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:37 INFO - 'XPC_FLAGS': '0x0', 12:16:37 INFO - 'XPC_SERVICE_NAME': '0', 12:16:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:16:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:16:39 INFO - Installing collected packages: mozsystemmonitor 12:16:39 INFO - Running setup.py install for mozsystemmonitor 12:16:39 INFO - Successfully installed mozsystemmonitor 12:16:39 INFO - Cleaning up... 12:16:39 INFO - Return code: 0 12:16:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:39 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 0x10bcd9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcdc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5a9c7c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bab7d30>, '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 0x10b9dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10baacbe8>, '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.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:16:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 12:16:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7qZ4R6GBrk/Render', 12:16:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:39 INFO - 'HOME': '/Users/cltbld', 12:16:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:39 INFO - 'LOGNAME': 'cltbld', 12:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:39 INFO - 'MOZ_NO_REMOTE': '1', 12:16:39 INFO - 'NO_EM_RESTART': '1', 12:16:39 INFO - 'PAGER': '/bin/cat', 12:16:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:39 INFO - 'PWD': '/builds/slave/test', 12:16:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:39 INFO - 'SHELL': '/bin/bash', 12:16:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzZVd2Rka/Listeners', 12:16:39 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 12:16:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:39 INFO - 'USER': 'cltbld', 12:16:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:39 INFO - 'XPC_FLAGS': '0x0', 12:16:39 INFO - 'XPC_SERVICE_NAME': '0', 12:16:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:39 INFO - Downloading/unpacking jsonschema==2.5.1 12:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:16:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:41 INFO - Downloading functools32-3.2.3-2.tar.gz 12:16:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:16:41 INFO - warning: no files found matching '*.txt' 12:16:41 INFO - no previously-included directories found matching 'build' 12:16:41 INFO - no previously-included directories found matching 'dist' 12:16:41 INFO - no previously-included directories found matching '.git*' 12:16:41 INFO - Installing collected packages: jsonschema, functools32 12:16:41 INFO - Running setup.py install for functools32 12:16:41 INFO - warning: no files found matching '*.txt' 12:16:41 INFO - no previously-included directories found matching 'build' 12:16:41 INFO - no previously-included directories found matching 'dist' 12:16:41 INFO - no previously-included directories found matching '.git*' 12:16:41 INFO - Successfully installed jsonschema functools32 12:16:41 INFO - Cleaning up... 12:16:41 INFO - Return code: 0 12:16:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:42 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 0x10bcd9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcdc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5a9c7c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bab7d30>, '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 0x10b9dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10baacbe8>, '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.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:16:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:16:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7qZ4R6GBrk/Render', 12:16:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:42 INFO - 'HOME': '/Users/cltbld', 12:16:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:42 INFO - 'LOGNAME': 'cltbld', 12:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:42 INFO - 'MOZ_NO_REMOTE': '1', 12:16:42 INFO - 'NO_EM_RESTART': '1', 12:16:42 INFO - 'PAGER': '/bin/cat', 12:16:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:42 INFO - 'PWD': '/builds/slave/test', 12:16:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:42 INFO - 'SHELL': '/bin/bash', 12:16:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzZVd2Rka/Listeners', 12:16:42 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 12:16:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:42 INFO - 'USER': 'cltbld', 12:16:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:42 INFO - 'XPC_FLAGS': '0x0', 12:16:42 INFO - 'XPC_SERVICE_NAME': '0', 12:16:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:16:42 INFO - Cleaning up... 12:16:42 INFO - Return code: 0 12:16:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:42 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 0x10bcd9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcdc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5a9c7c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bab7d30>, '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 0x10b9dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10baacbe8>, '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.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:16:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:16:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:16:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7qZ4R6GBrk/Render', 12:16:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:42 INFO - 'HOME': '/Users/cltbld', 12:16:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:42 INFO - 'LOGNAME': 'cltbld', 12:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:42 INFO - 'MOZ_NO_REMOTE': '1', 12:16:42 INFO - 'NO_EM_RESTART': '1', 12:16:42 INFO - 'PAGER': '/bin/cat', 12:16:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:42 INFO - 'PWD': '/builds/slave/test', 12:16:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:42 INFO - 'SHELL': '/bin/bash', 12:16:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzZVd2Rka/Listeners', 12:16:42 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 12:16:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:42 INFO - 'USER': 'cltbld', 12:16:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:42 INFO - 'XPC_FLAGS': '0x0', 12:16:42 INFO - 'XPC_SERVICE_NAME': '0', 12:16:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:43 INFO - Downloading/unpacking blobuploader==1.2.4 12:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:44 INFO - Downloading blobuploader-1.2.4.tar.gz 12:16:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:16:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:16:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:45 INFO - Downloading docopt-0.6.1.tar.gz 12:16:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:16:45 INFO - Installing collected packages: blobuploader, requests, docopt 12:16:45 INFO - Running setup.py install for blobuploader 12:16:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:16:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:16:45 INFO - Running setup.py install for requests 12:16:46 INFO - Running setup.py install for docopt 12:16:46 INFO - Successfully installed blobuploader requests docopt 12:16:46 INFO - Cleaning up... 12:16:46 INFO - Return code: 0 12:16:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:46 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 0x10bcd9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcdc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5a9c7c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bab7d30>, '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 0x10b9dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10baacbe8>, '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.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:16:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:16:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:16:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7qZ4R6GBrk/Render', 12:16:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:46 INFO - 'HOME': '/Users/cltbld', 12:16:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:46 INFO - 'LOGNAME': 'cltbld', 12:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:46 INFO - 'MOZ_NO_REMOTE': '1', 12:16:46 INFO - 'NO_EM_RESTART': '1', 12:16:46 INFO - 'PAGER': '/bin/cat', 12:16:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:46 INFO - 'PWD': '/builds/slave/test', 12:16:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:46 INFO - 'SHELL': '/bin/bash', 12:16:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzZVd2Rka/Listeners', 12:16:46 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 12:16:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:46 INFO - 'USER': 'cltbld', 12:16:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:46 INFO - 'XPC_FLAGS': '0x0', 12:16:46 INFO - 'XPC_SERVICE_NAME': '0', 12:16:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:16:46 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-4wXQ2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:16:47 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-shXu5w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:16:47 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-1AMrtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:16:47 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-RbI0J8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:16:47 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-mQo7xB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:16:47 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-J_CWyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:16:47 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-BHdl76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:16:47 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-6OtukU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:16:47 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-K8FyZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:16:47 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-YBg0AB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:16:48 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-xNU3m7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:16:48 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-IS2cw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:16:48 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-4jgtfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:16:48 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-Js4E9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:48 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-dakqen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:16:48 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-0fkjU5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:16:48 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-3L8RK4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:16:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:16:48 INFO - Running setup.py install for manifestparser 12:16:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:16:48 INFO - Running setup.py install for mozcrash 12:16:49 INFO - Running setup.py install for mozdebug 12:16:49 INFO - Running setup.py install for mozdevice 12:16:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:16:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:16:49 INFO - Running setup.py install for mozfile 12:16:49 INFO - Running setup.py install for mozhttpd 12:16:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:16:49 INFO - Running setup.py install for mozinfo 12:16:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:16:49 INFO - Running setup.py install for mozInstall 12:16:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:16:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:16:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:16:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:16:49 INFO - Running setup.py install for mozleak 12:16:50 INFO - Running setup.py install for mozlog 12:16:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:16:50 INFO - Running setup.py install for moznetwork 12:16:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:16:50 INFO - Running setup.py install for mozprocess 12:16:50 INFO - Running setup.py install for mozprofile 12:16:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:16:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:16:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:16:50 INFO - Running setup.py install for mozrunner 12:16:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:16:50 INFO - Running setup.py install for mozscreenshot 12:16:51 INFO - Running setup.py install for moztest 12:16:51 INFO - Running setup.py install for mozversion 12:16:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:16:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:16:51 INFO - Cleaning up... 12:16:51 INFO - Return code: 0 12:16:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:51 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 0x10bcd9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcdc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5a9c7c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bab7d30>, '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 0x10b9dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10baacbe8>, '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.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:16:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:16:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:16:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7qZ4R6GBrk/Render', 12:16:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:51 INFO - 'HOME': '/Users/cltbld', 12:16:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:51 INFO - 'LOGNAME': 'cltbld', 12:16:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:51 INFO - 'MOZ_NO_REMOTE': '1', 12:16:51 INFO - 'NO_EM_RESTART': '1', 12:16:51 INFO - 'PAGER': '/bin/cat', 12:16:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:51 INFO - 'PWD': '/builds/slave/test', 12:16:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:51 INFO - 'SHELL': '/bin/bash', 12:16:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzZVd2Rka/Listeners', 12:16:51 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 12:16:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:51 INFO - 'USER': 'cltbld', 12:16:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:51 INFO - 'XPC_FLAGS': '0x0', 12:16:51 INFO - 'XPC_SERVICE_NAME': '0', 12:16:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:16:51 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-m99vgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:16:51 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:16:51 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-TsLxk1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:16:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:16:51 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-fuVqcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:16:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:16:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:16:51 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-i9hPzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:16:52 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-51yty2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:16:52 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-ntrCLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:16:52 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-j4pHIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:16:52 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-b6alIb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:16:52 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-wUBCef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:16:52 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-e4QluM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:16:52 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-6DUNPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:16:52 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-wJ73qN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:16:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:16:52 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-B8j_FD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:16:53 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-rNH8mY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:53 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-AaT0jr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:16:53 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-X_rKN4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:16:53 INFO - Running setup.py (path:/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/pip-UNkOmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:16:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:54 INFO - Downloading blessings-1.6.tar.gz 12:16:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:16:54 INFO - Installing collected packages: blessings 12:16:54 INFO - Running setup.py install for blessings 12:16:55 INFO - Successfully installed blessings 12:16:55 INFO - Cleaning up... 12:16:55 INFO - Return code: 0 12:16:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:16:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:16:55 INFO - Reading from file tmpfile_stdout 12:16:55 INFO - Current package versions: 12:16:55 INFO - blessings == 1.6 12:16:55 INFO - blobuploader == 1.2.4 12:16:55 INFO - docopt == 0.6.1 12:16:55 INFO - functools32 == 3.2.3-2 12:16:55 INFO - jsonschema == 2.5.1 12:16:55 INFO - manifestparser == 1.1 12:16:55 INFO - mozInstall == 1.12 12:16:55 INFO - mozcrash == 1.0 12:16:55 INFO - mozdebug == 0.1 12:16:55 INFO - mozdevice == 0.49 12:16:55 INFO - mozfile == 1.2 12:16:55 INFO - mozhttpd == 0.7 12:16:55 INFO - mozinfo == 0.9 12:16:55 INFO - mozleak == 0.1 12:16:55 INFO - mozlog == 3.4 12:16:55 INFO - moznetwork == 0.27 12:16:55 INFO - mozprocess == 0.25 12:16:55 INFO - mozprofile == 0.28 12:16:55 INFO - mozrunner == 6.13 12:16:55 INFO - mozscreenshot == 0.1 12:16:55 INFO - mozsystemmonitor == 0.3 12:16:55 INFO - moztest == 0.8 12:16:55 INFO - mozversion == 1.4 12:16:55 INFO - psutil == 3.1.1 12:16:55 INFO - requests == 1.2.3 12:16:55 INFO - wsgiref == 0.1.2 12:16:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:55 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 0x10bcd9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcdc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5a9c7c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bab7d30>, '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 0x10b9dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10baacbe8>, '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.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:16:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:16:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7qZ4R6GBrk/Render', 12:16:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:55 INFO - 'HOME': '/Users/cltbld', 12:16:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:55 INFO - 'LOGNAME': 'cltbld', 12:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:55 INFO - 'MOZ_NO_REMOTE': '1', 12:16:55 INFO - 'NO_EM_RESTART': '1', 12:16:55 INFO - 'PAGER': '/bin/cat', 12:16:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:55 INFO - 'PWD': '/builds/slave/test', 12:16:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:55 INFO - 'SHELL': '/bin/bash', 12:16:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzZVd2Rka/Listeners', 12:16:55 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 12:16:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:55 INFO - 'USER': 'cltbld', 12:16:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:55 INFO - 'XPC_FLAGS': '0x0', 12:16:55 INFO - 'XPC_SERVICE_NAME': '0', 12:16:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:16:55 INFO - Cleaning up... 12:16:55 INFO - Return code: 0 12:16:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bcd9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bcdc768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5a9c7c100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bab7d30>, '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 0x10b9dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10baacbe8>, '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.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:16:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:16:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7qZ4R6GBrk/Render', 12:16:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:55 INFO - 'HOME': '/Users/cltbld', 12:16:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:55 INFO - 'LOGNAME': 'cltbld', 12:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:55 INFO - 'MOZ_NO_REMOTE': '1', 12:16:55 INFO - 'NO_EM_RESTART': '1', 12:16:55 INFO - 'PAGER': '/bin/cat', 12:16:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:55 INFO - 'PWD': '/builds/slave/test', 12:16:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:55 INFO - 'SHELL': '/bin/bash', 12:16:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzZVd2Rka/Listeners', 12:16:55 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 12:16:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:55 INFO - 'USER': 'cltbld', 12:16:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:55 INFO - 'XPC_FLAGS': '0x0', 12:16:55 INFO - 'XPC_SERVICE_NAME': '0', 12:16:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:16:55 INFO - Cleaning up... 12:16:55 INFO - Return code: 0 12:16:55 INFO - Running post-action listener: _resource_record_post_action 12:16:55 INFO - Running post-action listener: _start_resource_monitoring 12:16:56 INFO - Starting resource monitoring. 12:16:56 INFO - [mozharness: 2017-06-02 19:16:56.009724Z] Finished create-virtualenv step (success) 12:16:56 INFO - [mozharness: 2017-06-02 19:16:56.010706Z] Running install step. 12:16:56 INFO - Running pre-action listener: _resource_record_pre_action 12:16:56 INFO - Running main action method: install 12:16:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:16:56 INFO - Reading from file tmpfile_stdout 12:16:56 INFO - Detecting whether we're running mozinstall >=1.0... 12:16:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:16:56 INFO - Reading from file tmpfile_stdout 12:16:56 INFO - Output received: 12:16:56 INFO - Usage: mozinstall [options] installer 12:16:56 INFO - Options: 12:16:56 INFO - -h, --help show this help message and exit 12:16:56 INFO - -d DEST, --destination=DEST 12:16:56 INFO - Directory to install application into. [default: 12:16:56 INFO - "/builds/slave/test"] 12:16:56 INFO - --app=APP Application being installed. [default: firefox] 12:16:56 INFO - mkdir: /builds/slave/test/build/application 12:16:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:16:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 12:17:16 INFO - Reading from file tmpfile_stdout 12:17:16 INFO - Output received: 12:17:16 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:17:16 INFO - Running post-action listener: _resource_record_post_action 12:17:16 INFO - [mozharness: 2017-06-02 19:17:16.587537Z] Finished install step (success) 12:17:16 INFO - [mozharness: 2017-06-02 19:17:16.587725Z] Running run-tests step. 12:17:16 INFO - Running pre-action listener: _resource_record_pre_action 12:17:16 INFO - Running main action method: run_tests 12:17:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:17:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:17:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:17:16 INFO - 2017-06-02 12:17:16.602 screenresolution[1626:7598] starting screenresolution argv=screenresolution get 12:17:16 INFO - 2017-06-02 12:17:16.605 screenresolution[1626:7598] Display 0: 1600x1200x32@60 12:17:16 INFO - 2017-06-02 12:17:16.611 screenresolution[1627:7602] starting screenresolution argv=screenresolution list 12:17:16 INFO - Available Modes on Display 0 12:17:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:17:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:17:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:17:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:17:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:17:16 INFO - Intel Iris: 12:17:16 INFO - Chipset Model: Intel Iris 12:17:16 INFO - Type: GPU 12:17:16 INFO - Bus: Built-In 12:17:16 INFO - VRAM (Dynamic, Max): 1536 MB 12:17:16 INFO - Vendor: Intel (0x8086) 12:17:16 INFO - Device ID: 0x0a2e 12:17:16 INFO - Revision ID: 0x0009 12:17:16 INFO - Displays: 12:17:16 INFO - Display: 12:17:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:17:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:17:16 INFO - Display Serial Number: 1600x1200 60 12:17:16 INFO - Main Display: Yes 12:17:16 INFO - Mirror: Off 12:17:16 INFO - Online: Yes 12:17:16 INFO - Rotation: Supported 12:17:16 INFO - Return code: 0 12:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:17:16 INFO - Python 2.7.3 12:17:16 INFO - Return code: 0 12:17:16 INFO - grabbing minidump binary from tooltool 12:17:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:16 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 0x10bab7d30>, '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 0x10b9dedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10baacbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:17:16 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:17:16 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:17:16 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:17:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:17:16 INFO - Return code: 0 12:17:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:17:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:17:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:17:16 INFO - ENV: RUST_BACKTRACE is now 1 12:17:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:17:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:17:16 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0297', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 12:17:16 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0297 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 12:17:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7qZ4R6GBrk/Render', 12:17:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:16 INFO - 'HOME': '/Users/cltbld', 12:17:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:16 INFO - 'LOGNAME': 'cltbld', 12:17:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:17:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:16 INFO - 'MOZ_NO_REMOTE': '1', 12:17:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:17:16 INFO - 'NO_EM_RESTART': '1', 12:17:16 INFO - 'PAGER': '/bin/cat', 12:17:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:16 INFO - 'PWD': '/builds/slave/test', 12:17:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:17:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:16 INFO - 'RUST_BACKTRACE': '1', 12:17:16 INFO - 'SHELL': '/bin/bash', 12:17:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wEzZVd2Rka/Listeners', 12:17:16 INFO - 'TMPDIR': '/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/', 12:17:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:16 INFO - 'USER': 'cltbld', 12:17:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:16 INFO - 'XPC_FLAGS': '0x0', 12:17:16 INFO - 'XPC_SERVICE_NAME': '0', 12:17:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:16 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/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0297', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 12:17:17 INFO - mozversion application_buildid: 20170602110524 12:17:17 INFO - mozversion application_changeset: d47466614ea1ea1a4d9c64ff943a0a3670291529 12:17:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:17:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:17:17 INFO - mozversion application_name: Firefox 12:17:17 INFO - mozversion application_remotingname: firefox-dev 12:17:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 12:17:17 INFO - mozversion application_vendor: Mozilla 12:17:17 INFO - mozversion application_version: 54.0 12:17:17 INFO - mozversion platform_buildid: 20170602110524 12:17:17 INFO - mozversion platform_changeset: d47466614ea1ea1a4d9c64ff943a0a3670291529 12:17:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 12:17:17 INFO - mozversion platform_version: 54.0 12:17:17 INFO - using testdate: 1496431037 12:17:17 INFO - actual date: 1496431037 12:17:17 INFO - starting webserver on 'localhost:49227' 12:17:17 INFO - SUITE-START | Running 2 tests 12:17:17 INFO - TEST-START | dromaeo_css 12:17:17 INFO - operating with platform_type : mac_ 12:17:17 INFO - Initialising browser for dromaeo_css test... 12:17:17 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmpSlxxkr/profile http://localhost:49227/getInfo.html) 12:17:20 INFO - PROCESS | 1637 | 2017-06-02 12:17:20.234 plugin-container[1640:7783] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f4b, name = 'com.apple.tsm.portname' 12:17:20 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:20 INFO - PROCESS | 1637 | 2017-06-02 12:17:20.234 plugin-container[1640:7783] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b1b, name = 'com.apple.CFPasteboardClient' 12:17:20 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:20 INFO - PROCESS | 1637 | 2017-06-02 12:17:20.234 plugin-container[1640:7783] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:17:20 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 12:17:20 INFO - PROCESS | 1637 | colorDepth:24 12:17:20 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 12:17:20 INFO - PROCESS | 1637 | __metrics 12:17:20 ERROR - PROCESS | 1637 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 12:17:20 INFO - TEST-INFO | 1637: exit 0 12:17:20 INFO - Browser initialized. 12:17:20 INFO - Running cycle 1/1 for dromaeo_css test... 12:17:20 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmpSlxxkr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:17:23 INFO - PROCESS | 1641 | 2017-06-02 12:17:23.392 plugin-container[1642:7916] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 12:17:23 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:23 INFO - PROCESS | 1641 | 2017-06-02 12:17:23.393 plugin-container[1642:7916] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460b, name = 'com.apple.CFPasteboardClient' 12:17:23 INFO - PROCESS | 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:23 INFO - PROCESS | 1641 | 2017-06-02 12:17:23.393 plugin-container[1642:7916] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:17:33 INFO - PROCESS | 1641 | RSS: Main: 203345920 12:17:33 INFO - PROCESS | 1641 | 12:20:36 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 12:20:38 INFO - PROCESS | 1641 | RSS: Main: 216096768 12:20:38 INFO - PROCESS | 1641 | 12:23:40 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 12:23:41 INFO - PROCESS | 1641 | RSS: Main: 231170048 12:23:41 INFO - PROCESS | 1641 | 12:26:42 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 12:26:43 INFO - PROCESS | 1641 | RSS: Main: 233349120 12:26:43 INFO - PROCESS | 1641 | 12:29:45 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 12:29:46 INFO - PROCESS | 1641 | RSS: Main: 236498944 12:29:46 INFO - PROCESS | 1641 | 12:32:48 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 12:32:49 INFO - PROCESS | 1641 | RSS: Main: 239185920 12:32:49 INFO - PROCESS | 1641 | 12:35:52 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 12:35:52 INFO - PROCESS | 1641 | RSS: Main: 237162496 12:35:52 INFO - PROCESS | 1641 | 12:35:52 INFO - PROCESS | 1641 | __start_tp_report 12:35:52 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 12:35:52 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 12:35:52 INFO - PROCESS | 1641 | |i|pagename|runs| 12:35:52 INFO - PROCESS | 1641 | |0;dojo.html;3414.1123307939943;3471.5834099908016;3481.2863363010583;3508.3860324443217;3540.1680605057813;5879.059350503915;5901.468867801902;5986.521078313735;6048.727807248673;6087.026075827868;5915.940840591593;5931.9915614345555;5968.313643930949;6010.338862725108;6013.188219590354;5377.704226267544;5413.729313534327;5441.809536666217;5476.205950137228;5506.421825708303;4503.909921801562;5470.5350045246005;5472.288602481672;5480.835574932738;5489.204065410513;4454.398656181423;5251.816186433475;5472.863178420532;5505.201745746865;5531.640443371168;4668.323093151491;5405.378381486121;5410.188471729272;5452.2094296327205;5479;5296.708681022542;5435.592330575223;5438.673679579238;5440.401555828856;5461.590380721422;5935.584509084366;6002.909956350658;6079.361667024988;6089.908651370233;6109.297430795471;6178.289496707891;6249;6256.6871656417;6263.404976527268;6265.498760099226;6352.36476352361;6378.681065946684;6379;6403.679816009228;6410.775622853178;6133.539984501181;6154.3845615438095;6156.415140561684;6163.445289923929;6167.198264225622;6211.16149319836;6281.089242059876;6303.432691057827;6478.870422591522;6498.935010649928;5306.363236411656;5568.749406276727;6348.619082855043;6367.936320636734;6373.649449280241;6357.364263573606;6383.776567820104;6385.13800636908;6385.96807015962;6417.7753778617525;5120.668626157151;6270;6271.310155882849;6303.338149494237;6326.2724786649205;6259.092431597484;6259.624422534663;6273.247210334789;6277.591956522811;6279.686015699197;4814.638902082358;4834.685745426536;4895.779689913891;4929.950700493021;4935.605151587864;4390.582894625006;4424.203643344229;4445.644348452116;4445.844395446208;4477.776111194427;5654.434556544313;5695.145728140812;5727.942720572741;5833.154192642128;5883.294004719461;6208.00671892504;6278;6308.558400911984;6314.747410103544;6319.05214217871;5892.204552385459;5893.528517718572;5899.115132730039;5904.9075920954765;5936.7328470218945;5590.888182236333;5592.133219351007;5615.354234263031;5616.241807355955;5625.887482250332;6283.968580157161;6304.338044505261;6439.420452159328;6457.160569125984;6502.772402965874;4921.384826896638;5496.945030549643;5771.68255745938;6376.9043464349;6405;6501.934980650322;6593.406593406617;6595.134769279816;6618.768343107968;6673.833154171185;6217.285012223471;6244.344343843831;6252.374762523711;6268.435840774352;6337.334579869232;6374.93625063762;6391;6400.135981642418;6412.358764123737;6461.418472337325;5265.763040663102;6097.451229389376;6250.468710159687;6270.592411493239;6330.683465826783;5643.7742490299925;5827.096799996008;6181.660008699565;6264.68676566179;6347;6115.149072927182;6195.597286176384;6260.123582698335;6274.717637706223;6303.9684801575695;6274.905876411766;6316.431521163117;6326.8734625306315;6354.555181137276;6354.93645063562;4938.43208031081;5991.430814072656;6046.667433291212;6047.425494578008;6169.50643948474 12:35:52 INFO - PROCESS | 1641 | |1;ext.html;758.5562445969108;782.1396463889722;824.0029564227286;965.2615748952048;973.7127547373524;38248.80875595622;39018.41472377913;40635;40657.3737050518;40704.185916281654;39100.41349379761;39117.608823911825;39194;39311.803440982825;39328.803355983255;8062.71780487683;8228.136045715204;11023.944880275587;11297.435128243596;11512.827307590393;11048.337099774039;11161.330320180774;11168.609098681547;11186.720331991684;11190.216684832065;11196.944015279912;11213;11220.438978051106;11221.438928053605;11227.157963152755;11153.275037122561;11177.83233251502;11184.273022253528;11185.608503702331;11202.495887685074;11034.889651103467;11035.779284414348;11093.278936869077;11093.33439993595;11108;35198.472022919676;35459.93620191398;35484;35503.75736849224;35572.46641300383;3259.6414394416597;3750.1374683822874;3810.79040652764;3840.2319536092614;4003.73975691582;25528.489430211477;25754.09860654868;26142.477150457074;26738.46523069547;27333.316667083283;2580.6645136132183;3038.9392212155853;3063.326068264978;3105.1771280610656;3138.1056398926426;253701;265994;363590;369673;450342.74828625977;43869.78065109718;44347;44437;44455.11089778251;44459;45317.86705332393;45433.77283113563;45532.3170152448;45558.54441455543;45746.771266143456;13780.379882905294;13866.237356945263;13903.513377031957;13942.302884855717;13961;12010.279383237033;12155.574554890536;12178.269303841798;12186.51253949832;12187.634370968884;9836;12110.515579376903;12118.39408029595;12136.696582585506;12205.572804951784;5812.44781745742;5848.181254624356;5849.298084229835;5868.471837534642;5868.794592189253;1081.416035340923;1147.971300717472;1148.586508856811;1153.2792004996877;1190.1371505658328;26812.865935670197;27234.863825681143;27398;27442.17673469799;27448.588271175948;1238.13949305232;1253.078986944595;1265.4368805881295;1302.4529697527062;1314.737052589486;11946.641600751991;14047.929760351337;14298;14308.427662893577;14422.567322980327;392033;393878.0306098451;394172;394384;395087;55731.442685573435;55807.72096139493;56357.43642563522;56370.718146409825;56649.43350566524;1357.6470117769452;1444.4294503671201;1478.1943840606896;1480.326451464581;1488.24843454055;23093.307200784002;23135.652965205198;27543.586846196922;28447.857760711064;29309;38642.0339491515;47666;48057.27914081362;48064.75967620139;48115.75942120407;457144;460868;462146;463319;464881;26589.867050664623;27194.864025679744;32440;33105.84129555454;33309.50035749514;38420;40074.59925400709;40081.1983760329;40568;40697.38953915634;28521.572176417787;28752.712472875002;28853.27866803347;29312.41375172527;29610.259743506584;27028.864855675594;28654.713452865206;29217;29398.85300573569;29489.705102949552;32850.01449956505;33800;33942.49086263659;34518.30963380769;36115.277694446486;28580.85709571439;29071.854640726662;29414.558781617863;30087;30180.54729179108;23124.537509250058;24134.75865241325;24363.473232977627;24485.38786530351;24498.877505613073 12:35:52 INFO - PROCESS | 1641 | |2;jquery.html;385.4565063260803;396.98213580388887;400.99198016039696;418.2200196633279;419.4379531427886;21182.364529064133;22032.44918877029;22753.54492910143;22780.86095695217;22988.19541316054;22019;22066.558668826612;22346;22361.664575031387;22572.54854902901;6071.453569178773;6522.57603255788;6651.966740166293;6703.229128650194;6818.590884546943;6334.619922804646;6703.262641109474;6748;6767.796966091025;6778.152730908637;6478.449331806802;6531.216254049521;6664.700088496029;6796.524243302971;6806.319368063178;6562.639054851981;6582.5063120265695;6611.206655201358;6629.8342541436605;6640.833979150511;6533.836654083639;7181.820454488679;7207.639618019078;7238.022866912952;7253.165885923081;25433.87283063591;25806.741932580622;26308.60537091926;26659.600105998426;26705.73294267052;2513.912013079552;3391.406503861814;3433.073070271013;3450.8447119879665;3463.4458486642015;12884.420201090974;15900.5901835296;16186.919065404716;16357.83642163587;16654.58363540909;1865.664180447519;2100.3488918435332;2127.1066152215953;2137.0810551462864;2229.297771202066;145005;153047.9999999989;153322;154451;157739.21130394388;15946.760798588031;19566.902165489082;20450.693239601416;20580;20604;31219;31747.84126079355;31980.040598782074;32070.87751928718;32564;10196.694099177037;10356.326838755456;10553.314034587727;10576.206784491194;10581.31221470602;8783.5169065702;8784.560771961376;8796.648134074565;8867.201951824367;8908.153725396078;9507.239420846316;9590.90409095914;9671;9671.468069256118;9671.758206044906;3561.7684850484634;3618.945715814265;3905.4378248700746;3973.179042879588;4202.159568086395;1000.4397537379091;1014.7209517382779;1021.5147804792813;1025.2720568396521;1056.9852941176557;15801.288941997638;17750.11249437523;20837;21121.471963200736;21182.89408552978;997.835357166074;997.850322451638;1020.831562792131;1031.9535620897077;1037.7353774787382;8711.823903772909;10576.576936922436;10774.353538787542;10775.245732798629;10836.67489975302;729557;730723;731758.3779892894;757264;767250;111013;160080;160558;161798;163942;1217.1662411248324;1220.1519943639264;1252.6868282929086;1254.667513109027;1256.1269917407315;17829.46511604654;18650;18696.626067478846;18704.812951870306;18783.34258300953;49401;62746;63417;63466.68266658822;63642.68178659077;77311.61344193244;84647;85093;86284.13715863012;86438;8287.12985136576;9722.416655000723;10499.317544359592;11079.667609971713;11805.76388472243;9310.72067837966;12321.507139714311;12478.064145188966;12765.978721702057;12816.487340506275;10447.791044179226;11058.281211721212;11171.776564468502;11376.829347559627;11445.542178313108;10306.33008854422;10382.42896640692;10464.372137671764;10692.465376731288;10708.678739637824;11383.307458422487;11427.371494567546;11496.310221386744;11561.075113991028;11564.47959841826;11269.887301126884;11379.772404551697;11438.771224575628;11607.7364913841;11685.415729213335;10999.560017599206;11042.613508527164;11065.612703555336;11191.72020699489;11412.543498259976 12:35:52 INFO - PROCESS | 1641 | |3;mootools.html;1392.728417958498;1533.3253368517858;1597.6405308805513;1598.3686443854679;1606.5100144455937;10889.074428673563;11158.218924675277;11433.599824006162;11456.942715286423;11468.19722619417;11298.322100673966;11448.942755286213;11482.253653512516;11491.138164637643;11518.424078796048;2430.465297634522;2462.655228268044;2472.5549401107883;2472.9010839566395;2475.8390704604144;2401.387646150229;2414.674019007438;2454.6686197363306;2456.91400800972;2458.090506512593;2380.583397905368;2394.437307232799;2397.580423425902;2450.681411416522;2451.1911069347075;2356.32844639277;2383.2611890314056;2401.3516350585332;2408.4701365699516;2434.6834911461574;2367.4791545859885;2404.038384646138;2404.89177986991;2408.6507456418894;2409.662647229389;11083.556657733681;11260.774784504263;11286.548538058469;11316.773664526663;11403.828942565786;4848.7090774553635;5887.911681324784;6035.939640603582;6064.06007068905;6072.483838873701;3536.328097661462;3653.6529029742132;3684.1342284563107;3697.9075523112137;3726.9440958385644;913.7258822353267;1005.2109094360892;1156.0751398880861;1156.5951916829126;1838.44846546037;13865.792013119812;14185.43258269668;15057.924710376377;15602.531924042298;15757.290921908541;3893.5717071122153;4847.830325938575;4852.757362131879;4871.853844384674;4879.463259041502;11558.190926635054;11634.53461861533;11717.355545445082;11734.178607497393;11738.295702257892;5951.613145145639;5962;5962.4633782959745;5982.581219314607;5985.25184351956;5307.124324486494;5456.7544460499375;5485;5546.55627549795;5547.72261386929;3825.9617403826164;3989.740666856645;3998.28030954431;4022.0950286185375;4039.5354534228354;1069.3583849690124;1074.2426589254558;1079.83262594298;1082.1721383141903;1087.3041253597703;927.5408672707053;929.953502324881;938.6620816506053;953.4470007395694;995.8157740817971;6507.642079685568;6527.673616319165;6543.1821022372205;6793.015012823212;6886.2769409212315;829.0051937674814;957.6648173139484;967.666155176463;972.1493693018608;1031.896810318962;4122.134351786099;4228.386883901879;4252.659787217015;4361.454818147731;4401.581849724271;13431.328433578283;14074;14081.507147249797;14090.013699041174;14092.577222683336;11343.149263805248;13281.535146269835;13944.860551394559;13955.720885582232;13977.930110349587;10209.336393134423;10403.895961040444;10480.371177729208;10496.107830834464;10513;6407.327230640715;6628.436582890508;6645.136132302963;6795.082663840516;6973.825654358681;11926.940365298118;12270.570530031406;12373.62879113628;12621.81067283973;12649.87350126487;10790.082842958123;11185.608503702331;12533;12862.678433038875;12876.484940602271;9218.677346292847;9462.952685236529;9464.763380915532;9509.334346595671;9516.953135154989;10416.114630256514;10416.947915260374;10458.058774710009;10464.00591943764;10587.20595955291;8984.59569319369;8988.68539601137;9018.368714189945;9128.908710913072;9133.908660913572;8715.259202967565;8800.251978581893;8843.778905527155;8881.866771998555;8897.866532001895;7907.090684571348;7944.563049032358;7976.680932762624;8067.1529489402765;8074.232947869943;5443.727813609383;5635.816478539553;5807.448292412214;5905.40945905406;6065.545084118797;5967.37342579023;5980.37206093354;5985.311689155628;5985.5810093294795;6133.417325354084 12:35:52 INFO - PROCESS | 1641 | |4;prototype.html;385.35260761919966;387.0845450509545;391.18242872396684;396.4825902197632;402.07522697795065;4737.407824021997;4795.808167673289;5227.764750586223;5320.76056577454;5444.292242008543;5183.663061900983;5192.8701782455455;5198.142306519419;5227.111391063519;5259.815906443275;3753.230587729509;4234.343676730112;4357.4771027476745;4375.453068366446;4382.145481631083;3407.693307602315;4268.935965960513;4302.924268932767;4405.00887300358;4427.911441771163;3789.147441825581;4371.846985355513;4393.05549306899;4405.691601587874;4445.666575006871;3757.079515518688;3791.7345785795005;4240.681948853818;4401.163778882018;4427.955720442803;4018.718689691723;4019.577944315863;4075.286824805647;4075.918481630351;4088.9795551022353;5157.226416037587;5158.40678321994;5162.767675454576;5169.224616307546;5199.584033277367;4066.8576599818866;4453.398791163184;4825.782839772219;4956.355673762388;5055.9241611375855;5031.4465408805;5041.59667226621;5658.292713410824;5672.886542269172;5688;3662.6154253803234;4355.847545335898;4520.524680566248;4521.1409832131785;4560.863174104782;5614.326280846284;5929.733162007677;5946.345901950782;6016.789412370546;6021.657979821198;3971.6425521703186;4295.334223195409;4298.849540266076;4632.258838585877;4636.768161591907;4225.577442255812;4579.564941330568;4700.85897423071;4751.883307422753;4789.544993225638;4365.991643843804;4389.956100438954;4799.952179803962;5050.974745126325;5134.409542902539;4596.587276214805;5454.318210223722;5502.669839809624;5514.91727624086;5562.749676264578;1438.6259572511012;1446.9220430779137;1517.4006267524487;1609.7263465210708;1612.4114698135331;1112.844201811747;1198.1074099795708;1212.1817773003188;1214.7934851075338;1225.2893321873291;4576.588107070313;5047.586675730801;5071.467495912876;5123.43828085957;5138.897222055538;5223.555997740159;5771.5571107223195;5869.178315035899;5891.466669332228;5896.115582662635;4333.891652708708;4699.365585645963;4724.433068031858;4920.926186107211;5042.646639984443;2695.3126952627345;2841.7070233043914;2850.8454076099215;3024.3044099856716;3048.5579590959187;5440.292761940922;5684.488396044376;5732.308968854198;5893.852653683693;6075.75696972125;5191.61062920275;5276.973615131899;5593.916091258634;5600.635958662755;5928.132638218947;5739.827805165852;5809.5890205565065;5904.527637788967;5927.050912488121;6129.478994285536;4917.90988829753;5717.828465146053;5806.709664516673;5957;6002.979493486205;4655.813767449264;4974.452810190804;5832.387599302012;5853.590248682552;5857.941420585739;6060.424379593379;6088.6042407243385;6125.601835880653;6130.069028256085;6152.939060220233;4324.654027677841;5124.769385377593;5137.434882163034;5144.562712169358;5217.6694942789345;4689.788959496763;5636.323641163087;5813.941860581509;5818.94181058184;5838.678872662044;5146.794128234828;5389.676619402849;5683.204351390726;5716.685582293014;5725.656460612377;5399.4600539945695;5625.8593535161945;5688.265079150899;5742.765305459347;5746.500272381528;4837.3311207633315;4968;4981.775820088177;5249.868753281198;5259.737013149251;5094.745262736922;5382.434844341445;5554.1391084381985;5683.749575093473;5799.797007104731;4939.407271127344;5034.521720436553;5365.543928765943;5524.392316845064;5636.80058356527 12:35:52 INFO - PROCESS | 1641 | |5;yui.html;695.7008486350869;725.4015437264255;742.9591372474512;805.931495822855;852.1478521478522;15736.842631573682;16615.69521068201;16933.83066169338;16964.236609352578;17086.060266685326;15354;15704.293306801193;15881.761773573377;15957.760633590477;16056.919715401407;984.7538115471132;1008.9091981721643;1009.2128140050736;1012.8987101289886;1012.9949350253238;975.7560609847538;995.7510622344414;1008.4604736465992;1013.4780587997187;1018.8318927376974;1063.8989296016905;1064.834950582661;1066.5467176450018;1066.568039943824;1066.9413182274973;1041.0630432610635;1058.1164727452585;1059.2426415113193;1059.6026490066224;1059.607945060325;791.3115589437169;797.5095316380384;797.6490344248571;799.9200079992013;800.5276886636854;16895.746563801553;17082.829171708374;17101.914490427593;17575.824241757546;17823.46529604101;10588.5235164417;12140.846619571215;12295.56965506203;12448.564300249538;12669.619911402673;9775;11633.592824251195;11706.590269340617;11855.288682679067;12025.63923082309;3576.946345804815;3751.0997360633523;3754.935778717379;3781.7541859779303;3874.2832575973534;107468;117018;118125.81874181234;118505.8149418512;118866;10670.11131947488;10868.293560918673;11008.39453830031;11151.275167114112;11443.313401195956;22849.885750571142;23274.53450931006;23291.88354058219;23390.64914026291;23494.412639684146;6987.8602427951155;7379.88930166048;7744.0707115145415;7848.411369147223;7911.28798408146;4301.290287102655;4374.715643483227;4436.977815110904;4490.887727806766;4551.52209018055;3343.097363711784;3387.864485420605;3388.661133886641;3405.523226748257;3419.65803419661;590.1767034986256;608.4159207161086;612.543654977045;614.2874265851591;616.7841255560518;636.296891934415;648.2836465705421;651.3453978751324;654.5777973207255;670.5741853922697;9369.672061477815;9577.23382129428;10240.539175737109;10283.43441110746;10321.277510574339;645.5061877663591;670.2091531992198;673.5419914458117;676.1345477788439;683.3337495941418;4287.8070486828165;4415.161119387306;4436;4452.844150454718;4500.527444618333;77970.22029779744;97501;99122.5043874776;99573.50213248888;100614.49692751489;50734;63247.36752632507;76951.61524192455;79532;84965;53488.0814926996;70010;75735;80603.38793224102;81004.78492822613;10439.634612788515;10688;11046.33721976684;11119.666410007712;11268.042216411697;46934.765326173154;48917.26624100713;49318.75340623417;51845;51929.480705192465;62937.68531157315;63910.041349378866;66333;67264.99102513368;67327.66336168288;12398.442070106687;12773.872261277269;12824.935875320563;12981;12983.026273029373;14549.127052376467;14686.632834178803;14993.43488050477;15056;15116;13560.915126789636;13922.860771392156;13992;14104.78842817338;14494.420223190955;13569.253691047088;13883.86116138866;13992.720145597235;14026.719465610426;14030;12912.612621621367;13115.147515411467;13265.27041012715;13721.794173087212;14003.50987715425;9397;9588.712338629852;9751.902480975099;9792.608295668093;9805.264605154785;9296.910523607048;10953.34279943206;11207.271527350696;11286.548538058714;11398.544058237578 12:35:52 INFO - PROCESS | 1641 | __end_tp_report 12:35:52 INFO - PROCESS | 1641 | __start_cc_report 12:35:52 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,375 12:35:52 INFO - PROCESS | 1641 | __end_cc_report 12:35:52 INFO - PROCESS | 1641 | __startTimestamp1496432152680__endTimestamp 12:35:52 INFO - PROCESS | 1641 | 12:35:52 INFO - PROCESS | 1641 | ------- Summary: start ------- 12:35:52 INFO - PROCESS | 1641 | Number of tests: 6 12:35:52 INFO - PROCESS | 1641 | 12:35:52 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5842.85 Median:6097.45 stddev:676.16 (11.1%) stddev-sans-first:650.84 12:35:52 INFO - PROCESS | 1641 | Values: 3414.1 3471.6 3481.3 3508.4 3540.2 5879.1 5901.5 5986.5 6048.7 6087.0 5915.9 5932.0 5968.3 6010.3 6013.2 5377.7 5413.7 5441.8 5476.2 5506.4 4503.9 5470.5 5472.3 5480.8 5489.2 4454.4 5251.8 5472.9 5505.2 5531.6 4668.3 5405.4 5410.2 5452.2 5479.0 5296.7 5435.6 5438.7 5440.4 5461.6 5935.6 6002.9 6079.4 6089.9 6109.3 6178.3 6249.0 6256.7 6263.4 6265.5 6352.4 6378.7 6379.0 6403.7 6410.8 6133.5 6154.4 6156.4 6163.4 6167.2 6211.2 6281.1 6303.4 6478.9 6498.9 5306.4 5568.7 6348.6 6367.9 6373.6 6357.4 6383.8 6385.1 6386.0 6417.8 5120.7 6270.0 6271.3 6303.3 6326.3 6259.1 6259.6 6273.2 6277.6 6279.7 4814.6 4834.7 4895.8 4930.0 4935.6 4390.6 4424.2 4445.6 4445.8 4477.8 5654.4 5695.1 5727.9 5833.2 5883.3 6208.0 6278.0 6308.6 6314.7 6319.1 5892.2 5893.5 5899.1 5904.9 5936.7 5590.9 5592.1 5615.4 5616.2 5625.9 6284.0 6304.3 6439.4 6457.2 6502.8 4921.4 5496.9 5771.7 6376.9 6405.0 6501.9 6593.4 6595.1 6618.8 6673.8 6217.3 6244.3 6252.4 6268.4 6337.3 6374.9 6391.0 6400.1 6412.4 6461.4 5265.8 6097.5 6250.5 6270.6 6330.7 5643.8 5827.1 6181.7 6264.7 6347.0 6115.1 6195.6 6260.1 6274.7 6304.0 6274.9 6316.4 6326.9 6354.6 6354.9 4938.4 5991.4 6046.7 6047.4 6169.5 12:35:52 INFO - PROCESS | 1641 | 12:35:52 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:53397.42 Median:29355.63 stddev:107152.14 (365.0%) stddev-sans-first:107379.84 12:35:52 INFO - PROCESS | 1641 | Values: 758.6 782.1 824.0 965.3 973.7 38248.8 39018.4 40635.0 40657.4 40704.2 39100.4 39117.6 39194.0 39311.8 39328.8 8062.7 8228.1 11023.9 11297.4 11512.8 11048.3 11161.3 11168.6 11186.7 11190.2 11196.9 11213.0 11220.4 11221.4 11227.2 11153.3 11177.8 11184.3 11185.6 11202.5 11034.9 11035.8 11093.3 11093.3 11108.0 35198.5 35459.9 35484.0 35503.8 35572.5 3259.6 3750.1 3810.8 3840.2 4003.7 25528.5 25754.1 26142.5 26738.5 27333.3 2580.7 3038.9 3063.3 3105.2 3138.1 253701.0 265994.0 363590.0 369673.0 450342.7 43869.8 44347.0 44437.0 44455.1 44459.0 45317.9 45433.8 45532.3 45558.5 45746.8 13780.4 13866.2 13903.5 13942.3 13961.0 12010.3 12155.6 12178.3 12186.5 12187.6 9836.0 12110.5 12118.4 12136.7 12205.6 5812.4 5848.2 5849.3 5868.5 5868.8 1081.4 1148.0 1148.6 1153.3 1190.1 26812.9 27234.9 27398.0 27442.2 27448.6 1238.1 1253.1 1265.4 1302.5 1314.7 11946.6 14047.9 14298.0 14308.4 14422.6 392033.0 393878.0 394172.0 394384.0 395087.0 55731.4 55807.7 56357.4 56370.7 56649.4 1357.6 1444.4 1478.2 1480.3 1488.2 23093.3 23135.7 27543.6 28447.9 29309.0 38642.0 47666.0 48057.3 48064.8 48115.8 457144.0 460868.0 462146.0 463319.0 464881.0 26589.9 27194.9 32440.0 33105.8 33309.5 38420.0 40074.6 40081.2 40568.0 40697.4 28521.6 28752.7 28853.3 29312.4 29610.3 27028.9 28654.7 29217.0 29398.9 29489.7 32850.0 33800.0 33942.5 34518.3 36115.3 28580.9 29071.9 29414.6 30087.0 30180.5 23124.5 24134.8 24363.5 24485.4 24498.9 12:35:52 INFO - PROCESS | 1641 | 12:35:52 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:42534.01 Median:22049.50 stddev:124021.41 (562.5%) stddev-sans-first:124328.94 12:35:52 INFO - PROCESS | 1641 | Values: 385.5 397.0 401.0 418.2 419.4 21182.4 22032.4 22753.5 22780.9 22988.2 22019.0 22066.6 22346.0 22361.7 22572.5 6071.5 6522.6 6652.0 6703.2 6818.6 6334.6 6703.3 6748.0 6767.8 6778.2 6478.4 6531.2 6664.7 6796.5 6806.3 6562.6 6582.5 6611.2 6629.8 6640.8 6533.8 7181.8 7207.6 7238.0 7253.2 25433.9 25806.7 26308.6 26659.6 26705.7 2513.9 3391.4 3433.1 3450.8 3463.4 12884.4 15900.6 16186.9 16357.8 16654.6 1865.7 2100.3 2127.1 2137.1 2229.3 145005.0 153048.0 153322.0 154451.0 157739.2 15946.8 19566.9 20450.7 20580.0 20604.0 31219.0 31747.8 31980.0 32070.9 32564.0 10196.7 10356.3 10553.3 10576.2 10581.3 8783.5 8784.6 8796.6 8867.2 8908.2 9507.2 9590.9 9671.0 9671.5 9671.8 3561.8 3618.9 3905.4 3973.2 4202.2 1000.4 1014.7 1021.5 1025.3 1057.0 15801.3 17750.1 20837.0 21121.5 21182.9 997.8 997.9 1020.8 1032.0 1037.7 8711.8 10576.6 10774.4 10775.2 10836.7 729557.0 730723.0 731758.4 757264.0 767250.0 111013.0 160080.0 160558.0 161798.0 163942.0 1217.2 1220.2 1252.7 1254.7 1256.1 17829.5 18650.0 18696.6 18704.8 18783.3 49401.0 62746.0 63417.0 63466.7 63642.7 77311.6 84647.0 85093.0 86284.1 86438.0 8287.1 9722.4 10499.3 11079.7 11805.8 9310.7 12321.5 12478.1 12766.0 12816.5 10447.8 11058.3 11171.8 11376.8 11445.5 10306.3 10382.4 10464.4 10692.5 10708.7 11383.3 11427.4 11496.3 11561.1 11564.5 11269.9 11379.8 11438.8 11607.7 11685.4 10999.6 11042.6 11065.6 11191.7 11412.5 12:35:52 INFO - PROCESS | 1641 | 12:35:52 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:6811.34 Median:2460.37 stddev:4228.53 (171.9%) stddev-sans-first:4220.79 12:35:52 INFO - PROCESS | 1641 | Values: 1392.7 1533.3 1597.6 1598.4 1606.5 10889.1 11158.2 11433.6 11456.9 11468.2 11298.3 11448.9 11482.3 11491.1 11518.4 2430.5 2462.7 2472.6 2472.9 2475.8 2401.4 2414.7 2454.7 2456.9 2458.1 2380.6 2394.4 2397.6 2450.7 2451.2 2356.3 2383.3 2401.4 2408.5 2434.7 2367.5 2404.0 2404.9 2408.7 2409.7 11083.6 11260.8 11286.5 11316.8 11403.8 4848.7 5887.9 6035.9 6064.1 6072.5 3536.3 3653.7 3684.1 3697.9 3726.9 913.7 1005.2 1156.1 1156.6 1838.4 13865.8 14185.4 15057.9 15602.5 15757.3 3893.6 4847.8 4852.8 4871.9 4879.5 11558.2 11634.5 11717.4 11734.2 11738.3 5951.6 5962.0 5962.5 5982.6 5985.3 5307.1 5456.8 5485.0 5546.6 5547.7 3826.0 3989.7 3998.3 4022.1 4039.5 1069.4 1074.2 1079.8 1082.2 1087.3 927.5 930.0 938.7 953.4 995.8 6507.6 6527.7 6543.2 6793.0 6886.3 829.0 957.7 967.7 972.1 1031.9 4122.1 4228.4 4252.7 4361.5 4401.6 13431.3 14074.0 14081.5 14090.0 14092.6 11343.1 13281.5 13944.9 13955.7 13977.9 10209.3 10403.9 10480.4 10496.1 10513.0 6407.3 6628.4 6645.1 6795.1 6973.8 11926.9 12270.6 12373.6 12621.8 12649.9 10790.1 11185.6 12533.0 12862.7 12876.5 9218.7 9463.0 9464.8 9509.3 9517.0 10416.1 10416.9 10458.1 10464.0 10587.2 8984.6 8988.7 9018.4 9128.9 9133.9 8715.3 8800.3 8843.8 8881.9 8897.9 7907.1 7944.6 7976.7 8067.2 8074.2 5443.7 5635.8 5807.4 5905.4 6065.5 5967.4 5980.4 5985.3 5985.6 6133.4 12:35:52 INFO - PROCESS | 1641 | 12:35:52 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4699.09 Median:5063.70 stddev:1324.19 (26.2%) stddev-sans-first:1287.71 12:35:52 INFO - PROCESS | 1641 | Values: 385.4 387.1 391.2 396.5 402.1 4737.4 4795.8 5227.8 5320.8 5444.3 5183.7 5192.9 5198.1 5227.1 5259.8 3753.2 4234.3 4357.5 4375.5 4382.1 3407.7 4268.9 4302.9 4405.0 4427.9 3789.1 4371.8 4393.1 4405.7 4445.7 3757.1 3791.7 4240.7 4401.2 4428.0 4018.7 4019.6 4075.3 4075.9 4089.0 5157.2 5158.4 5162.8 5169.2 5199.6 4066.9 4453.4 4825.8 4956.4 5055.9 5031.4 5041.6 5658.3 5672.9 5688.0 3662.6 4355.8 4520.5 4521.1 4560.9 5614.3 5929.7 5946.3 6016.8 6021.7 3971.6 4295.3 4298.8 4632.3 4636.8 4225.6 4579.6 4700.9 4751.9 4789.5 4366.0 4390.0 4800.0 5051.0 5134.4 4596.6 5454.3 5502.7 5514.9 5562.7 1438.6 1446.9 1517.4 1609.7 1612.4 1112.8 1198.1 1212.2 1214.8 1225.3 4576.6 5047.6 5071.5 5123.4 5138.9 5223.6 5771.6 5869.2 5891.5 5896.1 4333.9 4699.4 4724.4 4920.9 5042.6 2695.3 2841.7 2850.8 3024.3 3048.6 5440.3 5684.5 5732.3 5893.9 6075.8 5191.6 5277.0 5593.9 5600.6 5928.1 5739.8 5809.6 5904.5 5927.1 6129.5 4917.9 5717.8 5806.7 5957.0 6003.0 4655.8 4974.5 5832.4 5853.6 5857.9 6060.4 6088.6 6125.6 6130.1 6152.9 4324.7 5124.8 5137.4 5144.6 5217.7 4689.8 5636.3 5813.9 5818.9 5838.7 5146.8 5389.7 5683.2 5716.7 5725.7 5399.5 5625.9 5688.3 5742.8 5746.5 4837.3 4968.0 4981.8 5249.9 5259.7 5094.7 5382.4 5554.1 5683.7 5799.8 4939.4 5034.5 5365.5 5524.4 5636.8 12:35:52 INFO - PROCESS | 1641 | 12:35:52 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:19960.06 Median:23283.21 stddev:28364.44 (121.8%) stddev-sans-first:28407.13 12:35:52 INFO - PROCESS | 1641 | Values: 695.7 725.4 743.0 805.9 852.1 15736.8 16615.7 16933.8 16964.2 17086.1 15354.0 15704.3 15881.8 15957.8 16056.9 984.8 1008.9 1009.2 1012.9 1013.0 975.8 995.8 1008.5 1013.5 1018.8 1063.9 1064.8 1066.5 1066.6 1066.9 1041.1 1058.1 1059.2 1059.6 1059.6 791.3 797.5 797.6 799.9 800.5 16895.7 17082.8 17101.9 17575.8 17823.5 10588.5 12140.8 12295.6 12448.6 12669.6 9775.0 11633.6 11706.6 11855.3 12025.6 3576.9 3751.1 3754.9 3781.8 3874.3 107468.0 117018.0 118125.8 118505.8 118866.0 10670.1 10868.3 11008.4 11151.3 11443.3 22849.9 23274.5 23291.9 23390.6 23494.4 6987.9 7379.9 7744.1 7848.4 7911.3 4301.3 4374.7 4437.0 4490.9 4551.5 3343.1 3387.9 3388.7 3405.5 3419.7 590.2 608.4 612.5 614.3 616.8 636.3 648.3 651.3 654.6 670.6 9369.7 9577.2 10240.5 10283.4 10321.3 645.5 670.2 673.5 676.1 683.3 4287.8 4415.2 4436.0 4452.8 4500.5 77970.2 97501.0 99122.5 99573.5 100614.5 50734.0 63247.4 76951.6 79532.0 84965.0 53488.1 70010.0 75735.0 80603.4 81004.8 10439.6 10688.0 11046.3 11119.7 11268.0 46934.8 48917.3 49318.8 51845.0 51929.5 62937.7 63910.0 66333.0 67265.0 67327.7 12398.4 12773.9 12824.9 12981.0 12983.0 14549.1 14686.6 14993.4 15056.0 15116.0 13560.9 13922.9 13992.0 14104.8 14494.4 13569.3 13883.9 13992.7 14026.7 14030.0 12912.6 13115.1 13265.3 13721.8 14003.5 9397.0 9588.7 9751.9 9792.6 9805.3 9296.9 10953.3 11207.3 11286.5 11398.5 12:35:52 INFO - PROCESS | 1641 | -------- Summary: end -------- 12:35:52 INFO - PROCESS | 1641 | 12:35:53 INFO - TEST-INFO | 1641: exit 0 12:35:53 INFO - TEST-OK | dromaeo_css | took 1115949ms 12:35:53 INFO - TEST-START | kraken 12:35:53 INFO - operating with platform_type : mac_ 12:35:53 INFO - Initialising browser for kraken test... 12:35:53 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmpvQIcsS/profile http://localhost:49227/getInfo.html) 12:35:55 INFO - PROCESS | 1655 | 2017-06-02 12:35:55.775 plugin-container[1657:10559] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.tsm.portname' 12:35:55 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:35:55 INFO - PROCESS | 1655 | 2017-06-02 12:35:55.776 plugin-container[1657:10559] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x874b, name = 'com.apple.CFPasteboardClient' 12:35:55 INFO - PROCESS | 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:35:55 INFO - PROCESS | 1655 | 2017-06-02 12:35:55.776 plugin-container[1657:10559] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:35:55 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 12:35:55 INFO - PROCESS | 1655 | colorDepth:24 12:35:55 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 12:35:55 INFO - PROCESS | 1655 | __metrics 12:35:55 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3381: TypeError: this.tabs is undefined 12:35:56 INFO - TEST-INFO | 1655: exit 0 12:35:56 INFO - Browser initialized. 12:35:56 INFO - Running cycle 1/1 for kraken test... 12:35:56 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/T/tmpvQIcsS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:35:58 INFO - PROCESS | 1658 | 2017-06-02 12:35:58.841 plugin-container[1659:10684] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 12:35:58 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:35:58 INFO - PROCESS | 1658 | 2017-06-02 12:35:58.842 plugin-container[1659:10684] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 12:35:58 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:35:58 INFO - PROCESS | 1658 | 2017-06-02 12:35:58.842 plugin-container[1659:10684] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:36:08 INFO - PROCESS | 1658 | RSS: Main: 203055104 12:36:08 INFO - PROCESS | 1658 | 12:36:11 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 12:36:12 INFO - PROCESS | 1658 | RSS: Main: 216870912 12:36:12 INFO - PROCESS | 1658 | 12:36:15 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 12:36:15 INFO - PROCESS | 1658 | RSS: Main: 224759808 12:36:15 INFO - PROCESS | 1658 | 12:36:19 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 12:36:19 INFO - PROCESS | 1658 | RSS: Main: 228212736 12:36:19 INFO - PROCESS | 1658 | 12:36:21 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 12:36:22 INFO - PROCESS | 1658 | RSS: Main: 228720640 12:36:22 INFO - PROCESS | 1658 | 12:36:24 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:36:25 INFO - PROCESS | 1658 | RSS: Main: 228745216 12:36:25 INFO - PROCESS | 1658 | 12:36:28 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 12:36:29 INFO - PROCESS | 1658 | RSS: Main: 232894464 12:36:29 INFO - PROCESS | 1658 | 12:36:32 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 12:36:32 INFO - PROCESS | 1658 | RSS: Main: 232140800 12:36:32 INFO - PROCESS | 1658 | 12:36:35 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 12:36:36 INFO - PROCESS | 1658 | RSS: Main: 232226816 12:36:36 INFO - PROCESS | 1658 | 12:36:38 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:36:38 INFO - PROCESS | 1658 | RSS: Main: 231653376 12:36:38 INFO - PROCESS | 1658 | 12:36:40 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:36:41 INFO - PROCESS | 1658 | RSS: Main: 232980480 12:36:41 INFO - PROCESS | 1658 | 12:36:43 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:36:44 INFO - PROCESS | 1658 | RSS: Main: 233607168 12:36:44 INFO - PROCESS | 1658 | 12:36:47 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:36:48 INFO - PROCESS | 1658 | RSS: Main: 233717760 12:36:48 INFO - PROCESS | 1658 | 12:36:51 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:36:51 INFO - PROCESS | 1658 | RSS: Main: 233140224 12:36:51 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 12:36:53 INFO - PROCESS | 1658 | RSS: Main: 235253760 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | __start_tp_report 12:36:53 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 12:36:53 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 12:36:53 INFO - PROCESS | 1658 | |i|pagename|runs| 12:36:53 INFO - PROCESS | 1658 | |0;ai-astar;121;177;160;120;117;120;119;139;179;152 12:36:53 INFO - PROCESS | 1658 | |1;audio-beat-detection;148;111;110;145;113;133;113;112;129;118 12:36:53 INFO - PROCESS | 1658 | |2;audio-dft;195;195;200;203;197;198;196;194;203;195 12:36:53 INFO - PROCESS | 1658 | |3;audio-fft;84;59;63;86;62;69;72;62;64;75 12:36:53 INFO - PROCESS | 1658 | |4;audio-oscillator;87;82;79;86;101;102;116;77;70;81 12:36:53 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;125;137;91;91;120;137;91;125;139;101 12:36:53 INFO - PROCESS | 1658 | |6;imaging-darkroom;96;97;98;103;156;97;98;97;121;130 12:36:53 INFO - PROCESS | 1658 | |7;imaging-desaturate;93;95;92;90;93;91;105;114;91;94 12:36:53 INFO - PROCESS | 1658 | |8;json-parse-financial;68;69;96;84;68;70;71;69;69;68 12:36:53 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;58;62;55;60;63;64;60;56;58;60 12:36:53 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;86;85;84;79;80;93;105;108;100;84 12:36:53 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;145;160;163;129;153;165;154;157;210;167 12:36:53 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;195;214;228;188;165;161;170;167;166;200 12:36:53 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;68;67;66;66;65;65;66;67;64;64 12:36:53 INFO - PROCESS | 1658 | __end_tp_report 12:36:53 INFO - PROCESS | 1658 | __start_cc_report 12:36:53 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1017 12:36:53 INFO - PROCESS | 1658 | __end_cc_report 12:36:53 INFO - PROCESS | 1658 | __startTimestamp1496432213641__endTimestamp 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | ------- Summary: start ------- 12:36:53 INFO - PROCESS | 1658 | Number of tests: 14 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:140.40 Median:145.50 stddev:24.86 (17.1%) stddev-sans-first:25.36 12:36:53 INFO - PROCESS | 1658 | Values: 121.0 177.0 160.0 120.0 117.0 120.0 119.0 139.0 179.0 152.0 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:123.20 Median:123.50 stddev:14.54 (11.8%) stddev-sans-first:12.35 12:36:53 INFO - PROCESS | 1658 | Values: 148.0 111.0 110.0 145.0 113.0 133.0 113.0 112.0 129.0 118.0 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:197.60 Median:197.50 stddev:3.34 (1.7%) stddev-sans-first:3.41 12:36:53 INFO - PROCESS | 1658 | Values: 195.0 195.0 200.0 203.0 197.0 198.0 196.0 194.0 203.0 195.0 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:69.60 Median:70.50 stddev:9.51 (13.5%) stddev-sans-first:8.54 12:36:53 INFO - PROCESS | 1658 | Values: 84.0 59.0 63.0 86.0 62.0 69.0 72.0 62.0 64.0 75.0 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:88.10 Median:80.00 stddev:14.00 (17.5%) stddev-sans-first:14.85 12:36:53 INFO - PROCESS | 1658 | Values: 87.0 82.0 79.0 86.0 101.0 102.0 116.0 77.0 70.0 81.0 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:115.70 Median:138.00 stddev:20.24 (14.7%) stddev-sans-first:21.19 12:36:53 INFO - PROCESS | 1658 | Values: 125.0 137.0 91.0 91.0 120.0 137.0 91.0 125.0 139.0 101.0 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:109.30 Median:97.00 stddev:20.20 (20.8%) stddev-sans-first:20.84 12:36:53 INFO - PROCESS | 1658 | Values: 96.0 97.0 98.0 103.0 156.0 97.0 98.0 97.0 121.0 130.0 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:92.50 stddev:7.67 (8.3%) stddev-sans-first:8.07 12:36:53 INFO - PROCESS | 1658 | Values: 93.0 95.0 92.0 90.0 93.0 91.0 105.0 114.0 91.0 94.0 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:73.20 Median:69.50 stddev:9.34 (13.4%) stddev-sans-first:9.72 12:36:53 INFO - PROCESS | 1658 | Values: 68.0 69.0 96.0 84.0 68.0 70.0 71.0 69.0 69.0 68.0 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:60.00 stddev:2.91 (4.9%) stddev-sans-first:3.03 12:36:53 INFO - PROCESS | 1658 | Values: 58.0 62.0 55.0 60.0 63.0 64.0 60.0 56.0 58.0 60.0 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:90.40 Median:84.00 stddev:10.49 (12.5%) stddev-sans-first:11.01 12:36:53 INFO - PROCESS | 1658 | Values: 86.0 85.0 84.0 79.0 80.0 93.0 105.0 108.0 100.0 84.0 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:160.30 Median:161.50 stddev:20.72 (12.8%) stddev-sans-first:21.22 12:36:53 INFO - PROCESS | 1658 | Values: 145.0 160.0 163.0 129.0 153.0 165.0 154.0 157.0 210.0 167.0 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.40 Median:191.50 stddev:23.35 (12.2%) stddev-sans-first:24.51 12:36:53 INFO - PROCESS | 1658 | Values: 195.0 214.0 228.0 188.0 165.0 161.0 170.0 167.0 166.0 200.0 12:36:53 INFO - PROCESS | 1658 | 12:36:53 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.32 (2.0%) stddev-sans-first:1.13 12:36:53 INFO - PROCESS | 1658 | Values: 68.0 67.0 66.0 66.0 65.0 65.0 66.0 67.0 64.0 64.0 12:36:53 INFO - PROCESS | 1658 | -------- Summary: end -------- 12:36:53 INFO - PROCESS | 1658 | 12:36:54 INFO - TEST-INFO | 1658: exit 0 12:36:54 INFO - TEST-OK | kraken | took 61178ms 12:36:54 INFO - SUITE-END | took 1177s 12:36:54 INFO - Completed test suite (00:19:37) 12:36:54 INFO - javascript benchmark 12:36:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7715.248632608167, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3414.1123307939943, 3471.5834099908016, 3481.2863363010583, 3508.3860324443217, 3540.1680605057813, 5879.059350503915, 5901.468867801902, 5986.521078313735, 6048.727807248673, 6087.026075827868, 5915.940840591593, 5931.9915614345555, 5968.313643930949, 6010.338862725108, 6013.188219590354, 5377.704226267544, 5413.729313534327, 5441.809536666217, 5476.205950137228, 5506.421825708303, 4503.909921801562, 5470.5350045246005, 5472.288602481672, 5480.835574932738, 5489.204065410513, 4454.398656181423, 5251.816186433475, 5472.863178420532, 5505.201745746865, 5531.640443371168, 4668.323093151491, 5405.378381486121, 5410.188471729272, 5452.2094296327205, 5479.0, 5296.708681022542, 5435.592330575223, 5438.673679579238, 5440.401555828856, 5461.590380721422, 5935.584509084366, 6002.909956350658, 6079.361667024988, 6089.908651370233, 6109.297430795471, 6178.289496707891, 6249.0, 6256.6871656417, 6263.404976527268, 6265.498760099226, 6352.36476352361, 6378.681065946684, 6379.0, 6403.679816009228, 6410.775622853178, 6133.539984501181, 6154.3845615438095, 6156.415140561684, 6163.445289923929, 6167.198264225622, 6211.16149319836, 6281.089242059876, 6303.432691057827, 6478.870422591522, 6498.935010649928, 5306.363236411656, 5568.749406276727, 6348.619082855043, 6367.936320636734, 6373.649449280241, 6357.364263573606, 6383.776567820104, 6385.13800636908, 6385.96807015962, 6417.7753778617525, 5120.668626157151, 6270.0, 6271.310155882849, 6303.338149494237, 6326.2724786649205, 6259.092431597484, 6259.624422534663, 6273.247210334789, 6277.591956522811, 6279.686015699197, 4814.638902082358, 4834.685745426536, 4895.779689913891, 4929.950700493021, 4935.605151587864, 4390.582894625006, 4424.203643344229, 4445.644348452116, 4445.844395446208, 4477.776111194427, 5654.434556544313, 5695.145728140812, 5727.942720572741, 5833.154192642128, 5883.294004719461, 6208.00671892504, 6278.0, 6308.558400911984, 6314.747410103544, 6319.05214217871, 5892.204552385459, 5893.528517718572, 5899.115132730039, 5904.9075920954765, 5936.7328470218945, 5590.888182236333, 5592.133219351007, 5615.354234263031, 5616.241807355955, 5625.887482250332, 6283.968580157161, 6304.338044505261, 6439.420452159328, 6457.160569125984, 6502.772402965874, 4921.384826896638, 5496.945030549643, 5771.68255745938, 6376.9043464349, 6405.0, 6501.934980650322, 6593.406593406617, 6595.134769279816, 6618.768343107968, 6673.833154171185, 6217.285012223471, 6244.344343843831, 6252.374762523711, 6268.435840774352, 6337.334579869232, 6374.93625063762, 6391.0, 6400.135981642418, 6412.358764123737, 6461.418472337325, 5265.763040663102, 6097.451229389376, 6250.468710159687, 6270.592411493239, 6330.683465826783, 5643.7742490299925, 5827.096799996008, 6181.660008699565, 6264.68676566179, 6347.0, 6115.149072927182, 6195.597286176384, 6260.123582698335, 6274.717637706223, 6303.9684801575695, 6274.905876411766, 6316.431521163117, 6326.8734625306315, 6354.555181137276, 6354.93645063562, 4938.43208031081, 5991.430814072656, 6046.667433291212, 6047.425494578008, 6169.50643948474], "value": 5801.734351703057, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [758.5562445969108, 782.1396463889722, 824.0029564227286, 965.2615748952048, 973.7127547373524, 38248.80875595622, 39018.41472377913, 40635.0, 40657.3737050518, 40704.185916281654, 39100.41349379761, 39117.608823911825, 39194.0, 39311.803440982825, 39328.803355983255, 8062.71780487683, 8228.136045715204, 11023.944880275587, 11297.435128243596, 11512.827307590393, 11048.337099774039, 11161.330320180774, 11168.609098681547, 11186.720331991684, 11190.216684832065, 11196.944015279912, 11213.0, 11220.438978051106, 11221.438928053605, 11227.157963152755, 11153.275037122561, 11177.83233251502, 11184.273022253528, 11185.608503702331, 11202.495887685074, 11034.889651103467, 11035.779284414348, 11093.278936869077, 11093.33439993595, 11108.0, 35198.472022919676, 35459.93620191398, 35484.0, 35503.75736849224, 35572.46641300383, 3259.6414394416597, 3750.1374683822874, 3810.79040652764, 3840.2319536092614, 4003.73975691582, 25528.489430211477, 25754.09860654868, 26142.477150457074, 26738.46523069547, 27333.316667083283, 2580.6645136132183, 3038.9392212155853, 3063.326068264978, 3105.1771280610656, 3138.1056398926426, 253701.0, 265994.0, 363590.0, 369673.0, 450342.74828625977, 43869.78065109718, 44347.0, 44437.0, 44455.11089778251, 44459.0, 45317.86705332393, 45433.77283113563, 45532.3170152448, 45558.54441455543, 45746.771266143456, 13780.379882905294, 13866.237356945263, 13903.513377031957, 13942.302884855717, 13961.0, 12010.279383237033, 12155.574554890536, 12178.269303841798, 12186.51253949832, 12187.634370968884, 9836.0, 12110.515579376903, 12118.39408029595, 12136.696582585506, 12205.572804951784, 5812.44781745742, 5848.181254624356, 5849.298084229835, 5868.471837534642, 5868.794592189253, 1081.416035340923, 1147.971300717472, 1148.586508856811, 1153.2792004996877, 1190.1371505658328, 26812.865935670197, 27234.863825681143, 27398.0, 27442.17673469799, 27448.588271175948, 1238.13949305232, 1253.078986944595, 1265.4368805881295, 1302.4529697527062, 1314.737052589486, 11946.641600751991, 14047.929760351337, 14298.0, 14308.427662893577, 14422.567322980327, 392033.0, 393878.0306098451, 394172.0, 394384.0, 395087.0, 55731.442685573435, 55807.72096139493, 56357.43642563522, 56370.718146409825, 56649.43350566524, 1357.6470117769452, 1444.4294503671201, 1478.1943840606896, 1480.326451464581, 1488.24843454055, 23093.307200784002, 23135.652965205198, 27543.586846196922, 28447.857760711064, 29309.0, 38642.0339491515, 47666.0, 48057.27914081362, 48064.75967620139, 48115.75942120407, 457144.0, 460868.0, 462146.0, 463319.0, 464881.0, 26589.867050664623, 27194.864025679744, 32440.0, 33105.84129555454, 33309.50035749514, 38420.0, 40074.59925400709, 40081.1983760329, 40568.0, 40697.38953915634, 28521.572176417787, 28752.712472875002, 28853.27866803347, 29312.41375172527, 29610.259743506584, 27028.864855675594, 28654.713452865206, 29217.0, 29398.85300573569, 29489.705102949552, 32850.01449956505, 33800.0, 33942.49086263659, 34518.30963380769, 36115.277694446486, 28580.85709571439, 29071.854640726662, 29414.558781617863, 30087.0, 30180.54729179108, 23124.537509250058, 24134.75865241325, 24363.473232977627, 24485.38786530351, 24498.877505613073], "value": 18507.680518462897, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.4565063260803, 396.98213580388887, 400.99198016039696, 418.2200196633279, 419.4379531427886, 21182.364529064133, 22032.44918877029, 22753.54492910143, 22780.86095695217, 22988.19541316054, 22019.0, 22066.558668826612, 22346.0, 22361.664575031387, 22572.54854902901, 6071.453569178773, 6522.57603255788, 6651.966740166293, 6703.229128650194, 6818.590884546943, 6334.619922804646, 6703.262641109474, 6748.0, 6767.796966091025, 6778.152730908637, 6478.449331806802, 6531.216254049521, 6664.700088496029, 6796.524243302971, 6806.319368063178, 6562.639054851981, 6582.5063120265695, 6611.206655201358, 6629.8342541436605, 6640.833979150511, 6533.836654083639, 7181.820454488679, 7207.639618019078, 7238.022866912952, 7253.165885923081, 25433.87283063591, 25806.741932580622, 26308.60537091926, 26659.600105998426, 26705.73294267052, 2513.912013079552, 3391.406503861814, 3433.073070271013, 3450.8447119879665, 3463.4458486642015, 12884.420201090974, 15900.5901835296, 16186.919065404716, 16357.83642163587, 16654.58363540909, 1865.664180447519, 2100.3488918435332, 2127.1066152215953, 2137.0810551462864, 2229.297771202066, 145005.0, 153047.9999999989, 153322.0, 154451.0, 157739.21130394388, 15946.760798588031, 19566.902165489082, 20450.693239601416, 20580.0, 20604.0, 31219.0, 31747.84126079355, 31980.040598782074, 32070.87751928718, 32564.0, 10196.694099177037, 10356.326838755456, 10553.314034587727, 10576.206784491194, 10581.31221470602, 8783.5169065702, 8784.560771961376, 8796.648134074565, 8867.201951824367, 8908.153725396078, 9507.239420846316, 9590.90409095914, 9671.0, 9671.468069256118, 9671.758206044906, 3561.7684850484634, 3618.945715814265, 3905.4378248700746, 3973.179042879588, 4202.159568086395, 1000.4397537379091, 1014.7209517382779, 1021.5147804792813, 1025.2720568396521, 1056.9852941176557, 15801.288941997638, 17750.11249437523, 20837.0, 21121.471963200736, 21182.89408552978, 997.835357166074, 997.850322451638, 1020.831562792131, 1031.9535620897077, 1037.7353774787382, 8711.823903772909, 10576.576936922436, 10774.353538787542, 10775.245732798629, 10836.67489975302, 729557.0, 730723.0, 731758.3779892894, 757264.0, 767250.0, 111013.0, 160080.0, 160558.0, 161798.0, 163942.0, 1217.1662411248324, 1220.1519943639264, 1252.6868282929086, 1254.667513109027, 1256.1269917407315, 17829.46511604654, 18650.0, 18696.626067478846, 18704.812951870306, 18783.34258300953, 49401.0, 62746.0, 63417.0, 63466.68266658822, 63642.68178659077, 77311.61344193244, 84647.0, 85093.0, 86284.13715863012, 86438.0, 8287.12985136576, 9722.416655000723, 10499.317544359592, 11079.667609971713, 11805.76388472243, 9310.72067837966, 12321.507139714311, 12478.064145188966, 12765.978721702057, 12816.487340506275, 10447.791044179226, 11058.281211721212, 11171.776564468502, 11376.829347559627, 11445.542178313108, 10306.33008854422, 10382.42896640692, 10464.372137671764, 10692.465376731288, 10708.678739637824, 11383.307458422487, 11427.371494567546, 11496.310221386744, 11561.075113991028, 11564.47959841826, 11269.887301126884, 11379.772404551697, 11438.771224575628, 11607.7364913841, 11685.415729213335, 10999.560017599206, 11042.613508527164, 11065.612703555336, 11191.72020699489, 11412.543498259976], "value": 11476.980669102992, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1392.728417958498, 1533.3253368517858, 1597.6405308805513, 1598.3686443854679, 1606.5100144455937, 10889.074428673563, 11158.218924675277, 11433.599824006162, 11456.942715286423, 11468.19722619417, 11298.322100673966, 11448.942755286213, 11482.253653512516, 11491.138164637643, 11518.424078796048, 2430.465297634522, 2462.655228268044, 2472.5549401107883, 2472.9010839566395, 2475.8390704604144, 2401.387646150229, 2414.674019007438, 2454.6686197363306, 2456.91400800972, 2458.090506512593, 2380.583397905368, 2394.437307232799, 2397.580423425902, 2450.681411416522, 2451.1911069347075, 2356.32844639277, 2383.2611890314056, 2401.3516350585332, 2408.4701365699516, 2434.6834911461574, 2367.4791545859885, 2404.038384646138, 2404.89177986991, 2408.6507456418894, 2409.662647229389, 11083.556657733681, 11260.774784504263, 11286.548538058469, 11316.773664526663, 11403.828942565786, 4848.7090774553635, 5887.911681324784, 6035.939640603582, 6064.06007068905, 6072.483838873701, 3536.328097661462, 3653.6529029742132, 3684.1342284563107, 3697.9075523112137, 3726.9440958385644, 913.7258822353267, 1005.2109094360892, 1156.0751398880861, 1156.5951916829126, 1838.44846546037, 13865.792013119812, 14185.43258269668, 15057.924710376377, 15602.531924042298, 15757.290921908541, 3893.5717071122153, 4847.830325938575, 4852.757362131879, 4871.853844384674, 4879.463259041502, 11558.190926635054, 11634.53461861533, 11717.355545445082, 11734.178607497393, 11738.295702257892, 5951.613145145639, 5962.0, 5962.4633782959745, 5982.581219314607, 5985.25184351956, 5307.124324486494, 5456.7544460499375, 5485.0, 5546.55627549795, 5547.72261386929, 3825.9617403826164, 3989.740666856645, 3998.28030954431, 4022.0950286185375, 4039.5354534228354, 1069.3583849690124, 1074.2426589254558, 1079.83262594298, 1082.1721383141903, 1087.3041253597703, 927.5408672707053, 929.953502324881, 938.6620816506053, 953.4470007395694, 995.8157740817971, 6507.642079685568, 6527.673616319165, 6543.1821022372205, 6793.015012823212, 6886.2769409212315, 829.0051937674814, 957.6648173139484, 967.666155176463, 972.1493693018608, 1031.896810318962, 4122.134351786099, 4228.386883901879, 4252.659787217015, 4361.454818147731, 4401.581849724271, 13431.328433578283, 14074.0, 14081.507147249797, 14090.013699041174, 14092.577222683336, 11343.149263805248, 13281.535146269835, 13944.860551394559, 13955.720885582232, 13977.930110349587, 10209.336393134423, 10403.895961040444, 10480.371177729208, 10496.107830834464, 10513.0, 6407.327230640715, 6628.436582890508, 6645.136132302963, 6795.082663840516, 6973.825654358681, 11926.940365298118, 12270.570530031406, 12373.62879113628, 12621.81067283973, 12649.87350126487, 10790.082842958123, 11185.608503702331, 12533.0, 12862.678433038875, 12876.484940602271, 9218.677346292847, 9462.952685236529, 9464.763380915532, 9509.334346595671, 9516.953135154989, 10416.114630256514, 10416.947915260374, 10458.058774710009, 10464.00591943764, 10587.20595955291, 8984.59569319369, 8988.68539601137, 9018.368714189945, 9128.908710913072, 9133.908660913572, 8715.259202967565, 8800.251978581893, 8843.778905527155, 8881.866771998555, 8897.866532001895, 7907.090684571348, 7944.563049032358, 7976.680932762624, 8067.1529489402765, 8074.232947869943, 5443.727813609383, 5635.816478539553, 5807.448292412214, 5905.40945905406, 6065.545084118797, 5967.37342579023, 5980.37206093354, 5985.311689155628, 5985.5810093294795, 6133.417325354084], "value": 5199.4310365056035, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.35260761919966, 387.0845450509545, 391.18242872396684, 396.4825902197632, 402.07522697795065, 4737.407824021997, 4795.808167673289, 5227.764750586223, 5320.76056577454, 5444.292242008543, 5183.663061900983, 5192.8701782455455, 5198.142306519419, 5227.111391063519, 5259.815906443275, 3753.230587729509, 4234.343676730112, 4357.4771027476745, 4375.453068366446, 4382.145481631083, 3407.693307602315, 4268.935965960513, 4302.924268932767, 4405.00887300358, 4427.911441771163, 3789.147441825581, 4371.846985355513, 4393.05549306899, 4405.691601587874, 4445.666575006871, 3757.079515518688, 3791.7345785795005, 4240.681948853818, 4401.163778882018, 4427.955720442803, 4018.718689691723, 4019.577944315863, 4075.286824805647, 4075.918481630351, 4088.9795551022353, 5157.226416037587, 5158.40678321994, 5162.767675454576, 5169.224616307546, 5199.584033277367, 4066.8576599818866, 4453.398791163184, 4825.782839772219, 4956.355673762388, 5055.9241611375855, 5031.4465408805, 5041.59667226621, 5658.292713410824, 5672.886542269172, 5688.0, 3662.6154253803234, 4355.847545335898, 4520.524680566248, 4521.1409832131785, 4560.863174104782, 5614.326280846284, 5929.733162007677, 5946.345901950782, 6016.789412370546, 6021.657979821198, 3971.6425521703186, 4295.334223195409, 4298.849540266076, 4632.258838585877, 4636.768161591907, 4225.577442255812, 4579.564941330568, 4700.85897423071, 4751.883307422753, 4789.544993225638, 4365.991643843804, 4389.956100438954, 4799.952179803962, 5050.974745126325, 5134.409542902539, 4596.587276214805, 5454.318210223722, 5502.669839809624, 5514.91727624086, 5562.749676264578, 1438.6259572511012, 1446.9220430779137, 1517.4006267524487, 1609.7263465210708, 1612.4114698135331, 1112.844201811747, 1198.1074099795708, 1212.1817773003188, 1214.7934851075338, 1225.2893321873291, 4576.588107070313, 5047.586675730801, 5071.467495912876, 5123.43828085957, 5138.897222055538, 5223.555997740159, 5771.5571107223195, 5869.178315035899, 5891.466669332228, 5896.115582662635, 4333.891652708708, 4699.365585645963, 4724.433068031858, 4920.926186107211, 5042.646639984443, 2695.3126952627345, 2841.7070233043914, 2850.8454076099215, 3024.3044099856716, 3048.5579590959187, 5440.292761940922, 5684.488396044376, 5732.308968854198, 5893.852653683693, 6075.75696972125, 5191.61062920275, 5276.973615131899, 5593.916091258634, 5600.635958662755, 5928.132638218947, 5739.827805165852, 5809.5890205565065, 5904.527637788967, 5927.050912488121, 6129.478994285536, 4917.90988829753, 5717.828465146053, 5806.709664516673, 5957.0, 6002.979493486205, 4655.813767449264, 4974.452810190804, 5832.387599302012, 5853.590248682552, 5857.941420585739, 6060.424379593379, 6088.6042407243385, 6125.601835880653, 6130.069028256085, 6152.939060220233, 4324.654027677841, 5124.769385377593, 5137.434882163034, 5144.562712169358, 5217.6694942789345, 4689.788959496763, 5636.323641163087, 5813.941860581509, 5818.94181058184, 5838.678872662044, 5146.794128234828, 5389.676619402849, 5683.204351390726, 5716.685582293014, 5725.656460612377, 5399.4600539945695, 5625.8593535161945, 5688.265079150899, 5742.765305459347, 5746.500272381528, 4837.3311207633315, 4968.0, 4981.775820088177, 5249.868753281198, 5259.737013149251, 5094.745262736922, 5382.434844341445, 5554.1391084381985, 5683.749575093473, 5799.797007104731, 4939.407271127344, 5034.521720436553, 5365.543928765943, 5524.392316845064, 5636.80058356527], "value": 4315.062621362962, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [695.7008486350869, 725.4015437264255, 742.9591372474512, 805.931495822855, 852.1478521478522, 15736.842631573682, 16615.69521068201, 16933.83066169338, 16964.236609352578, 17086.060266685326, 15354.0, 15704.293306801193, 15881.761773573377, 15957.760633590477, 16056.919715401407, 984.7538115471132, 1008.9091981721643, 1009.2128140050736, 1012.8987101289886, 1012.9949350253238, 975.7560609847538, 995.7510622344414, 1008.4604736465992, 1013.4780587997187, 1018.8318927376974, 1063.8989296016905, 1064.834950582661, 1066.5467176450018, 1066.568039943824, 1066.9413182274973, 1041.0630432610635, 1058.1164727452585, 1059.2426415113193, 1059.6026490066224, 1059.607945060325, 791.3115589437169, 797.5095316380384, 797.6490344248571, 799.9200079992013, 800.5276886636854, 16895.746563801553, 17082.829171708374, 17101.914490427593, 17575.824241757546, 17823.46529604101, 10588.5235164417, 12140.846619571215, 12295.56965506203, 12448.564300249538, 12669.619911402673, 9775.0, 11633.592824251195, 11706.590269340617, 11855.288682679067, 12025.63923082309, 3576.946345804815, 3751.0997360633523, 3754.935778717379, 3781.7541859779303, 3874.2832575973534, 107468.0, 117018.0, 118125.81874181234, 118505.8149418512, 118866.0, 10670.11131947488, 10868.293560918673, 11008.39453830031, 11151.275167114112, 11443.313401195956, 22849.885750571142, 23274.53450931006, 23291.88354058219, 23390.64914026291, 23494.412639684146, 6987.8602427951155, 7379.88930166048, 7744.0707115145415, 7848.411369147223, 7911.28798408146, 4301.290287102655, 4374.715643483227, 4436.977815110904, 4490.887727806766, 4551.52209018055, 3343.097363711784, 3387.864485420605, 3388.661133886641, 3405.523226748257, 3419.65803419661, 590.1767034986256, 608.4159207161086, 612.543654977045, 614.2874265851591, 616.7841255560518, 636.296891934415, 648.2836465705421, 651.3453978751324, 654.5777973207255, 670.5741853922697, 9369.672061477815, 9577.23382129428, 10240.539175737109, 10283.43441110746, 10321.277510574339, 645.5061877663591, 670.2091531992198, 673.5419914458117, 676.1345477788439, 683.3337495941418, 4287.8070486828165, 4415.161119387306, 4436.0, 4452.844150454718, 4500.527444618333, 77970.22029779744, 97501.0, 99122.5043874776, 99573.50213248888, 100614.49692751489, 50734.0, 63247.36752632507, 76951.61524192455, 79532.0, 84965.0, 53488.0814926996, 70010.0, 75735.0, 80603.38793224102, 81004.78492822613, 10439.634612788515, 10688.0, 11046.33721976684, 11119.666410007712, 11268.042216411697, 46934.765326173154, 48917.26624100713, 49318.75340623417, 51845.0, 51929.480705192465, 62937.68531157315, 63910.041349378866, 66333.0, 67264.99102513368, 67327.66336168288, 12398.442070106687, 12773.872261277269, 12824.935875320563, 12981.0, 12983.026273029373, 14549.127052376467, 14686.632834178803, 14993.43488050477, 15056.0, 15116.0, 13560.915126789636, 13922.860771392156, 13992.0, 14104.78842817338, 14494.420223190955, 13569.253691047088, 13883.86116138866, 13992.720145597235, 14026.719465610426, 14030.0, 12912.612621621367, 13115.147515411467, 13265.27041012715, 13721.794173087212, 14003.50987715425, 9397.0, 9588.712338629852, 9751.902480975099, 9792.608295668093, 9805.264605154785, 9296.910523607048, 10953.34279943206, 11207.271527350696, 11286.548538058714, 11398.544058237578], "value": 7627.454535462749, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1574.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 177.0, 160.0, 120.0, 117.0, 120.0, 119.0, 139.0, 179.0, 152.0], "value": 140.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 111.0, 110.0, 145.0, 113.0, 133.0, 113.0, 112.0, 129.0, 118.0], "value": 123.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 195.0, 200.0, 203.0, 197.0, 198.0, 196.0, 194.0, 203.0, 195.0], "value": 197.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 63.0, 86.0, 62.0, 69.0, 72.0, 62.0, 64.0, 75.0], "value": 69.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 82.0, 79.0, 86.0, 101.0, 102.0, 116.0, 77.0, 70.0, 81.0], "value": 88.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 137.0, 91.0, 91.0, 120.0, 137.0, 91.0, 125.0, 139.0, 101.0], "value": 115.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 98.0, 103.0, 156.0, 97.0, 98.0, 97.0, 121.0, 130.0], "value": 109.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 92.0, 90.0, 93.0, 91.0, 105.0, 114.0, 91.0, 94.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 96.0, 84.0, 68.0, 70.0, 71.0, 69.0, 69.0, 68.0], "value": 73.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 55.0, 60.0, 63.0, 64.0, 60.0, 56.0, 58.0, 60.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 84.0, 79.0, 80.0, 93.0, 105.0, 108.0, 100.0, 84.0], "value": 90.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 160.0, 163.0, 129.0, 153.0, 165.0, 154.0, 157.0, 210.0, 167.0], "value": 160.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 214.0, 228.0, 188.0, 165.0, 161.0, 170.0, 167.0, 166.0, 200.0], "value": 185.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 66.0, 66.0, 65.0, 65.0, 66.0, 67.0, 64.0, 64.0], "value": 65.8, "unit": "score"}]}]} 12:36:54 INFO - Return code: 0 12:36:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:36:54 ERROR - # TBPL SUCCESS # 12:36:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:36:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:36:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:36:54 INFO - 2017-06-02 12:36:54.959 screenresolution[1663:11073] starting screenresolution argv=screenresolution get 12:36:54 INFO - 2017-06-02 12:36:54.961 screenresolution[1663:11073] Display 0: 1600x1200x32@60 12:36:54 INFO - 2017-06-02 12:36:54.967 screenresolution[1664:11076] starting screenresolution argv=screenresolution list 12:36:54 INFO - Available Modes on Display 0 12:36:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:36:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:36:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:36:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:36:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:36:55 INFO - Intel Iris: 12:36:55 INFO - Chipset Model: Intel Iris 12:36:55 INFO - Type: GPU 12:36:55 INFO - Bus: Built-In 12:36:55 INFO - VRAM (Dynamic, Max): 1536 MB 12:36:55 INFO - Vendor: Intel (0x8086) 12:36:55 INFO - Device ID: 0x0a2e 12:36:55 INFO - Revision ID: 0x0009 12:36:55 INFO - Displays: 12:36:55 INFO - Display: 12:36:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:36:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:36:55 INFO - Display Serial Number: 1600x1200 60 12:36:55 INFO - Main Display: Yes 12:36:55 INFO - Mirror: Off 12:36:55 INFO - Online: Yes 12:36:55 INFO - Rotation: Supported 12:36:55 INFO - Return code: 0 12:36:55 INFO - Running post-action listener: _resource_record_post_action 12:36:55 INFO - [mozharness: 2017-06-02 19:36:55.203595Z] Finished run-tests step (success) 12:36:55 INFO - Running post-run listener: _resource_record_post_run 12:36:55 INFO - Total resource usage - Wall time: 1198s; CPU: 55.0%; Read bytes: 41203712; Write bytes: 515288064; Read time: 267; Write time: 1675 12:36:55 INFO - TinderboxPrint: CPU usage
54.6% 12:36:55 INFO - TinderboxPrint: I/O read bytes / time
41,203,712 / 267 12:36:55 INFO - TinderboxPrint: I/O write bytes / time
515,288,064 / 1,675 12:36:55 INFO - TinderboxPrint: CPU idle
2,178.4 (45.4%) 12:36:55 INFO - TinderboxPrint: CPU system
963.1 (20.1%) 12:36:55 INFO - TinderboxPrint: CPU user
1,652.1 (34.5%) 12:36:55 INFO - TinderboxPrint: Swap in / out
248,983,552 / 0 12:36:55 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204274176; Write bytes: 203327488; Read time: 15558; Write time: 480 12:36:55 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 39229440; Write bytes: 301167616; Read time: 250; Write time: 1164 12:36:55 INFO - Running post-run listener: _upload_blobber_files 12:36:55 INFO - Blob upload gear active. 12:36:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:36:55 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 12:36:55 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'] 12:36:55 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 12:36:56 INFO - (blobuploader) - INFO - Open directory for files ... 12:36:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:36:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:36:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:36:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:36:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:36:57 INFO - (blobuploader) - INFO - Done attempting. 12:36:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:36:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:36:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:36:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:36:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:36:57 INFO - (blobuploader) - INFO - Done attempting. 12:36:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:36:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:36:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:36:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:36:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:36:58 INFO - (blobuploader) - INFO - Done attempting. 12:36:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:36:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:36:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:37:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:37:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:37:00 INFO - (blobuploader) - INFO - Done attempting. 12:37:00 INFO - (blobuploader) - INFO - Iteration through files over. 12:37:00 INFO - Return code: 0 12:37:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:37:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:37:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3a7ebb20799a08d30eb598bab479a1bfb4272d15ed37b8c16ca3593928945bb1da1a11794ef21f8cfe960367bdb5fa99ac637499eec788481c80860eda40489", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5c1990f77961086653a486ad77c59aaf378ea5a889369d71c2d3ce9c8ddf3cdc4d81ac1b1de0115c6d391d28d719150a29bbb1e114afcbe6d486bbe5ae54ee4f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/189f7eba4fbbe1f0ec22aa0c601f1fcd4240ff517c37d182a7970e43356eb59a8393cb5e4f051d099e31167aa56e984d2d0408d27880c8dbcd53c31c6116619e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04402e7d600052b66a945d4d00f22bbad88695038f560a74e76f6683ae44ffe59a2ac9ab22531c19bb07a8668c1b2b59c7d7c548fdb7c3652f59e2a20b7a0279"} 12:37:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:37:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:37:00 INFO - Contents: 12:37:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3a7ebb20799a08d30eb598bab479a1bfb4272d15ed37b8c16ca3593928945bb1da1a11794ef21f8cfe960367bdb5fa99ac637499eec788481c80860eda40489", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5c1990f77961086653a486ad77c59aaf378ea5a889369d71c2d3ce9c8ddf3cdc4d81ac1b1de0115c6d391d28d719150a29bbb1e114afcbe6d486bbe5ae54ee4f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/189f7eba4fbbe1f0ec22aa0c601f1fcd4240ff517c37d182a7970e43356eb59a8393cb5e4f051d099e31167aa56e984d2d0408d27880c8dbcd53c31c6116619e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04402e7d600052b66a945d4d00f22bbad88695038f560a74e76f6683ae44ffe59a2ac9ab22531c19bb07a8668c1b2b59c7d7c548fdb7c3652f59e2a20b7a0279"} 12:37:00 INFO - Running post-run listener: copy_logs_to_upload_dir 12:37:00 INFO - Copying logs to upload dir... 12:37:00 INFO - mkdir: /builds/slave/test/build/upload/logs 12:37:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.021101 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-06-02 12:37:00.937908) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 12:37:00.941297) ========= 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.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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/b3a7ebb20799a08d30eb598bab479a1bfb4272d15ed37b8c16ca3593928945bb1da1a11794ef21f8cfe960367bdb5fa99ac637499eec788481c80860eda40489", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5c1990f77961086653a486ad77c59aaf378ea5a889369d71c2d3ce9c8ddf3cdc4d81ac1b1de0115c6d391d28d719150a29bbb1e114afcbe6d486bbe5ae54ee4f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/189f7eba4fbbe1f0ec22aa0c601f1fcd4240ff517c37d182a7970e43356eb59a8393cb5e4f051d099e31167aa56e984d2d0408d27880c8dbcd53c31c6116619e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04402e7d600052b66a945d4d00f22bbad88695038f560a74e76f6683ae44ffe59a2ac9ab22531c19bb07a8668c1b2b59c7d7c548fdb7c3652f59e2a20b7a0279"} build_url:https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011041 build_url: 'https://queue.taskcluster.net/v1/task/dnaDtMH1TkWeOkQpd5Aznw/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/b3a7ebb20799a08d30eb598bab479a1bfb4272d15ed37b8c16ca3593928945bb1da1a11794ef21f8cfe960367bdb5fa99ac637499eec788481c80860eda40489", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5c1990f77961086653a486ad77c59aaf378ea5a889369d71c2d3ce9c8ddf3cdc4d81ac1b1de0115c6d391d28d719150a29bbb1e114afcbe6d486bbe5ae54ee4f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/189f7eba4fbbe1f0ec22aa0c601f1fcd4240ff517c37d182a7970e43356eb59a8393cb5e4f051d099e31167aa56e984d2d0408d27880c8dbcd53c31c6116619e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/04402e7d600052b66a945d4d00f22bbad88695038f560a74e76f6683ae44ffe59a2ac9ab22531c19bb07a8668c1b2b59c7d7c548fdb7c3652f59e2a20b7a0279"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 12:37:00.974737) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 12:37:00.975110) ========= 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.7qZ4R6GBrk/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.wEzZVd2Rka/Listeners TMPDIR=/var/folders/z9/ny7vg3vx1vq0s9z7q18thht400000w/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.004992 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 12:37:01.024096) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 12:37:01.024460) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 12:37:01.025008) ========= ========= Total master_lag: 2.19 =========