builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0096 starttime: 1495718711.09 results: success (0) buildid: 20170525051644 builduid: 746ae0e659f5456eaaebfc056e3cc955 revision: 02e5b298abedc3fd641a8db70efd59009109ed82 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 06:25:11.093280) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-25 06:25:11.093712) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 06:25:11.093989) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-25 06:25:11.121734) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:25:11.122157) ========= 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.oCa0I20s4P/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.YcCG7OEcHE/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-25 06:25:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 36.3M=0s 2017-05-25 06:25:11 (36.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.099353 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:25:11.244245) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:25:11.244650) ========= 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.oCa0I20s4P/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.YcCG7OEcHE/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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.021468 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:25:11.293499) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 06:25:11.293861) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 02e5b298abedc3fd641a8db70efd59009109ed82 --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 02e5b298abedc3fd641a8db70efd59009109ed82 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oCa0I20s4P/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.YcCG7OEcHE/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-25 06:25:11,372 truncating revision to first 12 chars 2017-05-25 06:25:11,372 Setting DEBUG logging. 2017-05-25 06:25:11,372 attempt 1/10 2017-05-25 06:25:11,372 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/02e5b298abed?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-25 06:25:12,954 unpacking tar archive at: mozilla-beta-02e5b298abed/testing/mozharness/ program finished with exit code 0 elapsedTime=1.839280 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-25 06:25:13.157696) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 06:25:13.158280) ========= script_repo_revision: 02e5b298abedc3fd641a8db70efd59009109ed82 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-25 06:25:13.158905) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 06:25:13.159355) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-25 06:25:13.179912) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-25 06:25:13.180499) ========= /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.oCa0I20s4P/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.YcCG7OEcHE/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 06:25:13 INFO - MultiFileLogger online at 20170525 06:25:13 in /builds/slave/test 06:25:13 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 06:25:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:25:13 INFO - {'append_to_log': False, 06:25:13 INFO - 'base_work_dir': '/builds/slave/test', 06:25:13 INFO - 'blob_upload_branch': 'Mozilla-Beta', 06:25:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:25:13 INFO - 'branch': 'Mozilla-Beta', 06:25:13 INFO - 'buildbot_json_path': 'buildprops.json', 06:25:13 INFO - 'config_files': ('talos/mac_config.py',), 06:25:13 INFO - 'default_actions': ('clobber', 06:25:13 INFO - 'read-buildbot-config', 06:25:13 INFO - 'download-and-extract', 06:25:13 INFO - 'populate-webroot', 06:25:13 INFO - 'create-virtualenv', 06:25:13 INFO - 'install', 06:25:13 INFO - 'run-tests'), 06:25:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:25:13 INFO - 'download_minidump_stackwalk': True, 06:25:13 INFO - 'download_symbols': 'ondemand', 06:25:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:25:13 INFO - 'tooltool.py': '/tools/tooltool.py', 06:25:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:25:13 INFO - '/tools/misc-python/virtualenv.py')}, 06:25:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:25:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:25:13 INFO - 'gecko_profile': False, 06:25:13 INFO - 'gecko_profile_interval': 0, 06:25:13 INFO - 'installer_path': 'installer.exe', 06:25:13 INFO - 'log_level': 'info', 06:25:13 INFO - 'log_name': 'talos', 06:25:13 INFO - 'log_to_console': True, 06:25:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:25:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:25:13 INFO - 'opt_config_files': (), 06:25:13 INFO - 'pip_index': False, 06:25:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:25:13 INFO - 'cmd': ('bash', 06:25:13 INFO - '-c', 06:25:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:25:13 INFO - 'enabled': True, 06:25:13 INFO - 'halt_on_failure': False, 06:25:13 INFO - 'name': 'check_screen_resolution'},), 06:25:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:25:13 INFO - 'cmd': ('bash', 06:25:13 INFO - '-c', 06:25:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:25:13 INFO - 'enabled': True, 06:25:13 INFO - 'halt_on_failure': False, 06:25:13 INFO - 'name': 'check_screen_resolution'},), 06:25:13 INFO - 'run_cmd_checks_enabled': True, 06:25:13 INFO - 'suite': 'dromaeojs-e10s', 06:25:13 INFO - 'system_bits': '32', 06:25:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:25:13 INFO - 'title': 't-yosemite-r7-0096', 06:25:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:25:13 INFO - 'use_talos_json': True, 06:25:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:25:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:25:13 INFO - 'work_dir': 'build'} 06:25:13 INFO - [mozharness: 2017-05-25 13:25:13.390440Z] Running clobber step. 06:25:13 INFO - Running pre-action listener: _resource_record_pre_action 06:25:13 INFO - Running main action method: clobber 06:25:13 INFO - rmtree: /builds/slave/test/build 06:25:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:25:13 INFO - Running post-action listener: _resource_record_post_action 06:25:13 INFO - [mozharness: 2017-05-25 13:25:13.827164Z] Finished clobber step (success) 06:25:13 INFO - [mozharness: 2017-05-25 13:25:13.827312Z] Running read-buildbot-config step. 06:25:13 INFO - Running pre-action listener: _resource_record_pre_action 06:25:13 INFO - Running main action method: read_buildbot_config 06:25:13 INFO - Using buildbot properties: 06:25:13 INFO - { 06:25:13 INFO - "project": "", 06:25:13 INFO - "product": "firefox", 06:25:13 INFO - "script_repo_revision": "production", 06:25:13 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 06:25:13 INFO - "repository": "", 06:25:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 06:25:13 INFO - "buildid": "20170525051644", 06:25:13 INFO - "pgo_build": "False", 06:25:13 INFO - "basedir": "/builds/slave/test", 06:25:13 INFO - "buildnumber": 1, 06:25:13 INFO - "slavename": "t-yosemite-r7-0096", 06:25:13 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 06:25:13 INFO - "platform": "yosemite_r7_devedition", 06:25:13 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 06:25:13 INFO - "branch": "mozilla-beta", 06:25:13 INFO - "repo_path": "releases/mozilla-beta", 06:25:13 INFO - "revision": "02e5b298abedc3fd641a8db70efd59009109ed82", 06:25:13 INFO - "stage_platform": "macosx64-devedition", 06:25:13 INFO - "builduid": "746ae0e659f5456eaaebfc056e3cc955", 06:25:13 INFO - "slavebuilddir": "test" 06:25:13 INFO - } 06:25:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 06:25:13 INFO - Running post-action listener: _resource_record_post_action 06:25:13 INFO - [mozharness: 2017-05-25 13:25:13.830615Z] Finished read-buildbot-config step (success) 06:25:13 INFO - [mozharness: 2017-05-25 13:25:13.830750Z] Running download-and-extract step. 06:25:13 INFO - Running pre-action listener: _resource_record_pre_action 06:25:13 INFO - Running main action method: download_and_extract 06:25:13 INFO - mkdir: /builds/slave/test/build/tests 06:25:13 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')]} 06:25:13 INFO - trying https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 06:25:13 INFO - Downloading https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/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 06:25:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/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 06:25:15 INFO - Downloaded 1287 bytes. 06:25:15 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 06:25:15 INFO - Using the following test package requirements: 06:25:15 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:25:15 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 06:25:15 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 06:25:15 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:25:15 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 06:25:15 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:25:15 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 06:25:15 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 06:25:15 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:25:15 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 06:25:15 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 06:25:15 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:25:15 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 06:25:15 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:25:15 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 06:25:15 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:25:15 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 06:25:15 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:25:15 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 06:25:15 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 06:25:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 06:25:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 06:25:15 INFO - Fetch https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 06:25:16 INFO - Expected file size: 51963861 06:25:16 INFO - Obtained file size: 51963861 06:25:33 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 06:25:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 06:25:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 06:25:33 INFO - Fetch https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 06:25:36 INFO - Expected file size: 51963861 06:25:36 INFO - Obtained file size: 51963861 06:25:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 06:25:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 06:25:51 INFO - Fetch https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 06:25:52 INFO - Expected file size: 14404449 06:25:52 INFO - Obtained file size: 14404449 06:25:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:53 INFO - trying https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:25:53 INFO - Downloading https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 06:25:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/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 06:25:55 INFO - Downloaded 74889227 bytes. 06:25:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:25:55 INFO - mkdir: /builds/slave/test/properties 06:25:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:25:55 INFO - Writing to file /builds/slave/test/properties/build_url 06:25:55 INFO - Contents: 06:25:55 INFO - build_url:https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:25:55 INFO - Running post-action listener: _resource_record_post_action 06:25:55 INFO - Running post-action listener: set_extra_try_arguments 06:25:55 INFO - [mozharness: 2017-05-25 13:25:55.836709Z] Finished download-and-extract step (success) 06:25:55 INFO - [mozharness: 2017-05-25 13:25:55.836864Z] Running populate-webroot step. 06:25:55 INFO - Running pre-action listener: _resource_record_pre_action 06:25:55 INFO - Running main action method: populate_webroot 06:25:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:25:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:25:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:25:55 INFO - '--tppagecycles', 06:25:55 INFO - '7'], 06:25:55 INFO - 'tests': ['tsvgm']}}, 06:25:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:25:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:25:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:25:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:25:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:25:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:25:55 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:25:55 INFO - 'talos_options': ['--disable-e10s'], 06:25:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:25:55 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:25:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:25:55 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:25:55 INFO - 'talos_options': ['--disable-e10s'], 06:25:55 INFO - 'tests': ['damp', 'tps']}, 06:25:55 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:25:55 INFO - 'tests': ['damp', 'tps']}, 06:25:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:25:55 INFO - 'tests': ['dromaeo_dom']}, 06:25:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:25:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:25:55 INFO - 'tests': ['basic_compositor_video']}, 06:25:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:25:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:25:55 INFO - 'tests': ['a11yr', 06:25:55 INFO - 'ts_paint', 06:25:55 INFO - 'tpaint', 06:25:55 INFO - 'sessionrestore', 06:25:55 INFO - 'sessionrestore_no_auto_restore', 06:25:55 INFO - 'tabpaint']}, 06:25:55 INFO - 'other-e10s': {'tests': ['a11yr', 06:25:55 INFO - 'ts_paint', 06:25:55 INFO - 'tpaint', 06:25:55 INFO - 'sessionrestore', 06:25:55 INFO - 'sessionrestore_no_auto_restore', 06:25:55 INFO - 'tabpaint']}, 06:25:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:25:55 INFO - 'ts_paint', 06:25:55 INFO - 'tpaint', 06:25:55 INFO - 'sessionrestore', 06:25:55 INFO - 'sessionrestore_no_auto_restore', 06:25:55 INFO - 'tabpaint']}, 06:25:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:25:55 INFO - 'ts_paint', 06:25:55 INFO - 'tpaint', 06:25:55 INFO - 'sessionrestore', 06:25:55 INFO - 'sessionrestore_no_auto_restore', 06:25:55 INFO - 'tabpaint']}, 06:25:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:25:55 INFO - 'tests': ['a11yr', 06:25:55 INFO - 'ts_paint', 06:25:55 INFO - 'tpaint', 06:25:55 INFO - 'sessionrestore', 06:25:55 INFO - 'sessionrestore_no_auto_restore', 06:25:55 INFO - 'tabpaint']}, 06:25:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:25:55 INFO - 'tests': ['a11yr', 06:25:55 INFO - 'ts_paint', 06:25:55 INFO - 'tpaint', 06:25:55 INFO - 'sessionrestore', 06:25:55 INFO - 'sessionrestore_no_auto_restore', 06:25:55 INFO - 'tabpaint']}, 06:25:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:25:55 INFO - 'tests': ['tsvgx', 06:25:55 INFO - 'tsvgr_opacity', 06:25:55 INFO - 'tart', 06:25:55 INFO - 'tscrollx', 06:25:55 INFO - 'cart', 06:25:55 INFO - 'tsvg_static']}, 06:25:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:25:55 INFO - 'tsvgr_opacity', 06:25:55 INFO - 'tart', 06:25:55 INFO - 'tscrollx', 06:25:55 INFO - 'cart', 06:25:55 INFO - 'tsvg_static']}, 06:25:55 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:25:55 INFO - 'talos_options': ['--disable-e10s'], 06:25:55 INFO - 'tests': ['tp5o']}, 06:25:55 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:25:55 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:25:55 INFO - 'talos_options': ['--disable-e10s', 06:25:55 INFO - '--xperf_path', 06:25:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:25:55 INFO - 'tests': ['tp5n']}, 06:25:55 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:25:55 INFO - 'talos_options': ['--xperf_path', 06:25:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:25:55 INFO - 'tests': ['tp5n']}}, 06:25:55 INFO - 'talos.zip': {'path': '', 06:25:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:25:55 INFO - Running post-action listener: _resource_record_post_action 06:25:55 INFO - [mozharness: 2017-05-25 13:25:55.855852Z] Finished populate-webroot step (success) 06:25:55 INFO - [mozharness: 2017-05-25 13:25:55.855973Z] Running create-virtualenv step. 06:25:55 INFO - Running pre-action listener: _resource_record_pre_action 06:25:55 INFO - Running main action method: create_virtualenv 06:25:55 INFO - Creating virtualenv /builds/slave/test/build/venv 06:25:55 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 06:25:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:25:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:25:55 INFO - Using real prefix '/tools/python27' 06:25:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:25:56 INFO - Installing distribute.............................................................................................................................................................................................done. 06:25:59 INFO - Installing pip.................done. 06:25:59 INFO - Return code: 0 06:25:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:25:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:25:59 INFO - Reading from file tmpfile_stdout 06:25:59 INFO - Output received: 06:25:59 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) 06:25:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:25:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:25:59 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 0x108451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef82d83a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10822fd30>, '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 0x108156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108224be8>, '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.oCa0I20s4P/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.YcCG7OEcHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 06:25:59 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 06:25:59 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 06:25:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oCa0I20s4P/Render', 06:25:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:25:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:25:59 INFO - 'HOME': '/Users/cltbld', 06:25:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:25:59 INFO - 'LOGNAME': 'cltbld', 06:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:25:59 INFO - 'MOZ_NO_REMOTE': '1', 06:25:59 INFO - 'NO_EM_RESTART': '1', 06:25:59 INFO - 'PAGER': '/bin/cat', 06:25:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:25:59 INFO - 'PWD': '/builds/slave/test', 06:25:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:25:59 INFO - 'SHELL': '/bin/bash', 06:25:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YcCG7OEcHE/Listeners', 06:25:59 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 06:25:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:25:59 INFO - 'USER': 'cltbld', 06:25:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:25:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:25:59 INFO - 'XPC_FLAGS': '0x0', 06:25:59 INFO - 'XPC_SERVICE_NAME': '0', 06:25:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:00 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 06:26:00 INFO - Cleaning up... 06:26:00 INFO - Return code: 0 06:26:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:00 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 0x108451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef82d83a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10822fd30>, '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 0x108156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108224be8>, '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.oCa0I20s4P/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.YcCG7OEcHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 06:26:00 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 06:26:00 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 06:26:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oCa0I20s4P/Render', 06:26:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:00 INFO - 'HOME': '/Users/cltbld', 06:26:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:00 INFO - 'LOGNAME': 'cltbld', 06:26:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:00 INFO - 'MOZ_NO_REMOTE': '1', 06:26:00 INFO - 'NO_EM_RESTART': '1', 06:26:00 INFO - 'PAGER': '/bin/cat', 06:26:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:00 INFO - 'PWD': '/builds/slave/test', 06:26:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:00 INFO - 'SHELL': '/bin/bash', 06:26:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YcCG7OEcHE/Listeners', 06:26:00 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 06:26:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:00 INFO - 'USER': 'cltbld', 06:26:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:00 INFO - 'XPC_FLAGS': '0x0', 06:26:00 INFO - 'XPC_SERVICE_NAME': '0', 06:26:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:00 INFO - Downloading/unpacking psutil>=3.1.1 06:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:26:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:26:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:26:02 INFO - Installing collected packages: psutil 06:26:02 INFO - Running setup.py install for psutil 06:26:02 INFO - building 'psutil._psutil_osx' extension 06:26:02 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 06:26:02 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 06:26:02 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 06:26:02 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 06:26:02 INFO - building 'psutil._psutil_posix' extension 06:26:02 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 06:26:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:26:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:26:02 INFO - ^ 06:26:02 INFO - 1 warning generated. 06:26:02 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 06:26:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:26:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:26:03 INFO - Successfully installed psutil 06:26:03 INFO - Cleaning up... 06:26:03 INFO - Return code: 0 06:26:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:26:03 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')]} 06:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:03 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')]} 06:26:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:03 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 0x108451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef82d83a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10822fd30>, '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 0x108156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108224be8>, '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.oCa0I20s4P/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.YcCG7OEcHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 06:26:03 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 06:26:03 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 06:26:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oCa0I20s4P/Render', 06:26:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:03 INFO - 'HOME': '/Users/cltbld', 06:26:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:03 INFO - 'LOGNAME': 'cltbld', 06:26:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:03 INFO - 'MOZ_NO_REMOTE': '1', 06:26:03 INFO - 'NO_EM_RESTART': '1', 06:26:03 INFO - 'PAGER': '/bin/cat', 06:26:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:03 INFO - 'PWD': '/builds/slave/test', 06:26:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:03 INFO - 'SHELL': '/bin/bash', 06:26:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YcCG7OEcHE/Listeners', 06:26:03 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 06:26:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:03 INFO - 'USER': 'cltbld', 06:26:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:03 INFO - 'XPC_FLAGS': '0x0', 06:26:03 INFO - 'XPC_SERVICE_NAME': '0', 06:26:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:26:03 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 06:26:03 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 06:26:03 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 06:26:03 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 06:26:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:26:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:26:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:26:04 INFO - Installing collected packages: mozsystemmonitor 06:26:04 INFO - Running setup.py install for mozsystemmonitor 06:26:05 INFO - Successfully installed mozsystemmonitor 06:26:05 INFO - Cleaning up... 06:26:05 INFO - Return code: 0 06:26:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:26:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:05 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 0x108451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef82d83a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10822fd30>, '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 0x108156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108224be8>, '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.oCa0I20s4P/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.YcCG7OEcHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 06:26:05 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 06:26:05 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 06:26:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oCa0I20s4P/Render', 06:26:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:05 INFO - 'HOME': '/Users/cltbld', 06:26:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:05 INFO - 'LOGNAME': 'cltbld', 06:26:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:05 INFO - 'MOZ_NO_REMOTE': '1', 06:26:05 INFO - 'NO_EM_RESTART': '1', 06:26:05 INFO - 'PAGER': '/bin/cat', 06:26:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:05 INFO - 'PWD': '/builds/slave/test', 06:26:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:05 INFO - 'SHELL': '/bin/bash', 06:26:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YcCG7OEcHE/Listeners', 06:26:05 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 06:26:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:05 INFO - 'USER': 'cltbld', 06:26:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:05 INFO - 'XPC_FLAGS': '0x0', 06:26:05 INFO - 'XPC_SERVICE_NAME': '0', 06:26:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:05 INFO - Downloading/unpacking jsonschema==2.5.1 06:26:05 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 06:26:05 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 06:26:05 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 06:26:05 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 06:26:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:26:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:26:06 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 06:26:06 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 06:26:06 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 06:26:06 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 06:26:06 INFO - Downloading functools32-3.2.3-2.tar.gz 06:26:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:26:07 INFO - warning: no files found matching '*.txt' 06:26:07 INFO - no previously-included directories found matching 'build' 06:26:07 INFO - no previously-included directories found matching 'dist' 06:26:07 INFO - no previously-included directories found matching '.git*' 06:26:07 INFO - Installing collected packages: jsonschema, functools32 06:26:07 INFO - Running setup.py install for functools32 06:26:07 INFO - warning: no files found matching '*.txt' 06:26:07 INFO - no previously-included directories found matching 'build' 06:26:07 INFO - no previously-included directories found matching 'dist' 06:26:07 INFO - no previously-included directories found matching '.git*' 06:26:07 INFO - Successfully installed jsonschema functools32 06:26:07 INFO - Cleaning up... 06:26:07 INFO - Return code: 0 06:26:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:08 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 0x108451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef82d83a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10822fd30>, '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 0x108156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108224be8>, '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.oCa0I20s4P/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.YcCG7OEcHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 06:26:08 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 06:26:08 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 06:26:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oCa0I20s4P/Render', 06:26:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:08 INFO - 'HOME': '/Users/cltbld', 06:26:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:08 INFO - 'LOGNAME': 'cltbld', 06:26:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:08 INFO - 'MOZ_NO_REMOTE': '1', 06:26:08 INFO - 'NO_EM_RESTART': '1', 06:26:08 INFO - 'PAGER': '/bin/cat', 06:26:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:08 INFO - 'PWD': '/builds/slave/test', 06:26:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:08 INFO - 'SHELL': '/bin/bash', 06:26:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YcCG7OEcHE/Listeners', 06:26:08 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 06:26:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:08 INFO - 'USER': 'cltbld', 06:26:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:08 INFO - 'XPC_FLAGS': '0x0', 06:26:08 INFO - 'XPC_SERVICE_NAME': '0', 06:26:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:26:08 INFO - Cleaning up... 06:26:08 INFO - Return code: 0 06:26:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:09 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 0x108451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef82d83a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10822fd30>, '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 0x108156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108224be8>, '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.oCa0I20s4P/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.YcCG7OEcHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 06:26:09 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 06:26:09 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 06:26:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oCa0I20s4P/Render', 06:26:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:09 INFO - 'HOME': '/Users/cltbld', 06:26:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:09 INFO - 'LOGNAME': 'cltbld', 06:26:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:09 INFO - 'MOZ_NO_REMOTE': '1', 06:26:09 INFO - 'NO_EM_RESTART': '1', 06:26:09 INFO - 'PAGER': '/bin/cat', 06:26:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:09 INFO - 'PWD': '/builds/slave/test', 06:26:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:09 INFO - 'SHELL': '/bin/bash', 06:26:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YcCG7OEcHE/Listeners', 06:26:09 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 06:26:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:09 INFO - 'USER': 'cltbld', 06:26:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:09 INFO - 'XPC_FLAGS': '0x0', 06:26:09 INFO - 'XPC_SERVICE_NAME': '0', 06:26:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:09 INFO - Downloading/unpacking blobuploader==1.2.4 06:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:26:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:26:10 INFO - Downloading blobuploader-1.2.4.tar.gz 06:26:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:26:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:26:11 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 06:26:11 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 06:26:11 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 06:26:11 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 06:26:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:26:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:26:11 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 06:26:11 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 06:26:11 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 06:26:11 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 06:26:11 INFO - Downloading docopt-0.6.1.tar.gz 06:26:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:26:11 INFO - Installing collected packages: blobuploader, requests, docopt 06:26:11 INFO - Running setup.py install for blobuploader 06:26:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:26:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:26:12 INFO - Running setup.py install for requests 06:26:12 INFO - Running setup.py install for docopt 06:26:12 INFO - Successfully installed blobuploader requests docopt 06:26:12 INFO - Cleaning up... 06:26:12 INFO - Return code: 0 06:26:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:26:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:12 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 0x108451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef82d83a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10822fd30>, '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 0x108156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108224be8>, '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.oCa0I20s4P/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.YcCG7OEcHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 06:26:12 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 06:26:12 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 06:26:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oCa0I20s4P/Render', 06:26:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:12 INFO - 'HOME': '/Users/cltbld', 06:26:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:12 INFO - 'LOGNAME': 'cltbld', 06:26:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:12 INFO - 'MOZ_NO_REMOTE': '1', 06:26:12 INFO - 'NO_EM_RESTART': '1', 06:26:12 INFO - 'PAGER': '/bin/cat', 06:26:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:12 INFO - 'PWD': '/builds/slave/test', 06:26:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:12 INFO - 'SHELL': '/bin/bash', 06:26:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YcCG7OEcHE/Listeners', 06:26:12 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 06:26:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:12 INFO - 'USER': 'cltbld', 06:26:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:12 INFO - 'XPC_FLAGS': '0x0', 06:26:12 INFO - 'XPC_SERVICE_NAME': '0', 06:26:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:26:13 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-7V2tDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:26:13 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-fKb_9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:26:13 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-PHEsWH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:26:13 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-FVto9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:26:13 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-X_hiih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:26:13 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-dahlHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:26:13 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-aVhSQo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:26:13 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-KmUgFK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:26:13 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-7eAy5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:26:13 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-m3Dedz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:26:14 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ReXgMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:26:14 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-g4XkE2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:26:14 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-EfISC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:26:14 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-FejXDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:26:14 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ZKQRb5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:26:14 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-z10n0U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:26:14 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-SVg3Rd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:26:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:26:14 INFO - Running setup.py install for manifestparser 06:26:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:26:14 INFO - Running setup.py install for mozcrash 06:26:15 INFO - Running setup.py install for mozdebug 06:26:15 INFO - Running setup.py install for mozdevice 06:26:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:26:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:26:15 INFO - Running setup.py install for mozfile 06:26:15 INFO - Running setup.py install for mozhttpd 06:26:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:26:15 INFO - Running setup.py install for mozinfo 06:26:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:26:15 INFO - Running setup.py install for mozInstall 06:26:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:26:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:26:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:26:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:26:15 INFO - Running setup.py install for mozleak 06:26:16 INFO - Running setup.py install for mozlog 06:26:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:26:16 INFO - Running setup.py install for moznetwork 06:26:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:26:16 INFO - Running setup.py install for mozprocess 06:26:16 INFO - Running setup.py install for mozprofile 06:26:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:26:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:26:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:26:16 INFO - Running setup.py install for mozrunner 06:26:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:26:16 INFO - Running setup.py install for mozscreenshot 06:26:17 INFO - Running setup.py install for moztest 06:26:17 INFO - Running setup.py install for mozversion 06:26:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:26:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:26:17 INFO - Cleaning up... 06:26:17 INFO - Return code: 0 06:26:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:26:17 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')]} 06:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:17 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')]} 06:26:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:17 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 0x108451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef82d83a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10822fd30>, '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 0x108156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108224be8>, '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.oCa0I20s4P/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.YcCG7OEcHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 06:26:17 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 06:26:17 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 06:26:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oCa0I20s4P/Render', 06:26:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:17 INFO - 'HOME': '/Users/cltbld', 06:26:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:17 INFO - 'LOGNAME': 'cltbld', 06:26:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:17 INFO - 'MOZ_NO_REMOTE': '1', 06:26:17 INFO - 'NO_EM_RESTART': '1', 06:26:17 INFO - 'PAGER': '/bin/cat', 06:26:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:17 INFO - 'PWD': '/builds/slave/test', 06:26:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:17 INFO - 'SHELL': '/bin/bash', 06:26:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YcCG7OEcHE/Listeners', 06:26:17 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 06:26:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:17 INFO - 'USER': 'cltbld', 06:26:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:17 INFO - 'XPC_FLAGS': '0x0', 06:26:17 INFO - 'XPC_SERVICE_NAME': '0', 06:26:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:26:17 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-RvpZlJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:26:17 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)) 06:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:26:17 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-JtTkLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:26:17 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)) 06:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:26:17 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-4t5JtP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:26:17 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)) 06:26:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:26:18 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-veIaGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:26:18 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)) 06:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:26:18 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-GQJ0u0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:26:18 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)) 06:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:26:18 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-zeUGOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:26:18 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)) 06:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:26:18 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-DQsYs2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:26:18 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)) 06:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:26:18 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ZXfvIT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:26:18 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)) 06:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:26:18 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-NypN4l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:26:18 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)) 06:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:26:18 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-oMPXXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:26:18 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)) 06:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:26:18 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-kWCep7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:26:18 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)) 06:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:26:18 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-2aSpTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:26:18 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)) 06:26:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:26:18 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-xnv_Vk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:26:19 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)) 06:26:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:26:19 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-GQW9RG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:26:19 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)) 06:26:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:26:19 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-wXhHSn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:26:19 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)) 06:26:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:26:19 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-Rmlili-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:26:19 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)) 06:26:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:26:19 INFO - Running setup.py (path:/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/pip-ecdTvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:26:19 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)) 06:26:19 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)) 06:26:19 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)) 06:26:19 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)) 06:26:19 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)) 06:26:19 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)) 06:26:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:26:19 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 06:26:19 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 06:26:19 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 06:26:19 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 06:26:20 INFO - Downloading blessings-1.6.tar.gz 06:26:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:26:21 INFO - Installing collected packages: blessings 06:26:21 INFO - Running setup.py install for blessings 06:26:21 INFO - Successfully installed blessings 06:26:21 INFO - Cleaning up... 06:26:21 INFO - Return code: 0 06:26:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:26:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:26:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:26:21 INFO - Reading from file tmpfile_stdout 06:26:21 INFO - Current package versions: 06:26:21 INFO - blessings == 1.6 06:26:21 INFO - blobuploader == 1.2.4 06:26:21 INFO - docopt == 0.6.1 06:26:21 INFO - functools32 == 3.2.3-2 06:26:21 INFO - jsonschema == 2.5.1 06:26:21 INFO - manifestparser == 1.1 06:26:21 INFO - mozInstall == 1.12 06:26:21 INFO - mozcrash == 1.0 06:26:21 INFO - mozdebug == 0.1 06:26:21 INFO - mozdevice == 0.49 06:26:21 INFO - mozfile == 1.2 06:26:21 INFO - mozhttpd == 0.7 06:26:21 INFO - mozinfo == 0.9 06:26:21 INFO - mozleak == 0.1 06:26:21 INFO - mozlog == 3.4 06:26:21 INFO - moznetwork == 0.27 06:26:21 INFO - mozprocess == 0.25 06:26:21 INFO - mozprofile == 0.28 06:26:21 INFO - mozrunner == 6.13 06:26:21 INFO - mozscreenshot == 0.1 06:26:21 INFO - mozsystemmonitor == 0.3 06:26:21 INFO - moztest == 0.8 06:26:21 INFO - mozversion == 1.4 06:26:21 INFO - psutil == 3.1.1 06:26:21 INFO - requests == 1.2.3 06:26:21 INFO - wsgiref == 0.1.2 06:26:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:21 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 0x108451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef82d83a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10822fd30>, '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 0x108156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108224be8>, '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.oCa0I20s4P/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.YcCG7OEcHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 06:26:21 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 06:26:21 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 06:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oCa0I20s4P/Render', 06:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:21 INFO - 'HOME': '/Users/cltbld', 06:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:21 INFO - 'LOGNAME': 'cltbld', 06:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:21 INFO - 'MOZ_NO_REMOTE': '1', 06:26:21 INFO - 'NO_EM_RESTART': '1', 06:26:21 INFO - 'PAGER': '/bin/cat', 06:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:21 INFO - 'PWD': '/builds/slave/test', 06:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:21 INFO - 'SHELL': '/bin/bash', 06:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YcCG7OEcHE/Listeners', 06:26:21 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 06:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:21 INFO - 'USER': 'cltbld', 06:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:21 INFO - 'XPC_FLAGS': '0x0', 06:26:21 INFO - 'XPC_SERVICE_NAME': '0', 06:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:21 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)) 06:26:21 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)) 06:26:21 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)) 06:26:21 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)) 06:26:21 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)) 06:26:21 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)) 06:26:21 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)) 06:26:21 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)) 06:26:21 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)) 06:26:21 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)) 06:26:21 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)) 06:26:21 INFO - Cleaning up... 06:26:21 INFO - Return code: 0 06:26:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:26:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:26:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:26:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108451b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108454768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef82d83a30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10822fd30>, '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 0x108156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108224be8>, '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.oCa0I20s4P/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.YcCG7OEcHE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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 06:26:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:26:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:26:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oCa0I20s4P/Render', 06:26:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:21 INFO - 'HOME': '/Users/cltbld', 06:26:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:21 INFO - 'LOGNAME': 'cltbld', 06:26:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:21 INFO - 'MOZ_NO_REMOTE': '1', 06:26:21 INFO - 'NO_EM_RESTART': '1', 06:26:21 INFO - 'PAGER': '/bin/cat', 06:26:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:21 INFO - 'PWD': '/builds/slave/test', 06:26:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:21 INFO - 'SHELL': '/bin/bash', 06:26:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YcCG7OEcHE/Listeners', 06:26:21 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 06:26:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:21 INFO - 'USER': 'cltbld', 06:26:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:21 INFO - 'XPC_FLAGS': '0x0', 06:26:21 INFO - 'XPC_SERVICE_NAME': '0', 06:26:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:26:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:26:22 INFO - Cleaning up... 06:26:22 INFO - Return code: 0 06:26:22 INFO - Running post-action listener: _resource_record_post_action 06:26:22 INFO - Running post-action listener: _start_resource_monitoring 06:26:22 INFO - Starting resource monitoring. 06:26:22 INFO - [mozharness: 2017-05-25 13:26:22.102511Z] Finished create-virtualenv step (success) 06:26:22 INFO - [mozharness: 2017-05-25 13:26:22.103450Z] Running install step. 06:26:22 INFO - Running pre-action listener: _resource_record_pre_action 06:26:22 INFO - Running main action method: install 06:26:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:26:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:26:22 INFO - Reading from file tmpfile_stdout 06:26:22 INFO - Detecting whether we're running mozinstall >=1.0... 06:26:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:26:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:26:22 INFO - Reading from file tmpfile_stdout 06:26:22 INFO - Output received: 06:26:22 INFO - Usage: mozinstall [options] installer 06:26:22 INFO - Options: 06:26:22 INFO - -h, --help show this help message and exit 06:26:22 INFO - -d DEST, --destination=DEST 06:26:22 INFO - Directory to install application into. [default: 06:26:22 INFO - "/builds/slave/test"] 06:26:22 INFO - --app=APP Application being installed. [default: firefox] 06:26:22 INFO - mkdir: /builds/slave/test/build/application 06:26:22 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'] 06:26:22 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 06:26:42 INFO - Reading from file tmpfile_stdout 06:26:42 INFO - Output received: 06:26:42 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 06:26:42 INFO - Running post-action listener: _resource_record_post_action 06:26:42 INFO - [mozharness: 2017-05-25 13:26:42.677387Z] Finished install step (success) 06:26:42 INFO - [mozharness: 2017-05-25 13:26:42.677537Z] Running run-tests step. 06:26:42 INFO - Running pre-action listener: _resource_record_pre_action 06:26:42 INFO - Running main action method: run_tests 06:26:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:26:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:26:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:26:42 INFO - 2017-05-25 06:26:42.692 screenresolution[2278:19754] starting screenresolution argv=screenresolution get 06:26:42 INFO - 2017-05-25 06:26:42.695 screenresolution[2278:19754] Display 0: 1600x1200x32@60 06:26:42 INFO - 2017-05-25 06:26:42.701 screenresolution[2279:19757] starting screenresolution argv=screenresolution list 06:26:42 INFO - Available Modes on Display 0 06:26:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:26:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:26:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:26:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:26:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:26:42 INFO - Intel Iris: 06:26:42 INFO - Chipset Model: Intel Iris 06:26:42 INFO - Type: GPU 06:26:42 INFO - Bus: Built-In 06:26:42 INFO - VRAM (Dynamic, Max): 1536 MB 06:26:42 INFO - Vendor: Intel (0x8086) 06:26:42 INFO - Device ID: 0x0a2e 06:26:42 INFO - Revision ID: 0x0009 06:26:42 INFO - Displays: 06:26:42 INFO - Display: 06:26:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:26:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:26:42 INFO - Display Serial Number: 1600x1200 60 06:26:42 INFO - Main Display: Yes 06:26:42 INFO - Mirror: Off 06:26:42 INFO - Online: Yes 06:26:42 INFO - Rotation: Supported 06:26:42 INFO - Return code: 0 06:26:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:26:42 INFO - Python 2.7.3 06:26:42 INFO - Return code: 0 06:26:42 INFO - grabbing minidump binary from tooltool 06:26: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')]} 06:26:42 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 0x10822fd30>, '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 0x108156db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108224be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:26:42 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 06:26:42 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 06:26:42 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 06:26:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:26:43 INFO - Return code: 0 06:26:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:26:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:26:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:26:43 INFO - ENV: RUST_BACKTRACE is now 1 06:26:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:26:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:26:43 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/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--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 06:26:43 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/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0096 --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 06:26:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oCa0I20s4P/Render', 06:26:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:26:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:26:43 INFO - 'HOME': '/Users/cltbld', 06:26:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:26:43 INFO - 'LOGNAME': 'cltbld', 06:26:43 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:26:43 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:26:43 INFO - 'MOZ_NO_REMOTE': '1', 06:26:43 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:26:43 INFO - 'NO_EM_RESTART': '1', 06:26:43 INFO - 'PAGER': '/bin/cat', 06:26:43 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:26:43 INFO - 'PWD': '/builds/slave/test', 06:26:43 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:26:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:26:43 INFO - 'RUST_BACKTRACE': '1', 06:26:43 INFO - 'SHELL': '/bin/bash', 06:26:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YcCG7OEcHE/Listeners', 06:26:43 INFO - 'TMPDIR': '/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/', 06:26:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:26:43 INFO - 'USER': 'cltbld', 06:26:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:26:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:26:43 INFO - 'XPC_FLAGS': '0x0', 06:26:43 INFO - 'XPC_SERVICE_NAME': '0', 06:26:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:26:43 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/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0096', '--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 06:26:43 INFO - mozversion application_buildid: 20170525051644 06:26:43 INFO - mozversion application_changeset: 02e5b298abedc3fd641a8db70efd59009109ed82 06:26:43 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:26:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:26:43 INFO - mozversion application_name: Firefox 06:26:43 INFO - mozversion application_remotingname: firefox-dev 06:26:43 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 06:26:43 INFO - mozversion application_vendor: Mozilla 06:26:43 INFO - mozversion application_version: 54.0 06:26:43 INFO - mozversion platform_buildid: 20170525051644 06:26:43 INFO - mozversion platform_changeset: 02e5b298abedc3fd641a8db70efd59009109ed82 06:26:43 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 06:26:43 INFO - mozversion platform_version: 54.0 06:26:43 INFO - using testdate: 1495718803 06:26:43 INFO - actual date: 1495718803 06:26:43 INFO - starting webserver on 'localhost:49806' 06:26:43 INFO - SUITE-START | Running 2 tests 06:26:43 INFO - TEST-START | dromaeo_css 06:26:43 INFO - operating with platform_type : mac_ 06:26:43 INFO - Initialising browser for dromaeo_css test... 06:26:43 INFO - TEST-INFO | started process 2289 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpbqIxc3/profile http://localhost:49806/getInfo.html) 06:26:46 INFO - PROCESS | 2289 | 2017-05-25 06:26:46.135 plugin-container[2292:19926] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c47, name = 'com.apple.tsm.portname' 06:26:46 INFO - PROCESS | 2289 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:26:46 INFO - PROCESS | 2289 | 2017-05-25 06:26:46.136 plugin-container[2292:19926] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9303, name = 'com.apple.CFPasteboardClient' 06:26:46 INFO - PROCESS | 2289 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:26:46 INFO - PROCESS | 2289 | 2017-05-25 06:26:46.136 plugin-container[2292:19926] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:26:46 INFO - PROCESS | 2289 | __metrics Screen width/height:1600/1200 06:26:46 INFO - PROCESS | 2289 | colorDepth:24 06:26:46 INFO - PROCESS | 2289 | Browser inner width/height: 1024/689 06:26:46 INFO - PROCESS | 2289 | __metrics 06:26:46 ERROR - PROCESS | 2289 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 06:26:46 INFO - TEST-INFO | 2289: exit 0 06:26:46 INFO - Browser initialized. 06:26:46 INFO - Running cycle 1/1 for dromaeo_css test... 06:26:46 INFO - TEST-INFO | started process 2293 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpbqIxc3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:26:49 INFO - PROCESS | 2293 | 2017-05-25 06:26:49.398 plugin-container[2294:20053] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4247, name = 'com.apple.tsm.portname' 06:26:49 INFO - PROCESS | 2293 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:26:49 INFO - PROCESS | 2293 | 2017-05-25 06:26:49.398 plugin-container[2294:20053] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 06:26:49 INFO - PROCESS | 2293 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:26:49 INFO - PROCESS | 2293 | 2017-05-25 06:26:49.399 plugin-container[2294:20053] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:26:59 INFO - PROCESS | 2293 | RSS: Main: 202969088 06:26:59 INFO - PROCESS | 2293 | 06:30:02 INFO - PROCESS | 2293 | Cycle 1(1): loaded http://localhost:49806/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49806/tests/dromaeo/cssquery-ext.html) 06:30:03 INFO - PROCESS | 2293 | RSS: Main: 219553792 06:30:03 INFO - PROCESS | 2293 | 06:33:06 INFO - PROCESS | 2293 | Cycle 1(1): loaded http://localhost:49806/tests/dromaeo/cssquery-ext.html (next: http://localhost:49806/tests/dromaeo/cssquery-jquery.html) 06:33:06 INFO - PROCESS | 2293 | RSS: Main: 230092800 06:33:06 INFO - PROCESS | 2293 | 06:36:08 INFO - PROCESS | 2293 | Cycle 1(1): loaded http://localhost:49806/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49806/tests/dromaeo/cssquery-mootools.html) 06:36:09 INFO - PROCESS | 2293 | RSS: Main: 231452672 06:36:09 INFO - PROCESS | 2293 | 06:39:11 INFO - PROCESS | 2293 | Cycle 1(1): loaded http://localhost:49806/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49806/tests/dromaeo/cssquery-prototype.html) 06:39:11 INFO - PROCESS | 2293 | RSS: Main: 234676224 06:39:11 INFO - PROCESS | 2293 | 06:42:14 INFO - PROCESS | 2293 | Cycle 1(1): loaded http://localhost:49806/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49806/tests/dromaeo/cssquery-yui.html) 06:42:15 INFO - PROCESS | 2293 | RSS: Main: 236818432 06:42:15 INFO - PROCESS | 2293 | 06:45:17 INFO - PROCESS | 2293 | Cycle 1(1): loaded http://localhost:49806/tests/dromaeo/cssquery-yui.html (next: http://localhost:49806/tests/dromaeo/cssquery-dojo.html) 06:45:18 INFO - PROCESS | 2293 | RSS: Main: 234885120 06:45:18 INFO - PROCESS | 2293 | 06:45:18 INFO - PROCESS | 2293 | __start_tp_report 06:45:18 INFO - PROCESS | 2293 | _x_x_mozilla_page_load 06:45:18 INFO - PROCESS | 2293 | _x_x_mozilla_page_load_details 06:45:18 INFO - PROCESS | 2293 | |i|pagename|runs| 06:45:18 INFO - PROCESS | 2293 | |0;dojo.html;2714.809963302569;3444.569428821399;3449.9482507762386;3458.118179864134;3472.7221822254214;5927.40725927407;5971.4625683688455;5984.730687119079;5992.071228959513;6026.4576188143055;5972.372900845414;5994.340622531518;6029.427204415573;6042.728077236524;6045.27456705196;5386.030514507397;5446.482584154499;5471.972640136813;5483.232347471358;5511.669299842002;4547.272436410175;5490.752916118764;5497.642653227527;5498.1752737089555;5502.147167189072;5313.601479889005;5338.27933229013;5448.782048718046;5491.890162196753;5539.639923404986;4453.487848897385;5431.864203394907;5467.343918729778;5491.533219676332;5546.195801608744;4319.719218250803;5433.347998240197;5444.101723215705;5444.346678398618;5482.451754824526;5708.857278568027;6043.335233124397;6076.878462430771;6082.422169893853;6086.330503644595;6262.749490020439;6275.184226050585;6283.151774510428;6298.68506574674;6303.653299068549;6472.190976127984;6488.21006967004;6488.5133614978595;6494.447971922392;6511.837204069889;6073.362296958756;6083.6349819011;6098.146259523626;6108.205933228696;6144.692765361714;6293.37066293367;6340.207474065742;6355.428011478989;6538.1827271591055;6546.509011824132;6229.813105606839;6231.906521402183;6233.625982441068;6251.71867265965;6280.2777680566405;6378.77674281398;6415.967920160369;6423.42189202974;6434.871302573923;6447.580907241015;6166.136740856284;6239.282482514478;6294.905576416358;6324.3043265240785;6338.080978258126;5201.5838732901275;6194.5044396448175;6287.402696743802;6321.494280457552;6332.841678958063;4832.613390928787;4864.343313652683;4895.31465594817;4904.5340692634145;4907.288443175719;4378.868633940987;4392.582704643054;4411.2941929291155;4416.933745993812;4420.889477763018;5691.630044047124;5710.971445142748;5857.58996870215;5887.499562537232;5963.582549221513;6304.716287767062;6317.4630156437215;6335.556511044275;6413.551051426355;6414.583052101599;5863.355030946593;5894.469497745138;5903.822885313448;5911.231539899785;5953.077273022688;5383.596230282745;5545.140503222007;5567.081431563746;5570.164475328733;5570.220169176318;5444.564434845282;6342.809715708536;6481.157449531531;6501.024846273097;6583.901241481477;5331.013762453958;6422.229332480132;6446.8388290293115;6451.096846441408;6463.741450341934;6486;6560.507961902972;6607.9669601651685;6621.841177793963;6631.436327912182;6169.198004259418;6214.8135555933395;6218.81343559694;6241.344658810941;6341.175647165839;5829.358770535323;6018.277806663228;6373.521985850987;6382.489400848014;6393.392627700361;6267.247930248217;6281.780137695158;6283.560150789401;6286.717097730704;6300;5252.816151434681;5425.050616142081;6115.179495343419;6157.630542167305;6207.34822843613;4828.444728856227;6086.3305036446845;6223.159873417212;6233.314335423191;6238.220222472191;6125.295591007091;6260.530460215593;6272;6276.215473065867;6282.654454004891;6078.149059131637;6108.645631960441;6116.092308610985;6120.540959427972;6124.228368151087 06:45:18 INFO - PROCESS | 2293 | |1;ext.html;684.9383555480006;726.7093162734909;793.8888555602215;961.7114865540336;974.4056125763278;39242.803785981065;42232;42279.78860105703;42408.15183696332;42462.15075698484;32420.513692294633;40731;40821.38767918484;40986.7950660247;41106.58893411065;8131.837363252732;8265.421420500568;9640.37337573059;11214.943925280362;11340.319580825177;11007.559697612085;11126;11133.665990020312;11149.99650031497;11151.219414640978;11187.440627968568;11189.104871610252;11212.495437705324;11223.214374993753;11267.154963377738;11077.003069723723;11114.166437517219;11121.332720036824;11143.219974601781;11175.21773475857;10936.125109991182;11009.449527523591;11014.17393695468;11025.669229923114;11038.282511636718;35397.64602353995;35549.933501994725;35746.106347341265;35794;35822.462663060076;3784.8675296364495;3809.3350510355544;3841.635044670752;3853.6339047790416;3908.9609103908883;25808.612870806952;26033.609495857578;26136.738632613622;27415.862920685267;28383.85808070967;3036.4693970737185;3077.430675325072;3123.109913674592;3145.952810707818;3264.60824701035;384021.9999999972;384647;386168;386207;386464;45901.770491147334;45970;46086;46118;46188;48189.55431337065;48222.31221907216;48265.75867120642;48308.27537586939;48343.274850877264;13980.370883310276;14053.859461405254;14288.928555357365;14320.92593055525;14326;12183.512659493697;12333.62999110028;12336.876631233752;12338.951189148844;12346.506139754489;12223.449944752507;12339.876601233873;12376.62870113898;12397.00823934083;12551.807578280048;5116.386033675983;5735.766810135758;5778.595498315161;5846.824595262149;5865;924.8057907839294;941.3269512298739;988.9505524723735;1117.1621284036971;1184.8518935133109;27500;29104.7089529102;29881.402371952438;30149.794008239423;30383;848.5460278750838;1027.9537420816082;1061.628430049492;1064.5741703318733;1071.981617463413;11476.02453791437;15143.848561514464;15176.544703658908;15190.669087861292;15565.766513502307;499744;501703;501786;503895;504760;60019.09971350433;60055;60184;60215.49461263503;60282;1179.622520793355;1202.807550791869;1427.643089227693;1454.6217983324411;1473.8820894328214;29534.704652954057;30308.393832122794;30661;30665;30936.917207897615;46752;47027.76486117548;47047.764761175975;47089.058218836115;47090.76454617705;442181;445303;445339;446042;446280;32792.01623951285;33145.83427082849;33400.99797006094;33523;33539.4969075469;44870;45579;46489.07021859611;46492.7675361621;46687;30972;31073.53389699111;31076;31823.36353272968;31950;31056.378872421974;31511.21221969469;32152.51771223387;32309.030729078164;32374;36286.455703165004;36838.44742328813;37558.87323380303;37583;37601.62398376091;31112.377752445278;31942.201444964063;32055.519167212977;32147.839260803546;33223.501647474826;19143.617127657646;24768.628470572596;24931.87534062318;25126.623100653138;25143.371415714755 06:45:18 INFO - PROCESS | 2293 | |2;jquery.html;367.19034528863847;406.3153586207241;410.81513319006456;446.0677184684011;446.92849144136943;20064.799352006496;20261.290854820083;21137.15451381943;21471.355859324245;21636;17934.91032544839;19111.61776764464;20862.06120724567;21985.67021494675;23086.076556937704;5903.940960590382;6583.5062370322175;6774.729010839561;6833.111695479581;6864.725410983555;6516.185476815398;6623.801285961429;6648.335166483362;6660.100886380349;6684.649582896243;6633.601983880959;6657.300983396746;6660.333966603349;6667.699953502104;6682.866342673144;7331.486795924287;7695.461317707763;7784.9610751945875;7835.216478352119;7855.450118491708;6982.860342793167;7301.1968683444775;7365.521241119363;7384.96610474534;7551.69793208271;25878.835452404688;25919.611205831927;26532.33669158267;26596.60105098425;26996.92012319505;2189.704389907358;2512.133314006665;2555.2334299710196;2594.0661361909697;2611.6343711880354;15413.229338533029;15795.131267780267;16090.356385744557;16700.49898503047;17217.397391091377;2097.7902209779054;2102.5899949509853;2121.745390553128;2129.708691594352;2153.601583707019;165316;169867;170252;171107;174168.99999999872;21070.36788896305;21273.893630532057;21434.249801257192;21519.031643576076;21687.34937951864;32390.514142287884;32926.67073329236;33299.16752081169;33347.499787503206;33404.331913361595;10208.285420020677;10504.474776261157;10682.893171068345;10767.300125491975;10787.946060269649;8772.95613521941;8777.561121944005;8849.247813935759;9014.41406308601;9044;9556.474393908402;9837.262205334628;9870.30907836459;9898.703038908845;9930.652427165014;3950.7234493585747;4016.2770701273466;4041.131156801243;4048.959510404858;4056.9797151014645;829.9875501867476;1013.5084484025242;1014.6702321745462;1021.918246540275;1053.4785281285813;21160.5187636867;21338.57322853534;21503;21685.783142168377;22842.429353439995;1075.4353964168874;1081.4214395298634;1087.445402844539;1124.6288724720987;1126.7408496045955;9520;10329.070383665507;11320.943395282971;11385.544578216945;11431.885681143249;763025;804860;810055;818676;820567;116303;132557;147592;162892.18553907154;166655;1231.4520038582746;1261.9116661833584;1293.088372697246;1294.0488740775334;1294.0747365633617;19224.423267302005;19620;19696.90151549233;19767;19950.800491995476;62808;62941;63679.68160159169;64131.374243734244;64135;86315.70526442234;90583.18833623422;91003.63494547455;92295;92806;11337.886621133683;11691.824622630838;11722.882771172179;11847.289162650268;11958.10314226454;12751.234925904475;12929.094963352474;13034.022448316224;13117.147385419918;13385.598432047054;11262.943685281849;11398.715032124263;11468.139889508153;11497.885021149681;11632.476538555617;10206.285560010729;10267.383956962607;10267.794644106927;10319.845202322122;10454.895451045697;9680.85478717834;11621.302721836717;11834.76330473403;11879.139279734294;11885.346305953255;10957.890421095686;11560.30638161713;11681.948624623825;11794.646160615195;11995.340256285988;9454.290928180277;11493.54025838957;11873.703157421134;11884.405779710807;11901.523939042687 06:45:18 INFO - PROCESS | 2293 | |3;mootools.html;1593.936242550298;1600.6474529022978;1616.8059832820056;1625.8699304055676;1628.6091338078857;9458.527073646308;9642.662506812263;10607.416592087433;11336.376499292535;11356.148288878336;10779.46102694866;11069.667909962694;11114.722131946706;11119.221654484189;11126.276792008533;2041.6631255842767;2405.26639374991;2452.141750387368;2457.913973010945;2463.310273123528;2410.240774156135;2421.6367544868235;2441.7924476419525;2443.926682199537;2447.4248551590363;2404.5791986402396;2422.2733180046002;2429.1133736186252;2432.3189506937997;2439.987800061006;2387.4508862961616;2390.438247011951;2394.5450364430703;2409.49400625867;2411.577973854586;2365.5741966446035;2372.3357459911253;2372.5610762009082;2379.976200237993;2403.495265994143;10904.563817447372;11006.284591501526;11009.779804403866;11028.22802403824;11145.777084458347;5910.763569457216;6065.4541091302;6102.26772787264;6125.111858780452;6126.264848218198;2920.8462657250266;3032.454158251514;3525.700315473188;3662.0661731258624;3712;1179.9941000294943;1184.2953442701578;1229.7909355409513;1241.3420886929941;1244.2037096258402;15434.151121688303;15581;15611.531654050397;15633.202181749017;15661.13863737494;4767.6662633615315;5070.746462676925;5132;5149.562287205555;5160.535551800356;13332.866671333357;13433.328333583282;13443.932780336232;13446.394912228972;13447.663808404672;5939.46544810969;5981.641101533922;6046.969765151234;6048.697565121726;6099.8475038123515;4283.4579551361485;5377.838664840061;5391.272178255964;5436.809711660152;5500.50495455411;4167.4790651168605;4227.830886764556;4254.340577210537;4288.978555107205;4302.096559722493;1011.610529945976;1171.630936255094;1172.1150531348776;1188.2692144331174;1189.3339729751367;885.5970533407414;916.4913473022403;941.3833938770116;958.6932181701789;962.2398305338844;6021.157038014681;6217.502599792006;6393.328700486475;6843.144606924135;6856.211535673362;1008.1078245752561;1013.1540163963173;1019.8215312320315;1023.2837014090018;1024.7130803375067;4350.347447882843;4381.430414046154;4398.780060996937;4505.729656220572;4520.32195170727;13563.525276615468;13937.303134843218;14075.929620351833;14103.0832995857;14129.576112716635;13505.864941350657;13580.049396542345;13970.022098453213;13981.300934953211;14084.647883802987;10153.187744980532;10237.283390162616;10331.896681033244;10457.633982810565;10485.528151233213;6712.362325579063;6780.118584583973;6821.386075253251;6823.317668233137;6924.376806087476;12419.93790031044;12554.623361299176;12601.306928118674;12605.684857878627;12681.619551413472;10835.67492975212;12579.685507862378;12686.365681716063;12785.232886026868;12886.09797314179;8128.309093727099;8365.372597055124;9117.77205569866;9283.582238799136;9491.762705932408;10303.53634086453;10706.089982351587;10772.084372828398;10774.784504310026;10778.514966826355;8059.838803223785;8904.19862212404;9069.319801014819;9118.863217051618;9240.353175277934;8783.780405489913;8835.602397891982;8842.734717958472;8914.37599368038;8966;7916.84166316682;8161.551114688749;8179.141190174946;8272.214139656722;8307.584620769057;6020.969895150496;6118.959776837859;6128.540359472968;6194.225721784777;6260.154879091447;4995.550400463976;6117.174181485442;6290.119383286435;6318.0838778375955;6353.269374021862 06:45:18 INFO - PROCESS | 2293 | |4;prototype.html;379.48200706036266;379.92021675448154;389.7934094929688;390.73820540238046;396.6311330462669;4770;5025.9748701256485;5071.7210553419545;5216.26972223889;5336.199570064492;4623.606993405556;5094.923576146361;5232.502912223342;5265.526102650761;5279.855313578842;4444.710913923689;4561.2245918193985;4576.176288268102;4593.379893714339;4602.516735742749;3797.7531460454984;4058.6144316289906;4213.599708027732;4505.707129036618;4524.5701658342405;3998.440218369416;4445.666575006871;4615.030843522847;4629.282461218517;4641.25739881619;3743.9251214975548;4155.916881662365;4184.958150418503;4411.42651455312;4561.977190114061;4332.176886391607;4354.629856462205;4359.237133501624;4371.535535595579;4383.9342409863875;5121.795128194868;5186.861948521128;5203.219517072432;5225.111731005738;5258.684478931276;4300.247841381518;5120.18077107661;5270.1040823060175;5364.758585863646;5425.83722488326;4682.180618391768;5239.502247286502;5650.971745141289;5655.943440565583;5674.716264186815;4412.75729834859;4916.754162291871;4928.778204980785;4931.26031095335;4945.357103576512;5581.186114512759;5839.416058394127;5854.151148083547;5873.676947767871;5976.372480889488;4249.660027197817;5048.3184770056305;5062.670926389773;5076.796928122833;5096.898062038813;4458.420405347349;4624.722516649012;5197.272381866542;5219;5225.301776922515;4684.672072954929;4722;4798.664093513421;4977.352944117241;5046.965372098711;4654.511276316006;5021.292760461461;5296.735163241822;5649.661020338793;5721.828345149652;1496.7829664698775;1650.3893559382866;1664.7193345117266;1780.3857669104138;1787.4012205911165;1212.7210741529498;1402.593247958101;1403.3474434388058;1459.3360021190333;1465.7654775236124;4768.8005592730005;4987.401511818605;4993.425756038029;5005.299258103795;5018.3476148101245;5147.782635852592;5557.638753481092;5780.081059065145;5812.534997200299;5839.248225532323;4074.6757303876357;4511.172974945152;5013.774380152829;5033.219850923113;5042.445331013586;2615.686117665892;2655.548556745358;2855.4289142171647;2987.9542160244177;3018.894338698179;5661.971690141523;5960.850978725566;6030.758769649165;6067.302260240129;6072.939270607326;4901.6323775716255;5189.922151167735;5305;5421.186821976735;5564.577784662403;5250.658707184097;5259.552938000236;5571.164325351229;5922.318933322636;6006.429389208019;5145.536901678867;5177.534021938043;5561.777528898799;5846.1815345852465;5852.94147058524;5302.70835104073;5598.1322894951345;5695.430456954271;5745.2818397700285;5796.304443466811;4990.675606085738;5367.463253674758;5859.267591551056;6056.394360564084;6098.621537600251;3812.7976439648237;4557.270836666117;4573.513608077322;4659.790309436015;4685.008871229782;5677.630953987978;5721.685307307972;5753.1370294456165;5811.634265947414;5829.737661805144;5409.810656626999;5618.85952851182;5624.915626265528;5657.264555607745;5682.107756187025;5342.0384330820825;5456.0358271021605;5561.054620714407;5624.678200622932;5707.600467967202;4871.95128048715;5010.749462526786;5498.752556135064;5504.614676972734;5529.723513824373;5387.515123638891;5561.416051314553;5678.318601767649;5699.5155411790465;5742.36833948257;5177.534021937893;5407.567394608501;5465.808696695596;5506;5536.339098270422 06:45:18 INFO - PROCESS | 2293 | |5;yui.html;716.8136284566011;729.18331468755;772.1197834468707;838.1953324808184;856.0968178571607;15255.542333729993;16033.599160021005;16358.509244722678;16518.256678449485;16604.83395166048;14739.926300368483;14772;14861.256937153126;14928.253587320645;15052.849471505282;947.7014740356783;977.1986970684042;989.787195752913;995.8854731705837;997.8652881860964;956.0821611253206;983.7786498037956;984.8276551603476;987.279286121132;989.6585677941134;1010.3786171504545;1024.677226673599;1027.1577306608583;1027.337367398028;1028.9845652315184;1014.3102690170681;1023.2785885950387;1028.172321281368;1031.2162756305263;1032.6179313653959;756.735142700056;760.6995236881401;766.8772996320617;767.2672597669213;769.7152053740152;16354;16988.49034528966;17011.404600838912;17076.487705368858;17370.478885633453;14706.411743530245;15015.774763378558;15050.623734406618;15121.621959450991;15398.384064637401;11660.883391166064;11762.235454695503;11858.320817211543;11932.343721095336;12076.516939322417;3727.105494681284;3850.5186851643543;3870.0905287257474;3875.283072631544;3960.8375531398437;86171;96600.51699741457;99053.50473247659;99622.5018874901;101878;11500.712482187844;11517.712057198469;11670.649880503599;11712.824307635392;11714.589989350332;25058.997640094556;25307.367315817253;25483.36291592725;25553.744462555136;25564.48871022569;8087.393445491612;8137.186281371934;8155.898953641183;8203.58982050895;8390.328773698211;4083.2037752638544;4382.167388196296;4468.173387923244;4523.36672865799;4528.456585209796;3183.7293830024246;3659.7621154624862;3721.404575267944;3741.120836603396;3744.932694182145;636.3763511758502;664.8637029408959;671.0705672643327;680.9863802723918;719.7912605344392;623.6538721009883;661.976830810929;671.5030877150873;697.476892330752;711.9038929744522;8332.62503187358;10623.628173014062;10654.520546575422;10896.455177241107;10972.835407468894;568.4542838874644;578.2396149063967;581.2182614383647;598.8413070536311;608.8203979825962;4580.4045474088825;5078.695278283315;5162.45794191612;5218.321618189611;5252.894942101213;72570;82540.17459825445;86374.56812715896;88304.55847720848;90441.09558904327;83888.58055709682;85338;85838;88559.55720221357;88587.55706221427;70287.53993610096;74201.62899185577;74657;75858.24141758622;76355.61822190853;8868.556572171237;10056;10061.798764024532;10068.244881634053;10071.949640251752;42792;47865;48574;49864;50225.497745023546;53935.46064539305;63795.36204637894;64176.395590110624;64819.67590162019;64954;10400.375977441377;11504.25222360544;11672.649820505398;11858.584949526725;11864.288142711119;10854.945725271324;12134.75730485403;12858.42137103814;13291.601251962456;13391.129576577492;12154.316842026925;12207.267563946192;12501.874981250436;12517.061220408325;12619.30593817349;11883.82174267404;12197.268163910194;12254.571090011807;12302.077344199042;12598.181118227287;11635.02633933002;12058;12083.154179207371;12095.75808483843;12495.312757798045;8407.80867741789;8733;8734.475931444134;8737.56312184401;9047.140521650434;8976.147266009719;9303.906960930304;10243.59025638996;10267.794644107225;10278.691639250834 06:45:18 INFO - PROCESS | 2293 | __end_tp_report 06:45:18 INFO - PROCESS | 2293 | __start_cc_report 06:45:18 INFO - PROCESS | 2293 | _x_x_mozilla_cycle_collect,375 06:45:18 INFO - PROCESS | 2293 | __end_cc_report 06:45:18 INFO - PROCESS | 2293 | __startTimestamp1495719918199__endTimestamp 06:45:18 INFO - PROCESS | 2293 | 06:45:18 INFO - PROCESS | 2293 | ------- Summary: start ------- 06:45:18 INFO - PROCESS | 2293 | Number of tests: 6 06:45:18 INFO - PROCESS | 2293 | 06:45:18 INFO - PROCESS | 2293 | [#0] dojo.html Cycles:165 Average:5851.45 Median:6098.15 stddev:704.37 (11.6%) stddev-sans-first:662.16 06:45:18 INFO - PROCESS | 2293 | Values: 2714.8 3444.6 3449.9 3458.1 3472.7 5927.4 5971.5 5984.7 5992.1 6026.5 5972.4 5994.3 6029.4 6042.7 6045.3 5386.0 5446.5 5472.0 5483.2 5511.7 4547.3 5490.8 5497.6 5498.2 5502.1 5313.6 5338.3 5448.8 5491.9 5539.6 4453.5 5431.9 5467.3 5491.5 5546.2 4319.7 5433.3 5444.1 5444.3 5482.5 5708.9 6043.3 6076.9 6082.4 6086.3 6262.7 6275.2 6283.2 6298.7 6303.7 6472.2 6488.2 6488.5 6494.4 6511.8 6073.4 6083.6 6098.1 6108.2 6144.7 6293.4 6340.2 6355.4 6538.2 6546.5 6229.8 6231.9 6233.6 6251.7 6280.3 6378.8 6416.0 6423.4 6434.9 6447.6 6166.1 6239.3 6294.9 6324.3 6338.1 5201.6 6194.5 6287.4 6321.5 6332.8 4832.6 4864.3 4895.3 4904.5 4907.3 4378.9 4392.6 4411.3 4416.9 4420.9 5691.6 5711.0 5857.6 5887.5 5963.6 6304.7 6317.5 6335.6 6413.6 6414.6 5863.4 5894.5 5903.8 5911.2 5953.1 5383.6 5545.1 5567.1 5570.2 5570.2 5444.6 6342.8 6481.2 6501.0 6583.9 5331.0 6422.2 6446.8 6451.1 6463.7 6486.0 6560.5 6608.0 6621.8 6631.4 6169.2 6214.8 6218.8 6241.3 6341.2 5829.4 6018.3 6373.5 6382.5 6393.4 6267.2 6281.8 6283.6 6286.7 6300.0 5252.8 5425.1 6115.2 6157.6 6207.3 4828.4 6086.3 6223.2 6233.3 6238.2 6125.3 6260.5 6272.0 6276.2 6282.7 6078.1 6108.6 6116.1 6120.5 6124.2 06:45:18 INFO - PROCESS | 2293 | 06:45:18 INFO - PROCESS | 2293 | [#1] ext.html Cycles:180 Average:58267.95 Median:32397.26 stddev:118623.44 (366.2%) stddev-sans-first:118877.43 06:45:18 INFO - PROCESS | 2293 | Values: 684.9 726.7 793.9 961.7 974.4 39242.8 42232.0 42279.8 42408.2 42462.2 32420.5 40731.0 40821.4 40986.8 41106.6 8131.8 8265.4 9640.4 11214.9 11340.3 11007.6 11126.0 11133.7 11150.0 11151.2 11187.4 11189.1 11212.5 11223.2 11267.2 11077.0 11114.2 11121.3 11143.2 11175.2 10936.1 11009.4 11014.2 11025.7 11038.3 35397.6 35549.9 35746.1 35794.0 35822.5 3784.9 3809.3 3841.6 3853.6 3909.0 25808.6 26033.6 26136.7 27415.9 28383.9 3036.5 3077.4 3123.1 3146.0 3264.6 384022.0 384647.0 386168.0 386207.0 386464.0 45901.8 45970.0 46086.0 46118.0 46188.0 48189.6 48222.3 48265.8 48308.3 48343.3 13980.4 14053.9 14288.9 14320.9 14326.0 12183.5 12333.6 12336.9 12339.0 12346.5 12223.4 12339.9 12376.6 12397.0 12551.8 5116.4 5735.8 5778.6 5846.8 5865.0 924.8 941.3 989.0 1117.2 1184.9 27500.0 29104.7 29881.4 30149.8 30383.0 848.5 1028.0 1061.6 1064.6 1072.0 11476.0 15143.8 15176.5 15190.7 15565.8 499744.0 501703.0 501786.0 503895.0 504760.0 60019.1 60055.0 60184.0 60215.5 60282.0 1179.6 1202.8 1427.6 1454.6 1473.9 29534.7 30308.4 30661.0 30665.0 30936.9 46752.0 47027.8 47047.8 47089.1 47090.8 442181.0 445303.0 445339.0 446042.0 446280.0 32792.0 33145.8 33401.0 33523.0 33539.5 44870.0 45579.0 46489.1 46492.8 46687.0 30972.0 31073.5 31076.0 31823.4 31950.0 31056.4 31511.2 32152.5 32309.0 32374.0 36286.5 36838.4 37558.9 37583.0 37601.6 31112.4 31942.2 32055.5 32147.8 33223.5 19143.6 24768.6 24931.9 25126.6 25143.4 06:45:18 INFO - PROCESS | 2293 | 06:45:18 INFO - PROCESS | 2293 | [#2] jquery.html Cycles:180 Average:45030.63 Median:21452.80 stddev:133856.89 (624.0%) stddev-sans-first:134190.38 06:45:18 INFO - PROCESS | 2293 | Values: 367.2 406.3 410.8 446.1 446.9 20064.8 20261.3 21137.2 21471.4 21636.0 17934.9 19111.6 20862.1 21985.7 23086.1 5903.9 6583.5 6774.7 6833.1 6864.7 6516.2 6623.8 6648.3 6660.1 6684.6 6633.6 6657.3 6660.3 6667.7 6682.9 7331.5 7695.5 7785.0 7835.2 7855.5 6982.9 7301.2 7365.5 7385.0 7551.7 25878.8 25919.6 26532.3 26596.6 26996.9 2189.7 2512.1 2555.2 2594.1 2611.6 15413.2 15795.1 16090.4 16700.5 17217.4 2097.8 2102.6 2121.7 2129.7 2153.6 165316.0 169867.0 170252.0 171107.0 174169.0 21070.4 21273.9 21434.2 21519.0 21687.3 32390.5 32926.7 33299.2 33347.5 33404.3 10208.3 10504.5 10682.9 10767.3 10787.9 8773.0 8777.6 8849.2 9014.4 9044.0 9556.5 9837.3 9870.3 9898.7 9930.7 3950.7 4016.3 4041.1 4049.0 4057.0 830.0 1013.5 1014.7 1021.9 1053.5 21160.5 21338.6 21503.0 21685.8 22842.4 1075.4 1081.4 1087.4 1124.6 1126.7 9520.0 10329.1 11320.9 11385.5 11431.9 763025.0 804860.0 810055.0 818676.0 820567.0 116303.0 132557.0 147592.0 162892.2 166655.0 1231.5 1261.9 1293.1 1294.0 1294.1 19224.4 19620.0 19696.9 19767.0 19950.8 62808.0 62941.0 63679.7 64131.4 64135.0 86315.7 90583.2 91003.6 92295.0 92806.0 11337.9 11691.8 11722.9 11847.3 11958.1 12751.2 12929.1 13034.0 13117.1 13385.6 11262.9 11398.7 11468.1 11497.9 11632.5 10206.3 10267.4 10267.8 10319.8 10454.9 9680.9 11621.3 11834.8 11879.1 11885.3 10957.9 11560.3 11681.9 11794.6 11995.3 9454.3 11493.5 11873.7 11884.4 11901.5 06:45:18 INFO - PROCESS | 2293 | 06:45:18 INFO - PROCESS | 2293 | [#3] mootools.html Cycles:180 Average:6883.60 Median:2436.15 stddev:4314.15 (177.1%) stddev-sans-first:4307.94 06:45:18 INFO - PROCESS | 2293 | Values: 1593.9 1600.6 1616.8 1625.9 1628.6 9458.5 9642.7 10607.4 11336.4 11356.1 10779.5 11069.7 11114.7 11119.2 11126.3 2041.7 2405.3 2452.1 2457.9 2463.3 2410.2 2421.6 2441.8 2443.9 2447.4 2404.6 2422.3 2429.1 2432.3 2440.0 2387.5 2390.4 2394.5 2409.5 2411.6 2365.6 2372.3 2372.6 2380.0 2403.5 10904.6 11006.3 11009.8 11028.2 11145.8 5910.8 6065.5 6102.3 6125.1 6126.3 2920.8 3032.5 3525.7 3662.1 3712.0 1180.0 1184.3 1229.8 1241.3 1244.2 15434.2 15581.0 15611.5 15633.2 15661.1 4767.7 5070.7 5132.0 5149.6 5160.5 13332.9 13433.3 13443.9 13446.4 13447.7 5939.5 5981.6 6047.0 6048.7 6099.8 4283.5 5377.8 5391.3 5436.8 5500.5 4167.5 4227.8 4254.3 4289.0 4302.1 1011.6 1171.6 1172.1 1188.3 1189.3 885.6 916.5 941.4 958.7 962.2 6021.2 6217.5 6393.3 6843.1 6856.2 1008.1 1013.2 1019.8 1023.3 1024.7 4350.3 4381.4 4398.8 4505.7 4520.3 13563.5 13937.3 14075.9 14103.1 14129.6 13505.9 13580.0 13970.0 13981.3 14084.6 10153.2 10237.3 10331.9 10457.6 10485.5 6712.4 6780.1 6821.4 6823.3 6924.4 12419.9 12554.6 12601.3 12605.7 12681.6 10835.7 12579.7 12686.4 12785.2 12886.1 8128.3 8365.4 9117.8 9283.6 9491.8 10303.5 10706.1 10772.1 10774.8 10778.5 8059.8 8904.2 9069.3 9118.9 9240.4 8783.8 8835.6 8842.7 8914.4 8966.0 7916.8 8161.6 8179.1 8272.2 8307.6 6021.0 6119.0 6128.5 6194.2 6260.2 4995.6 6117.2 6290.1 6318.1 6353.3 06:45:18 INFO - PROCESS | 2293 | 06:45:18 INFO - PROCESS | 2293 | [#4] prototype.html Cycles:180 Average:4755.90 Median:5120.99 stddev:1275.95 (24.9%) stddev-sans-first:1236.53 06:45:18 INFO - PROCESS | 2293 | Values: 379.5 379.9 389.8 390.7 396.6 4770.0 5026.0 5071.7 5216.3 5336.2 4623.6 5094.9 5232.5 5265.5 5279.9 4444.7 4561.2 4576.2 4593.4 4602.5 3797.8 4058.6 4213.6 4505.7 4524.6 3998.4 4445.7 4615.0 4629.3 4641.3 3743.9 4155.9 4185.0 4411.4 4562.0 4332.2 4354.6 4359.2 4371.5 4383.9 5121.8 5186.9 5203.2 5225.1 5258.7 4300.2 5120.2 5270.1 5364.8 5425.8 4682.2 5239.5 5651.0 5655.9 5674.7 4412.8 4916.8 4928.8 4931.3 4945.4 5581.2 5839.4 5854.2 5873.7 5976.4 4249.7 5048.3 5062.7 5076.8 5096.9 4458.4 4624.7 5197.3 5219.0 5225.3 4684.7 4722.0 4798.7 4977.4 5047.0 4654.5 5021.3 5296.7 5649.7 5721.8 1496.8 1650.4 1664.7 1780.4 1787.4 1212.7 1402.6 1403.3 1459.3 1465.8 4768.8 4987.4 4993.4 5005.3 5018.3 5147.8 5557.6 5780.1 5812.5 5839.2 4074.7 4511.2 5013.8 5033.2 5042.4 2615.7 2655.5 2855.4 2988.0 3018.9 5662.0 5960.9 6030.8 6067.3 6072.9 4901.6 5189.9 5305.0 5421.2 5564.6 5250.7 5259.6 5571.2 5922.3 6006.4 5145.5 5177.5 5561.8 5846.2 5852.9 5302.7 5598.1 5695.4 5745.3 5796.3 4990.7 5367.5 5859.3 6056.4 6098.6 3812.8 4557.3 4573.5 4659.8 4685.0 5677.6 5721.7 5753.1 5811.6 5829.7 5409.8 5618.9 5624.9 5657.3 5682.1 5342.0 5456.0 5561.1 5624.7 5707.6 4872.0 5010.7 5498.8 5504.6 5529.7 5387.5 5561.4 5678.3 5699.5 5742.4 5177.5 5407.6 5465.8 5506.0 5536.3 06:45:18 INFO - PROCESS | 2293 | 06:45:18 INFO - PROCESS | 2293 | [#5] yui.html Cycles:180 Average:19319.71 Median:3918.06 stddev:26646.18 (680.1%) stddev-sans-first:26684.32 06:45:18 INFO - PROCESS | 2293 | Values: 716.8 729.2 772.1 838.2 856.1 15255.5 16033.6 16358.5 16518.3 16604.8 14739.9 14772.0 14861.3 14928.3 15052.8 947.7 977.2 989.8 995.9 997.9 956.1 983.8 984.8 987.3 989.7 1010.4 1024.7 1027.2 1027.3 1029.0 1014.3 1023.3 1028.2 1031.2 1032.6 756.7 760.7 766.9 767.3 769.7 16354.0 16988.5 17011.4 17076.5 17370.5 14706.4 15015.8 15050.6 15121.6 15398.4 11660.9 11762.2 11858.3 11932.3 12076.5 3727.1 3850.5 3870.1 3875.3 3960.8 86171.0 96600.5 99053.5 99622.5 101878.0 11500.7 11517.7 11670.6 11712.8 11714.6 25059.0 25307.4 25483.4 25553.7 25564.5 8087.4 8137.2 8155.9 8203.6 8390.3 4083.2 4382.2 4468.2 4523.4 4528.5 3183.7 3659.8 3721.4 3741.1 3744.9 636.4 664.9 671.1 681.0 719.8 623.7 662.0 671.5 697.5 711.9 8332.6 10623.6 10654.5 10896.5 10972.8 568.5 578.2 581.2 598.8 608.8 4580.4 5078.7 5162.5 5218.3 5252.9 72570.0 82540.2 86374.6 88304.6 90441.1 83888.6 85338.0 85838.0 88559.6 88587.6 70287.5 74201.6 74657.0 75858.2 76355.6 8868.6 10056.0 10061.8 10068.2 10071.9 42792.0 47865.0 48574.0 49864.0 50225.5 53935.5 63795.4 64176.4 64819.7 64954.0 10400.4 11504.3 11672.6 11858.6 11864.3 10854.9 12134.8 12858.4 13291.6 13391.1 12154.3 12207.3 12501.9 12517.1 12619.3 11883.8 12197.3 12254.6 12302.1 12598.2 11635.0 12058.0 12083.2 12095.8 12495.3 8407.8 8733.0 8734.5 8737.6 9047.1 8976.1 9303.9 10243.6 10267.8 10278.7 06:45:18 INFO - PROCESS | 2293 | -------- Summary: end -------- 06:45:18 INFO - PROCESS | 2293 | 06:45:18 INFO - TEST-INFO | 2293: exit 0 06:45:18 INFO - TEST-OK | dromaeo_css | took 1115372ms 06:45:18 INFO - TEST-START | kraken 06:45:18 INFO - operating with platform_type : mac_ 06:45:18 INFO - Initialising browser for kraken test... 06:45:18 INFO - TEST-INFO | started process 2299 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpEYed6g/profile http://localhost:49806/getInfo.html) 06:45:21 INFO - PROCESS | 2299 | 2017-05-25 06:45:21.157 plugin-container[2301:22181] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 06:45:21 INFO - PROCESS | 2299 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:45:21 INFO - PROCESS | 2299 | 2017-05-25 06:45:21.157 plugin-container[2301:22181] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e17, name = 'com.apple.CFPasteboardClient' 06:45:21 INFO - PROCESS | 2299 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:45:21 INFO - PROCESS | 2299 | 2017-05-25 06:45:21.157 plugin-container[2301:22181] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:45:21 INFO - PROCESS | 2299 | __metrics Screen width/height:1600/1200 06:45:21 INFO - PROCESS | 2299 | colorDepth:24 06:45:21 INFO - PROCESS | 2299 | Browser inner width/height: 1024/689 06:45:21 INFO - PROCESS | 2299 | __metrics 06:45:21 ERROR - PROCESS | 2299 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 06:45:21 INFO - TEST-INFO | 2299: exit 0 06:45:21 INFO - Browser initialized. 06:45:21 INFO - Running cycle 1/1 for kraken test... 06:45:21 INFO - TEST-INFO | started process 2302 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/T/tmpEYed6g/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:45:24 INFO - PROCESS | 2302 | 2017-05-25 06:45:24.641 plugin-container[2303:22305] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 06:45:24 INFO - PROCESS | 2302 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:45:24 INFO - PROCESS | 2302 | 2017-05-25 06:45:24.641 plugin-container[2303:22305] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c03, name = 'com.apple.CFPasteboardClient' 06:45:24 INFO - PROCESS | 2302 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:45:24 INFO - PROCESS | 2302 | 2017-05-25 06:45:24.641 plugin-container[2303:22305] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:45:34 INFO - PROCESS | 2302 | RSS: Main: 203157504 06:45:34 INFO - PROCESS | 2302 | 06:45:37 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49806/tests/kraken/driver.html?testName=audio-beat-detection) 06:45:38 INFO - PROCESS | 2302 | RSS: Main: 216457216 06:45:38 INFO - PROCESS | 2302 | 06:45:40 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49806/tests/kraken/driver.html?testName=audio-dft) 06:45:41 INFO - PROCESS | 2302 | RSS: Main: 224337920 06:45:41 INFO - PROCESS | 2302 | 06:45:44 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49806/tests/kraken/driver.html?testName=audio-fft) 06:45:45 INFO - PROCESS | 2302 | RSS: Main: 227975168 06:45:45 INFO - PROCESS | 2302 | 06:45:47 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49806/tests/kraken/driver.html?testName=audio-oscillator) 06:45:48 INFO - PROCESS | 2302 | RSS: Main: 228298752 06:45:48 INFO - PROCESS | 2302 | 06:45:50 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49806/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:45:51 INFO - PROCESS | 2302 | RSS: Main: 228302848 06:45:51 INFO - PROCESS | 2302 | 06:45:54 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49806/tests/kraken/driver.html?testName=imaging-darkroom) 06:45:54 INFO - PROCESS | 2302 | RSS: Main: 233742336 06:45:54 INFO - PROCESS | 2302 | 06:45:57 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49806/tests/kraken/driver.html?testName=imaging-desaturate) 06:45:58 INFO - PROCESS | 2302 | RSS: Main: 233312256 06:45:58 INFO - PROCESS | 2302 | 06:46:01 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49806/tests/kraken/driver.html?testName=json-parse-financial) 06:46:02 INFO - PROCESS | 2302 | RSS: Main: 233148416 06:46:02 INFO - PROCESS | 2302 | 06:46:04 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49806/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:46:04 INFO - PROCESS | 2302 | RSS: Main: 232931328 06:46:04 INFO - PROCESS | 2302 | 06:46:06 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:46:07 INFO - PROCESS | 2302 | RSS: Main: 232923136 06:46:07 INFO - PROCESS | 2302 | 06:46:09 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:46:10 INFO - PROCESS | 2302 | RSS: Main: 232865792 06:46:10 INFO - PROCESS | 2302 | 06:46:12 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:46:13 INFO - PROCESS | 2302 | RSS: Main: 233353216 06:46:13 INFO - PROCESS | 2302 | 06:46:16 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:46:17 INFO - PROCESS | 2302 | RSS: Main: 233115648 06:46:17 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:49806/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49806/tests/kraken/driver.html?testName=ai-astar) 06:46:19 INFO - PROCESS | 2302 | RSS: Main: 235167744 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | __start_tp_report 06:46:19 INFO - PROCESS | 2302 | _x_x_mozilla_page_load 06:46:19 INFO - PROCESS | 2302 | _x_x_mozilla_page_load_details 06:46:19 INFO - PROCESS | 2302 | |i|pagename|runs| 06:46:19 INFO - PROCESS | 2302 | |0;ai-astar;110;167;152;122;127;162;121;120;120;119 06:46:19 INFO - PROCESS | 2302 | |1;audio-beat-detection;141;109;112;142;113;117;115;112;132;113 06:46:19 INFO - PROCESS | 2302 | |2;audio-dft;205;195;200;198;195;233;285;203;199;193 06:46:19 INFO - PROCESS | 2302 | |3;audio-fft;81;52;65;102;71;80;55;65;96;50 06:46:19 INFO - PROCESS | 2302 | |4;audio-oscillator;92;70;86;71;99;103;110;104;72;84 06:46:19 INFO - PROCESS | 2302 | |5;imaging-gaussian-blur;100;77;135;137;91;91;90;89;91;97 06:46:19 INFO - PROCESS | 2302 | |6;imaging-darkroom;122;103;151;100;99;109;151;99;99;100 06:46:19 INFO - PROCESS | 2302 | |7;imaging-desaturate;88;142;141;95;91;137;147;91;92;123 06:46:19 INFO - PROCESS | 2302 | |8;json-parse-financial;66;66;65;67;70;68;68;69;68;70 06:46:19 INFO - PROCESS | 2302 | |9;json-stringify-tinderbox;58;63;60;66;62;57;56;81;58;61 06:46:19 INFO - PROCESS | 2302 | |10;stanford-crypto-aes;84;87;90;85;81;82;85;86;94;87 06:46:19 INFO - PROCESS | 2302 | |11;stanford-crypto-ccm;139;160;119;136;125;145;152;168;205;154 06:46:19 INFO - PROCESS | 2302 | |12;stanford-crypto-pbkdf2;174;204;172;167;170;203;169;172;207;170 06:46:19 INFO - PROCESS | 2302 | |13;stanford-crypto-sha256-iterative;66;66;67;67;67;67;65;66;66;66 06:46:19 INFO - PROCESS | 2302 | __end_tp_report 06:46:19 INFO - PROCESS | 2302 | __start_cc_report 06:46:19 INFO - PROCESS | 2302 | _x_x_mozilla_cycle_collect,1011 06:46:19 INFO - PROCESS | 2302 | __end_cc_report 06:46:19 INFO - PROCESS | 2302 | __startTimestamp1495719979306__endTimestamp 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | ------- Summary: start ------- 06:46:19 INFO - PROCESS | 2302 | Number of tests: 14 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | [#0] ai-astar Cycles:10 Average:132.00 Median:124.50 stddev:20.31 (16.3%) stddev-sans-first:19.92 06:46:19 INFO - PROCESS | 2302 | Values: 110.0 167.0 152.0 122.0 127.0 162.0 121.0 120.0 120.0 119.0 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | [#1] audio-beat-detection Cycles:10 Average:120.60 Median:116.00 stddev:12.68 (10.9%) stddev-sans-first:11.09 06:46:19 INFO - PROCESS | 2302 | Values: 141.0 109.0 112.0 142.0 113.0 117.0 115.0 112.0 132.0 113.0 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | [#2] audio-dft Cycles:10 Average:210.60 Median:201.50 stddev:28.54 (14.2%) stddev-sans-first:30.19 06:46:19 INFO - PROCESS | 2302 | Values: 205.0 195.0 200.0 198.0 195.0 233.0 285.0 203.0 199.0 193.0 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | [#3] audio-fft Cycles:10 Average:71.70 Median:68.00 stddev:17.93 (26.4%) stddev-sans-first:18.69 06:46:19 INFO - PROCESS | 2302 | Values: 81.0 52.0 65.0 102.0 71.0 80.0 55.0 65.0 96.0 50.0 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | [#4] audio-oscillator Cycles:10 Average:89.10 Median:78.00 stddev:14.83 (19.0%) stddev-sans-first:15.69 06:46:19 INFO - PROCESS | 2302 | Values: 92.0 70.0 86.0 71.0 99.0 103.0 110.0 104.0 72.0 84.0 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | [#5] imaging-gaussian-blur Cycles:10 Average:99.80 Median:90.50 stddev:19.99 (22.1%) stddev-sans-first:21.20 06:46:19 INFO - PROCESS | 2302 | Values: 100.0 77.0 135.0 137.0 91.0 91.0 90.0 89.0 91.0 97.0 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | [#6] imaging-darkroom Cycles:10 Average:113.30 Median:151.00 stddev:21.11 (14.0%) stddev-sans-first:22.15 06:46:19 INFO - PROCESS | 2302 | Values: 122.0 103.0 151.0 100.0 99.0 109.0 151.0 99.0 99.0 100.0 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | [#7] imaging-desaturate Cycles:10 Average:114.70 Median:89.50 stddev:25.36 (28.3%) stddev-sans-first:24.98 06:46:19 INFO - PROCESS | 2302 | Values: 88.0 142.0 141.0 95.0 91.0 137.0 147.0 91.0 92.0 123.0 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | [#8] json-parse-financial Cycles:10 Average:67.70 Median:68.00 stddev:1.70 (2.5%) stddev-sans-first:1.69 06:46:19 INFO - PROCESS | 2302 | Values: 66.0 66.0 65.0 67.0 70.0 68.0 68.0 69.0 68.0 70.0 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | [#9] json-stringify-tinderbox Cycles:10 Average:62.20 Median:61.50 stddev:7.27 (11.8%) stddev-sans-first:7.55 06:46:19 INFO - PROCESS | 2302 | Values: 58.0 63.0 60.0 66.0 62.0 57.0 56.0 81.0 58.0 61.0 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:86.50 stddev:3.78 (4.4%) stddev-sans-first:3.94 06:46:19 INFO - PROCESS | 2302 | Values: 84.0 87.0 90.0 85.0 81.0 82.0 85.0 86.0 94.0 87.0 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | [#11] stanford-crypto-ccm Cycles:10 Average:150.30 Median:153.00 stddev:24.49 (16.0%) stddev-sans-first:25.63 06:46:19 INFO - PROCESS | 2302 | Values: 139.0 160.0 119.0 136.0 125.0 145.0 152.0 168.0 205.0 154.0 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.80 Median:173.00 stddev:16.61 (9.6%) stddev-sans-first:17.43 06:46:19 INFO - PROCESS | 2302 | Values: 174.0 204.0 172.0 167.0 170.0 203.0 169.0 172.0 207.0 170.0 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - PROCESS | 2302 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:0.67 (1.0%) stddev-sans-first:0.71 06:46:19 INFO - PROCESS | 2302 | Values: 66.0 66.0 67.0 67.0 67.0 67.0 65.0 66.0 66.0 66.0 06:46:19 INFO - PROCESS | 2302 | -------- Summary: end -------- 06:46:19 INFO - PROCESS | 2302 | 06:46:19 INFO - TEST-INFO | 2302: exit 0 06:46:20 INFO - TEST-OK | kraken | took 61365ms 06:46:20 INFO - SUITE-END | took 1176s 06:46:20 INFO - Completed test suite (00:19:37) 06:46:20 INFO - javascript benchmark 06:46:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7799.473159649631, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2714.809963302569, 3444.569428821399, 3449.9482507762386, 3458.118179864134, 3472.7221822254214, 5927.40725927407, 5971.4625683688455, 5984.730687119079, 5992.071228959513, 6026.4576188143055, 5972.372900845414, 5994.340622531518, 6029.427204415573, 6042.728077236524, 6045.27456705196, 5386.030514507397, 5446.482584154499, 5471.972640136813, 5483.232347471358, 5511.669299842002, 4547.272436410175, 5490.752916118764, 5497.642653227527, 5498.1752737089555, 5502.147167189072, 5313.601479889005, 5338.27933229013, 5448.782048718046, 5491.890162196753, 5539.639923404986, 4453.487848897385, 5431.864203394907, 5467.343918729778, 5491.533219676332, 5546.195801608744, 4319.719218250803, 5433.347998240197, 5444.101723215705, 5444.346678398618, 5482.451754824526, 5708.857278568027, 6043.335233124397, 6076.878462430771, 6082.422169893853, 6086.330503644595, 6262.749490020439, 6275.184226050585, 6283.151774510428, 6298.68506574674, 6303.653299068549, 6472.190976127984, 6488.21006967004, 6488.5133614978595, 6494.447971922392, 6511.837204069889, 6073.362296958756, 6083.6349819011, 6098.146259523626, 6108.205933228696, 6144.692765361714, 6293.37066293367, 6340.207474065742, 6355.428011478989, 6538.1827271591055, 6546.509011824132, 6229.813105606839, 6231.906521402183, 6233.625982441068, 6251.71867265965, 6280.2777680566405, 6378.77674281398, 6415.967920160369, 6423.42189202974, 6434.871302573923, 6447.580907241015, 6166.136740856284, 6239.282482514478, 6294.905576416358, 6324.3043265240785, 6338.080978258126, 5201.5838732901275, 6194.5044396448175, 6287.402696743802, 6321.494280457552, 6332.841678958063, 4832.613390928787, 4864.343313652683, 4895.31465594817, 4904.5340692634145, 4907.288443175719, 4378.868633940987, 4392.582704643054, 4411.2941929291155, 4416.933745993812, 4420.889477763018, 5691.630044047124, 5710.971445142748, 5857.58996870215, 5887.499562537232, 5963.582549221513, 6304.716287767062, 6317.4630156437215, 6335.556511044275, 6413.551051426355, 6414.583052101599, 5863.355030946593, 5894.469497745138, 5903.822885313448, 5911.231539899785, 5953.077273022688, 5383.596230282745, 5545.140503222007, 5567.081431563746, 5570.164475328733, 5570.220169176318, 5444.564434845282, 6342.809715708536, 6481.157449531531, 6501.024846273097, 6583.901241481477, 5331.013762453958, 6422.229332480132, 6446.8388290293115, 6451.096846441408, 6463.741450341934, 6486.0, 6560.507961902972, 6607.9669601651685, 6621.841177793963, 6631.436327912182, 6169.198004259418, 6214.8135555933395, 6218.81343559694, 6241.344658810941, 6341.175647165839, 5829.358770535323, 6018.277806663228, 6373.521985850987, 6382.489400848014, 6393.392627700361, 6267.247930248217, 6281.780137695158, 6283.560150789401, 6286.717097730704, 6300.0, 5252.816151434681, 5425.050616142081, 6115.179495343419, 6157.630542167305, 6207.34822843613, 4828.444728856227, 6086.3305036446845, 6223.159873417212, 6233.314335423191, 6238.220222472191, 6125.295591007091, 6260.530460215593, 6272.0, 6276.215473065867, 6282.654454004891, 6078.149059131637, 6108.645631960441, 6116.092308610985, 6120.540959427972, 6124.228368151087], "value": 5805.6314111811935, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [684.9383555480006, 726.7093162734909, 793.8888555602215, 961.7114865540336, 974.4056125763278, 39242.803785981065, 42232.0, 42279.78860105703, 42408.15183696332, 42462.15075698484, 32420.513692294633, 40731.0, 40821.38767918484, 40986.7950660247, 41106.58893411065, 8131.837363252732, 8265.421420500568, 9640.37337573059, 11214.943925280362, 11340.319580825177, 11007.559697612085, 11126.0, 11133.665990020312, 11149.99650031497, 11151.219414640978, 11187.440627968568, 11189.104871610252, 11212.495437705324, 11223.214374993753, 11267.154963377738, 11077.003069723723, 11114.166437517219, 11121.332720036824, 11143.219974601781, 11175.21773475857, 10936.125109991182, 11009.449527523591, 11014.17393695468, 11025.669229923114, 11038.282511636718, 35397.64602353995, 35549.933501994725, 35746.106347341265, 35794.0, 35822.462663060076, 3784.8675296364495, 3809.3350510355544, 3841.635044670752, 3853.6339047790416, 3908.9609103908883, 25808.612870806952, 26033.609495857578, 26136.738632613622, 27415.862920685267, 28383.85808070967, 3036.4693970737185, 3077.430675325072, 3123.109913674592, 3145.952810707818, 3264.60824701035, 384021.9999999972, 384647.0, 386168.0, 386207.0, 386464.0, 45901.770491147334, 45970.0, 46086.0, 46118.0, 46188.0, 48189.55431337065, 48222.31221907216, 48265.75867120642, 48308.27537586939, 48343.274850877264, 13980.370883310276, 14053.859461405254, 14288.928555357365, 14320.92593055525, 14326.0, 12183.512659493697, 12333.62999110028, 12336.876631233752, 12338.951189148844, 12346.506139754489, 12223.449944752507, 12339.876601233873, 12376.62870113898, 12397.00823934083, 12551.807578280048, 5116.386033675983, 5735.766810135758, 5778.595498315161, 5846.824595262149, 5865.0, 924.8057907839294, 941.3269512298739, 988.9505524723735, 1117.1621284036971, 1184.8518935133109, 27500.0, 29104.7089529102, 29881.402371952438, 30149.794008239423, 30383.0, 848.5460278750838, 1027.9537420816082, 1061.628430049492, 1064.5741703318733, 1071.981617463413, 11476.02453791437, 15143.848561514464, 15176.544703658908, 15190.669087861292, 15565.766513502307, 499744.0, 501703.0, 501786.0, 503895.0, 504760.0, 60019.09971350433, 60055.0, 60184.0, 60215.49461263503, 60282.0, 1179.622520793355, 1202.807550791869, 1427.643089227693, 1454.6217983324411, 1473.8820894328214, 29534.704652954057, 30308.393832122794, 30661.0, 30665.0, 30936.917207897615, 46752.0, 47027.76486117548, 47047.764761175975, 47089.058218836115, 47090.76454617705, 442181.0, 445303.0, 445339.0, 446042.0, 446280.0, 32792.01623951285, 33145.83427082849, 33400.99797006094, 33523.0, 33539.4969075469, 44870.0, 45579.0, 46489.07021859611, 46492.7675361621, 46687.0, 30972.0, 31073.53389699111, 31076.0, 31823.36353272968, 31950.0, 31056.378872421974, 31511.21221969469, 32152.51771223387, 32309.030729078164, 32374.0, 36286.455703165004, 36838.44742328813, 37558.87323380303, 37583.0, 37601.62398376091, 31112.377752445278, 31942.201444964063, 32055.519167212977, 32147.839260803546, 33223.501647474826, 19143.617127657646, 24768.628470572596, 24931.87534062318, 25126.623100653138, 25143.371415714755], "value": 19064.65561966022, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.19034528863847, 406.3153586207241, 410.81513319006456, 446.0677184684011, 446.92849144136943, 20064.799352006496, 20261.290854820083, 21137.15451381943, 21471.355859324245, 21636.0, 17934.91032544839, 19111.61776764464, 20862.06120724567, 21985.67021494675, 23086.076556937704, 5903.940960590382, 6583.5062370322175, 6774.729010839561, 6833.111695479581, 6864.725410983555, 6516.185476815398, 6623.801285961429, 6648.335166483362, 6660.100886380349, 6684.649582896243, 6633.601983880959, 6657.300983396746, 6660.333966603349, 6667.699953502104, 6682.866342673144, 7331.486795924287, 7695.461317707763, 7784.9610751945875, 7835.216478352119, 7855.450118491708, 6982.860342793167, 7301.1968683444775, 7365.521241119363, 7384.96610474534, 7551.69793208271, 25878.835452404688, 25919.611205831927, 26532.33669158267, 26596.60105098425, 26996.92012319505, 2189.704389907358, 2512.133314006665, 2555.2334299710196, 2594.0661361909697, 2611.6343711880354, 15413.229338533029, 15795.131267780267, 16090.356385744557, 16700.49898503047, 17217.397391091377, 2097.7902209779054, 2102.5899949509853, 2121.745390553128, 2129.708691594352, 2153.601583707019, 165316.0, 169867.0, 170252.0, 171107.0, 174168.99999999872, 21070.36788896305, 21273.893630532057, 21434.249801257192, 21519.031643576076, 21687.34937951864, 32390.514142287884, 32926.67073329236, 33299.16752081169, 33347.499787503206, 33404.331913361595, 10208.285420020677, 10504.474776261157, 10682.893171068345, 10767.300125491975, 10787.946060269649, 8772.95613521941, 8777.561121944005, 8849.247813935759, 9014.41406308601, 9044.0, 9556.474393908402, 9837.262205334628, 9870.30907836459, 9898.703038908845, 9930.652427165014, 3950.7234493585747, 4016.2770701273466, 4041.131156801243, 4048.959510404858, 4056.9797151014645, 829.9875501867476, 1013.5084484025242, 1014.6702321745462, 1021.918246540275, 1053.4785281285813, 21160.5187636867, 21338.57322853534, 21503.0, 21685.783142168377, 22842.429353439995, 1075.4353964168874, 1081.4214395298634, 1087.445402844539, 1124.6288724720987, 1126.7408496045955, 9520.0, 10329.070383665507, 11320.943395282971, 11385.544578216945, 11431.885681143249, 763025.0, 804860.0, 810055.0, 818676.0, 820567.0, 116303.0, 132557.0, 147592.0, 162892.18553907154, 166655.0, 1231.4520038582746, 1261.9116661833584, 1293.088372697246, 1294.0488740775334, 1294.0747365633617, 19224.423267302005, 19620.0, 19696.90151549233, 19767.0, 19950.800491995476, 62808.0, 62941.0, 63679.68160159169, 64131.374243734244, 64135.0, 86315.70526442234, 90583.18833623422, 91003.63494547455, 92295.0, 92806.0, 11337.886621133683, 11691.824622630838, 11722.882771172179, 11847.289162650268, 11958.10314226454, 12751.234925904475, 12929.094963352474, 13034.022448316224, 13117.147385419918, 13385.598432047054, 11262.943685281849, 11398.715032124263, 11468.139889508153, 11497.885021149681, 11632.476538555617, 10206.285560010729, 10267.383956962607, 10267.794644106927, 10319.845202322122, 10454.895451045697, 9680.85478717834, 11621.302721836717, 11834.76330473403, 11879.139279734294, 11885.346305953255, 10957.890421095686, 11560.30638161713, 11681.948624623825, 11794.646160615195, 11995.340256285988, 9454.290928180277, 11493.54025838957, 11873.703157421134, 11884.405779710807, 11901.523939042687], "value": 11762.140389706305, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1593.936242550298, 1600.6474529022978, 1616.8059832820056, 1625.8699304055676, 1628.6091338078857, 9458.527073646308, 9642.662506812263, 10607.416592087433, 11336.376499292535, 11356.148288878336, 10779.46102694866, 11069.667909962694, 11114.722131946706, 11119.221654484189, 11126.276792008533, 2041.6631255842767, 2405.26639374991, 2452.141750387368, 2457.913973010945, 2463.310273123528, 2410.240774156135, 2421.6367544868235, 2441.7924476419525, 2443.926682199537, 2447.4248551590363, 2404.5791986402396, 2422.2733180046002, 2429.1133736186252, 2432.3189506937997, 2439.987800061006, 2387.4508862961616, 2390.438247011951, 2394.5450364430703, 2409.49400625867, 2411.577973854586, 2365.5741966446035, 2372.3357459911253, 2372.5610762009082, 2379.976200237993, 2403.495265994143, 10904.563817447372, 11006.284591501526, 11009.779804403866, 11028.22802403824, 11145.777084458347, 5910.763569457216, 6065.4541091302, 6102.26772787264, 6125.111858780452, 6126.264848218198, 2920.8462657250266, 3032.454158251514, 3525.700315473188, 3662.0661731258624, 3712.0, 1179.9941000294943, 1184.2953442701578, 1229.7909355409513, 1241.3420886929941, 1244.2037096258402, 15434.151121688303, 15581.0, 15611.531654050397, 15633.202181749017, 15661.13863737494, 4767.6662633615315, 5070.746462676925, 5132.0, 5149.562287205555, 5160.535551800356, 13332.866671333357, 13433.328333583282, 13443.932780336232, 13446.394912228972, 13447.663808404672, 5939.46544810969, 5981.641101533922, 6046.969765151234, 6048.697565121726, 6099.8475038123515, 4283.4579551361485, 5377.838664840061, 5391.272178255964, 5436.809711660152, 5500.50495455411, 4167.4790651168605, 4227.830886764556, 4254.340577210537, 4288.978555107205, 4302.096559722493, 1011.610529945976, 1171.630936255094, 1172.1150531348776, 1188.2692144331174, 1189.3339729751367, 885.5970533407414, 916.4913473022403, 941.3833938770116, 958.6932181701789, 962.2398305338844, 6021.157038014681, 6217.502599792006, 6393.328700486475, 6843.144606924135, 6856.211535673362, 1008.1078245752561, 1013.1540163963173, 1019.8215312320315, 1023.2837014090018, 1024.7130803375067, 4350.347447882843, 4381.430414046154, 4398.780060996937, 4505.729656220572, 4520.32195170727, 13563.525276615468, 13937.303134843218, 14075.929620351833, 14103.0832995857, 14129.576112716635, 13505.864941350657, 13580.049396542345, 13970.022098453213, 13981.300934953211, 14084.647883802987, 10153.187744980532, 10237.283390162616, 10331.896681033244, 10457.633982810565, 10485.528151233213, 6712.362325579063, 6780.118584583973, 6821.386075253251, 6823.317668233137, 6924.376806087476, 12419.93790031044, 12554.623361299176, 12601.306928118674, 12605.684857878627, 12681.619551413472, 10835.67492975212, 12579.685507862378, 12686.365681716063, 12785.232886026868, 12886.09797314179, 8128.309093727099, 8365.372597055124, 9117.77205569866, 9283.582238799136, 9491.762705932408, 10303.53634086453, 10706.089982351587, 10772.084372828398, 10774.784504310026, 10778.514966826355, 8059.838803223785, 8904.19862212404, 9069.319801014819, 9118.863217051618, 9240.353175277934, 8783.780405489913, 8835.602397891982, 8842.734717958472, 8914.37599368038, 8966.0, 7916.84166316682, 8161.551114688749, 8179.141190174946, 8272.214139656722, 8307.584620769057, 6020.969895150496, 6118.959776837859, 6128.540359472968, 6194.225721784777, 6260.154879091447, 4995.550400463976, 6117.174181485442, 6290.119383286435, 6318.0838778375955, 6353.269374021862], "value": 5251.425348318831, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.48200706036266, 379.92021675448154, 389.7934094929688, 390.73820540238046, 396.6311330462669, 4770.0, 5025.9748701256485, 5071.7210553419545, 5216.26972223889, 5336.199570064492, 4623.606993405556, 5094.923576146361, 5232.502912223342, 5265.526102650761, 5279.855313578842, 4444.710913923689, 4561.2245918193985, 4576.176288268102, 4593.379893714339, 4602.516735742749, 3797.7531460454984, 4058.6144316289906, 4213.599708027732, 4505.707129036618, 4524.5701658342405, 3998.440218369416, 4445.666575006871, 4615.030843522847, 4629.282461218517, 4641.25739881619, 3743.9251214975548, 4155.916881662365, 4184.958150418503, 4411.42651455312, 4561.977190114061, 4332.176886391607, 4354.629856462205, 4359.237133501624, 4371.535535595579, 4383.9342409863875, 5121.795128194868, 5186.861948521128, 5203.219517072432, 5225.111731005738, 5258.684478931276, 4300.247841381518, 5120.18077107661, 5270.1040823060175, 5364.758585863646, 5425.83722488326, 4682.180618391768, 5239.502247286502, 5650.971745141289, 5655.943440565583, 5674.716264186815, 4412.75729834859, 4916.754162291871, 4928.778204980785, 4931.26031095335, 4945.357103576512, 5581.186114512759, 5839.416058394127, 5854.151148083547, 5873.676947767871, 5976.372480889488, 4249.660027197817, 5048.3184770056305, 5062.670926389773, 5076.796928122833, 5096.898062038813, 4458.420405347349, 4624.722516649012, 5197.272381866542, 5219.0, 5225.301776922515, 4684.672072954929, 4722.0, 4798.664093513421, 4977.352944117241, 5046.965372098711, 4654.511276316006, 5021.292760461461, 5296.735163241822, 5649.661020338793, 5721.828345149652, 1496.7829664698775, 1650.3893559382866, 1664.7193345117266, 1780.3857669104138, 1787.4012205911165, 1212.7210741529498, 1402.593247958101, 1403.3474434388058, 1459.3360021190333, 1465.7654775236124, 4768.8005592730005, 4987.401511818605, 4993.425756038029, 5005.299258103795, 5018.3476148101245, 5147.782635852592, 5557.638753481092, 5780.081059065145, 5812.534997200299, 5839.248225532323, 4074.6757303876357, 4511.172974945152, 5013.774380152829, 5033.219850923113, 5042.445331013586, 2615.686117665892, 2655.548556745358, 2855.4289142171647, 2987.9542160244177, 3018.894338698179, 5661.971690141523, 5960.850978725566, 6030.758769649165, 6067.302260240129, 6072.939270607326, 4901.6323775716255, 5189.922151167735, 5305.0, 5421.186821976735, 5564.577784662403, 5250.658707184097, 5259.552938000236, 5571.164325351229, 5922.318933322636, 6006.429389208019, 5145.536901678867, 5177.534021938043, 5561.777528898799, 5846.1815345852465, 5852.94147058524, 5302.70835104073, 5598.1322894951345, 5695.430456954271, 5745.2818397700285, 5796.304443466811, 4990.675606085738, 5367.463253674758, 5859.267591551056, 6056.394360564084, 6098.621537600251, 3812.7976439648237, 4557.270836666117, 4573.513608077322, 4659.790309436015, 4685.008871229782, 5677.630953987978, 5721.685307307972, 5753.1370294456165, 5811.634265947414, 5829.737661805144, 5409.810656626999, 5618.85952851182, 5624.915626265528, 5657.264555607745, 5682.107756187025, 5342.0384330820825, 5456.0358271021605, 5561.054620714407, 5624.678200622932, 5707.600467967202, 4871.95128048715, 5010.749462526786, 5498.752556135064, 5504.614676972734, 5529.723513824373, 5387.515123638891, 5561.416051314553, 5678.318601767649, 5699.5155411790465, 5742.36833948257, 5177.534021937893, 5407.567394608501, 5465.808696695596, 5506.0, 5536.339098270422], "value": 4391.2280865973535, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [716.8136284566011, 729.18331468755, 772.1197834468707, 838.1953324808184, 856.0968178571607, 15255.542333729993, 16033.599160021005, 16358.509244722678, 16518.256678449485, 16604.83395166048, 14739.926300368483, 14772.0, 14861.256937153126, 14928.253587320645, 15052.849471505282, 947.7014740356783, 977.1986970684042, 989.787195752913, 995.8854731705837, 997.8652881860964, 956.0821611253206, 983.7786498037956, 984.8276551603476, 987.279286121132, 989.6585677941134, 1010.3786171504545, 1024.677226673599, 1027.1577306608583, 1027.337367398028, 1028.9845652315184, 1014.3102690170681, 1023.2785885950387, 1028.172321281368, 1031.2162756305263, 1032.6179313653959, 756.735142700056, 760.6995236881401, 766.8772996320617, 767.2672597669213, 769.7152053740152, 16354.0, 16988.49034528966, 17011.404600838912, 17076.487705368858, 17370.478885633453, 14706.411743530245, 15015.774763378558, 15050.623734406618, 15121.621959450991, 15398.384064637401, 11660.883391166064, 11762.235454695503, 11858.320817211543, 11932.343721095336, 12076.516939322417, 3727.105494681284, 3850.5186851643543, 3870.0905287257474, 3875.283072631544, 3960.8375531398437, 86171.0, 96600.51699741457, 99053.50473247659, 99622.5018874901, 101878.0, 11500.712482187844, 11517.712057198469, 11670.649880503599, 11712.824307635392, 11714.589989350332, 25058.997640094556, 25307.367315817253, 25483.36291592725, 25553.744462555136, 25564.48871022569, 8087.393445491612, 8137.186281371934, 8155.898953641183, 8203.58982050895, 8390.328773698211, 4083.2037752638544, 4382.167388196296, 4468.173387923244, 4523.36672865799, 4528.456585209796, 3183.7293830024246, 3659.7621154624862, 3721.404575267944, 3741.120836603396, 3744.932694182145, 636.3763511758502, 664.8637029408959, 671.0705672643327, 680.9863802723918, 719.7912605344392, 623.6538721009883, 661.976830810929, 671.5030877150873, 697.476892330752, 711.9038929744522, 8332.62503187358, 10623.628173014062, 10654.520546575422, 10896.455177241107, 10972.835407468894, 568.4542838874644, 578.2396149063967, 581.2182614383647, 598.8413070536311, 608.8203979825962, 4580.4045474088825, 5078.695278283315, 5162.45794191612, 5218.321618189611, 5252.894942101213, 72570.0, 82540.17459825445, 86374.56812715896, 88304.55847720848, 90441.09558904327, 83888.58055709682, 85338.0, 85838.0, 88559.55720221357, 88587.55706221427, 70287.53993610096, 74201.62899185577, 74657.0, 75858.24141758622, 76355.61822190853, 8868.556572171237, 10056.0, 10061.798764024532, 10068.244881634053, 10071.949640251752, 42792.0, 47865.0, 48574.0, 49864.0, 50225.497745023546, 53935.46064539305, 63795.36204637894, 64176.395590110624, 64819.67590162019, 64954.0, 10400.375977441377, 11504.25222360544, 11672.649820505398, 11858.584949526725, 11864.288142711119, 10854.945725271324, 12134.75730485403, 12858.42137103814, 13291.601251962456, 13391.129576577492, 12154.316842026925, 12207.267563946192, 12501.874981250436, 12517.061220408325, 12619.30593817349, 11883.82174267404, 12197.268163910194, 12254.571090011807, 12302.077344199042, 12598.181118227287, 11635.02633933002, 12058.0, 12083.154179207371, 12095.75808483843, 12495.312757798045, 8407.80867741789, 8733.0, 8734.475931444134, 8737.56312184401, 9047.140521650434, 8976.147266009719, 9303.906960930304, 10243.59025638996, 10267.794644107225, 10278.691639250834], "value": 7497.624806504583, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1565.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 167.0, 152.0, 122.0, 127.0, 162.0, 121.0, 120.0, 120.0, 119.0], "value": 132.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 112.0, 142.0, 113.0, 117.0, 115.0, 112.0, 132.0, 113.0], "value": 120.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 195.0, 200.0, 198.0, 195.0, 233.0, 285.0, 203.0, 199.0, 193.0], "value": 210.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 52.0, 65.0, 102.0, 71.0, 80.0, 55.0, 65.0, 96.0, 50.0], "value": 71.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 70.0, 86.0, 71.0, 99.0, 103.0, 110.0, 104.0, 72.0, 84.0], "value": 89.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 77.0, 135.0, 137.0, 91.0, 91.0, 90.0, 89.0, 91.0, 97.0], "value": 99.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 103.0, 151.0, 100.0, 99.0, 109.0, 151.0, 99.0, 99.0, 100.0], "value": 113.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 142.0, 141.0, 95.0, 91.0, 137.0, 147.0, 91.0, 92.0, 123.0], "value": 114.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 67.0, 70.0, 68.0, 68.0, 69.0, 68.0, 70.0], "value": 67.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 63.0, 60.0, 66.0, 62.0, 57.0, 56.0, 81.0, 58.0, 61.0], "value": 62.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 90.0, 85.0, 81.0, 82.0, 85.0, 86.0, 94.0, 87.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 160.0, 119.0, 136.0, 125.0, 145.0, 152.0, 168.0, 205.0, 154.0], "value": 150.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 204.0, 172.0, 167.0, 170.0, 203.0, 169.0, 172.0, 207.0, 170.0], "value": 180.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 67.0, 67.0, 67.0, 65.0, 66.0, 66.0, 66.0], "value": 66.3, "unit": "score"}]}]} 06:46:20 INFO - Return code: 0 06:46:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:46:20 ERROR - # TBPL SUCCESS # 06:46:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:46:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:46:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:46:20 INFO - 2017-05-25 06:46:20.627 screenresolution[2307:22695] starting screenresolution argv=screenresolution get 06:46:20 INFO - 2017-05-25 06:46:20.629 screenresolution[2307:22695] Display 0: 1600x1200x32@60 06:46:20 INFO - 2017-05-25 06:46:20.636 screenresolution[2308:22698] starting screenresolution argv=screenresolution list 06:46:20 INFO - Available Modes on Display 0 06:46:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:46:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:46:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:46:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:46:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:46:20 INFO - Intel Iris: 06:46:20 INFO - Chipset Model: Intel Iris 06:46:20 INFO - Type: GPU 06:46:20 INFO - Bus: Built-In 06:46:20 INFO - VRAM (Dynamic, Max): 1536 MB 06:46:20 INFO - Vendor: Intel (0x8086) 06:46:20 INFO - Device ID: 0x0a2e 06:46:20 INFO - Revision ID: 0x0009 06:46:20 INFO - Displays: 06:46:20 INFO - Display: 06:46:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:46:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:46:20 INFO - Display Serial Number: 1600x1200 60 06:46:20 INFO - Main Display: Yes 06:46:20 INFO - Mirror: Off 06:46:20 INFO - Online: Yes 06:46:20 INFO - Rotation: Supported 06:46:20 INFO - Return code: 0 06:46:20 INFO - Running post-action listener: _resource_record_post_action 06:46:20 INFO - [mozharness: 2017-05-25 13:46:20.865131Z] Finished run-tests step (success) 06:46:20 INFO - Running post-run listener: _resource_record_post_run 06:46:20 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 1430528; Write bytes: 511579648; Read time: 19; Write time: 1640 06:46:20 INFO - TinderboxPrint: CPU usage
54.4% 06:46:20 INFO - TinderboxPrint: I/O read bytes / time
1,430,528 / 19 06:46:20 INFO - TinderboxPrint: I/O write bytes / time
511,579,648 / 1,640 06:46:20 INFO - TinderboxPrint: CPU idle
2,183.6 (45.6%) 06:46:20 INFO - TinderboxPrint: CPU system
961.6 (20.1%) 06:46:20 INFO - TinderboxPrint: CPU user
1,648.5 (34.4%) 06:46:20 INFO - TinderboxPrint: Swap in / out
210,194,432 / 0 06:46:20 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203964928; Write bytes: 202528768; Read time: 15804; Write time: 432 06:46:20 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1414144; Write bytes: 298257920; Read time: 18; Write time: 1178 06:46:21 INFO - Running post-run listener: _upload_blobber_files 06:46:21 INFO - Blob upload gear active. 06:46:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:46:21 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 06:46:21 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'] 06:46:21 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 06:46:21 INFO - (blobuploader) - INFO - Open directory for files ... 06:46:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:46:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:46:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:46:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:46:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:46:22 INFO - (blobuploader) - INFO - Done attempting. 06:46:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:46:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:46:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:46:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:46:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:46:23 INFO - (blobuploader) - INFO - Done attempting. 06:46:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:46:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:46:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:46:24 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:46:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:46:24 INFO - (blobuploader) - INFO - Done attempting. 06:46:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:46:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:46:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:46:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:46:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:46:26 INFO - (blobuploader) - INFO - Done attempting. 06:46:26 INFO - (blobuploader) - INFO - Iteration through files over. 06:46:26 INFO - Return code: 0 06:46:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:46:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:46:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/164a277a44bf3be243a593ebfc706c7c77bbaba5f7c284f35c32666ae9a864c1887771744eafd4f04db46f7705dfe2c86f12985966b7bc7189826417ccb3c16e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d818b3cca77041f13f49f089bce60806c7c411641f02159b47a2fef220183fdc8c1c1fb423c6adefaad4e12505d63f03f10a18f92f7f65cb5211677f4a757307", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c5709fd79332eac631f905ef578211d4ff5170e739ff166d3e7062fec56793c873fb9743f4317fb560bfc1957e51600c180b1a8954235ba32fdaf60b7ea31963", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7a8c4bac72eafe31babbff175239f56a7000c7c070ea1543c9e504507cdd89cb53bf48a3e28c50de0385db3a7eab24f1d81ca52986adc5382acaf42c780e3dd"} 06:46:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:46:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:46:26 INFO - Contents: 06:46:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/164a277a44bf3be243a593ebfc706c7c77bbaba5f7c284f35c32666ae9a864c1887771744eafd4f04db46f7705dfe2c86f12985966b7bc7189826417ccb3c16e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d818b3cca77041f13f49f089bce60806c7c411641f02159b47a2fef220183fdc8c1c1fb423c6adefaad4e12505d63f03f10a18f92f7f65cb5211677f4a757307", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c5709fd79332eac631f905ef578211d4ff5170e739ff166d3e7062fec56793c873fb9743f4317fb560bfc1957e51600c180b1a8954235ba32fdaf60b7ea31963", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7a8c4bac72eafe31babbff175239f56a7000c7c070ea1543c9e504507cdd89cb53bf48a3e28c50de0385db3a7eab24f1d81ca52986adc5382acaf42c780e3dd"} 06:46:26 INFO - Running post-run listener: copy_logs_to_upload_dir 06:46:26 INFO - Copying logs to upload dir... 06:46:26 INFO - mkdir: /builds/slave/test/build/upload/logs 06:46:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.710536 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-05-25 06:46:26.933044) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 06:46:26.937157) ========= 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.oCa0I20s4P/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.YcCG7OEcHE/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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/164a277a44bf3be243a593ebfc706c7c77bbaba5f7c284f35c32666ae9a864c1887771744eafd4f04db46f7705dfe2c86f12985966b7bc7189826417ccb3c16e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d818b3cca77041f13f49f089bce60806c7c411641f02159b47a2fef220183fdc8c1c1fb423c6adefaad4e12505d63f03f10a18f92f7f65cb5211677f4a757307", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c5709fd79332eac631f905ef578211d4ff5170e739ff166d3e7062fec56793c873fb9743f4317fb560bfc1957e51600c180b1a8954235ba32fdaf60b7ea31963", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7a8c4bac72eafe31babbff175239f56a7000c7c070ea1543c9e504507cdd89cb53bf48a3e28c50de0385db3a7eab24f1d81ca52986adc5382acaf42c780e3dd"} build_url:https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011125 build_url: 'https://queue.taskcluster.net/v1/task/aloPBMQeQK2CzJ0U7NHm8Q/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/164a277a44bf3be243a593ebfc706c7c77bbaba5f7c284f35c32666ae9a864c1887771744eafd4f04db46f7705dfe2c86f12985966b7bc7189826417ccb3c16e", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d818b3cca77041f13f49f089bce60806c7c411641f02159b47a2fef220183fdc8c1c1fb423c6adefaad4e12505d63f03f10a18f92f7f65cb5211677f4a757307", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c5709fd79332eac631f905ef578211d4ff5170e739ff166d3e7062fec56793c873fb9743f4317fb560bfc1957e51600c180b1a8954235ba32fdaf60b7ea31963", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7a8c4bac72eafe31babbff175239f56a7000c7c070ea1543c9e504507cdd89cb53bf48a3e28c50de0385db3a7eab24f1d81ca52986adc5382acaf42c780e3dd"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-25 06:46:26.975975) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:46:26.976746) ========= 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.oCa0I20s4P/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.YcCG7OEcHE/Listeners TMPDIR=/var/folders/yw/zx37twqx21967_ygvr9fd2fr00000w/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.005266 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-25 06:46:27.031273) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 06:46:27.032046) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-25 06:46:27.032618) ========= ========= Total master_lag: 0.19 =========