builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0072 starttime: 1494868459.5 results: success (0) buildid: 20170515090825 builduid: 36998ef6d21e462cb961f18418cc3a0e revision: b0a01d9a55898e9cdaa3a544c8183da2141199fb ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.502527) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.502943) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.503217) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.526818) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.527079) ========= 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.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-15 10:14:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 56.7M=0s 2017-05-15 10:14:19 (56.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128591 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.677804) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.678116) ========= 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.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.019514 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:19.727084) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-15 10:14:19.727497) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b0a01d9a55898e9cdaa3a544c8183da2141199fb --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 b0a01d9a55898e9cdaa3a544c8183da2141199fb --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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-15 10:14:19,803 truncating revision to first 12 chars 2017-05-15 10:14:19,803 Setting DEBUG logging. 2017-05-15 10:14:19,803 attempt 1/10 2017-05-15 10:14:19,803 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b0a01d9a5589?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-15 10:14:21,324 unpacking tar archive at: mozilla-beta-b0a01d9a5589/testing/mozharness/ program finished with exit code 0 elapsedTime=1.771660 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-15 10:14:21.521592) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:21.521914) ========= script_repo_revision: b0a01d9a55898e9cdaa3a544c8183da2141199fb ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:21.522316) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:21.522582) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 10:14:21.541232) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-05-15 10:14:21.541513) ========= /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.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:14:21 INFO - MultiFileLogger online at 20170515 10:14:21 in /builds/slave/test 10:14:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:14:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:14:21 INFO - {'append_to_log': False, 10:14:21 INFO - 'base_work_dir': '/builds/slave/test', 10:14:21 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:14:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:14:21 INFO - 'branch': 'Mozilla-Beta', 10:14:21 INFO - 'buildbot_json_path': 'buildprops.json', 10:14:21 INFO - 'config_files': ('talos/mac_config.py',), 10:14:21 INFO - 'default_actions': ('clobber', 10:14:21 INFO - 'read-buildbot-config', 10:14:21 INFO - 'download-and-extract', 10:14:21 INFO - 'populate-webroot', 10:14:21 INFO - 'create-virtualenv', 10:14:21 INFO - 'install', 10:14:21 INFO - 'run-tests'), 10:14:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:14:21 INFO - 'download_minidump_stackwalk': True, 10:14:21 INFO - 'download_symbols': 'ondemand', 10:14:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:14:21 INFO - 'tooltool.py': '/tools/tooltool.py', 10:14:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:14:21 INFO - '/tools/misc-python/virtualenv.py')}, 10:14:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:14:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:14:21 INFO - 'gecko_profile': False, 10:14:21 INFO - 'gecko_profile_interval': 0, 10:14:21 INFO - 'installer_path': 'installer.exe', 10:14:21 INFO - 'log_level': 'info', 10:14:21 INFO - 'log_name': 'talos', 10:14:21 INFO - 'log_to_console': True, 10:14:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:14:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:14:21 INFO - 'opt_config_files': (), 10:14:21 INFO - 'pip_index': False, 10:14:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:14:21 INFO - 'cmd': ('bash', 10:14:21 INFO - '-c', 10:14:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:14:21 INFO - 'enabled': True, 10:14:21 INFO - 'halt_on_failure': False, 10:14:21 INFO - 'name': 'check_screen_resolution'},), 10:14:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:14:21 INFO - 'cmd': ('bash', 10:14:21 INFO - '-c', 10:14:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:14:21 INFO - 'enabled': True, 10:14:21 INFO - 'halt_on_failure': False, 10:14:21 INFO - 'name': 'check_screen_resolution'},), 10:14:21 INFO - 'run_cmd_checks_enabled': True, 10:14:21 INFO - 'suite': 'dromaeojs-e10s', 10:14:21 INFO - 'system_bits': '32', 10:14:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:14:21 INFO - 'title': 't-yosemite-r7-0072', 10:14:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:14:21 INFO - 'use_talos_json': True, 10:14:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:14:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:14:21 INFO - 'work_dir': 'build'} 10:14:21 INFO - [mozharness: 2017-05-15 17:14:21.755645Z] Running clobber step. 10:14:21 INFO - Running pre-action listener: _resource_record_pre_action 10:14:21 INFO - Running main action method: clobber 10:14:21 INFO - rmtree: /builds/slave/test/build 10:14:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:14:25 INFO - Running post-action listener: _resource_record_post_action 10:14:25 INFO - [mozharness: 2017-05-15 17:14:25.297351Z] Finished clobber step (success) 10:14:25 INFO - [mozharness: 2017-05-15 17:14:25.297499Z] Running read-buildbot-config step. 10:14:25 INFO - Running pre-action listener: _resource_record_pre_action 10:14:25 INFO - Running main action method: read_buildbot_config 10:14:25 INFO - Using buildbot properties: 10:14:25 INFO - { 10:14:25 INFO - "project": "", 10:14:25 INFO - "product": "firefox", 10:14:25 INFO - "script_repo_revision": "production", 10:14:25 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 10:14:25 INFO - "repository": "", 10:14:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 10:14:25 INFO - "buildid": "20170515090825", 10:14:25 INFO - "pgo_build": "False", 10:14:25 INFO - "basedir": "/builds/slave/test", 10:14:25 INFO - "buildnumber": 3, 10:14:25 INFO - "slavename": "t-yosemite-r7-0072", 10:14:25 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 10:14:25 INFO - "platform": "yosemite_r7", 10:14:25 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 10:14:25 INFO - "branch": "mozilla-beta", 10:14:25 INFO - "repo_path": "releases/mozilla-beta", 10:14:25 INFO - "revision": "b0a01d9a55898e9cdaa3a544c8183da2141199fb", 10:14:25 INFO - "stage_platform": "macosx64", 10:14:25 INFO - "builduid": "36998ef6d21e462cb961f18418cc3a0e", 10:14:25 INFO - "slavebuilddir": "test" 10:14:25 INFO - } 10:14:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 10:14:25 INFO - Running post-action listener: _resource_record_post_action 10:14:25 INFO - [mozharness: 2017-05-15 17:14:25.300908Z] Finished read-buildbot-config step (success) 10:14:25 INFO - [mozharness: 2017-05-15 17:14:25.301024Z] Running download-and-extract step. 10:14:25 INFO - Running pre-action listener: _resource_record_pre_action 10:14:25 INFO - Running main action method: download_and_extract 10:14:25 INFO - mkdir: /builds/slave/test/build/tests 10:14:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:14:25 INFO - trying https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 10:14:25 INFO - Downloading https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:14:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 10:14:26 INFO - Downloaded 1287 bytes. 10:14:26 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:14:26 INFO - Using the following test package requirements: 10:14:26 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 10:14:26 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:14:26 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 10:14:26 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 10:14:26 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:14:26 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 10:14:26 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:14:26 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 10:14:26 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 10:14:26 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 10:14:26 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:14:26 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 10:14:26 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 10:14:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:14:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:14:26 INFO - Fetch https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:14:27 INFO - Expected file size: 51224155 10:14:27 INFO - Obtained file size: 51224155 10:14:44 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 10:14:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:14:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:14:44 INFO - Fetch https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:14:46 INFO - Expected file size: 51224155 10:14:46 INFO - Obtained file size: 51224155 10:15:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 10:15:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 10:15:01 INFO - Fetch https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 10:15:02 INFO - Expected file size: 14404449 10:15:02 INFO - Obtained file size: 14404449 10:15: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')]} 10:15:03 INFO - trying https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:15:03 INFO - Downloading https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 10:15:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 10:15:04 INFO - Downloaded 59539518 bytes. 10:15:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:15:04 INFO - mkdir: /builds/slave/test/properties 10:15:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:15:04 INFO - Writing to file /builds/slave/test/properties/build_url 10:15:04 INFO - Contents: 10:15:04 INFO - build_url:https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:15:05 INFO - Running post-action listener: _resource_record_post_action 10:15:05 INFO - Running post-action listener: set_extra_try_arguments 10:15:05 INFO - [mozharness: 2017-05-15 17:15:05.387061Z] Finished download-and-extract step (success) 10:15:05 INFO - [mozharness: 2017-05-15 17:15:05.387185Z] Running populate-webroot step. 10:15:05 INFO - Running pre-action listener: _resource_record_pre_action 10:15:05 INFO - Running main action method: populate_webroot 10:15:05 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:15:05 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:15:05 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:15:05 INFO - '--tppagecycles', 10:15:05 INFO - '7'], 10:15:05 INFO - 'tests': ['tsvgm']}}, 10:15:05 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:15:05 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:15:05 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:15:05 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:15:05 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:15:05 INFO - 'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:15:05 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:15:05 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:15:05 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:15:05 INFO - 'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['damp', 'tps']}, 10:15:05 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:15:05 INFO - 'tests': ['damp', 'tps']}, 10:15:05 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['dromaeo_dom']}, 10:15:05 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:15:05 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['basic_compositor_video']}, 10:15:05 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:15:05 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['a11yr', 10:15:05 INFO - 'ts_paint', 10:15:05 INFO - 'tpaint', 10:15:05 INFO - 'sessionrestore', 10:15:05 INFO - 'sessionrestore_no_auto_restore', 10:15:05 INFO - 'tabpaint']}, 10:15:05 INFO - 'other-e10s': {'tests': ['a11yr', 10:15:05 INFO - 'ts_paint', 10:15:05 INFO - 'tpaint', 10:15:05 INFO - 'sessionrestore', 10:15:05 INFO - 'sessionrestore_no_auto_restore', 10:15:05 INFO - 'tabpaint']}, 10:15:05 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:15:05 INFO - 'ts_paint', 10:15:05 INFO - 'tpaint', 10:15:05 INFO - 'sessionrestore', 10:15:05 INFO - 'sessionrestore_no_auto_restore', 10:15:05 INFO - 'tabpaint']}, 10:15:05 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:15:05 INFO - 'ts_paint', 10:15:05 INFO - 'tpaint', 10:15:05 INFO - 'sessionrestore', 10:15:05 INFO - 'sessionrestore_no_auto_restore', 10:15:05 INFO - 'tabpaint']}, 10:15:05 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['a11yr', 10:15:05 INFO - 'ts_paint', 10:15:05 INFO - 'tpaint', 10:15:05 INFO - 'sessionrestore', 10:15:05 INFO - 'sessionrestore_no_auto_restore', 10:15:05 INFO - 'tabpaint']}, 10:15:05 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['a11yr', 10:15:05 INFO - 'ts_paint', 10:15:05 INFO - 'tpaint', 10:15:05 INFO - 'sessionrestore', 10:15:05 INFO - 'sessionrestore_no_auto_restore', 10:15:05 INFO - 'tabpaint']}, 10:15:05 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['tsvgx', 10:15:05 INFO - 'tsvgr_opacity', 10:15:05 INFO - 'tart', 10:15:05 INFO - 'tscrollx', 10:15:05 INFO - 'cart', 10:15:05 INFO - 'tsvg_static']}, 10:15:05 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:15:05 INFO - 'tsvgr_opacity', 10:15:05 INFO - 'tart', 10:15:05 INFO - 'tscrollx', 10:15:05 INFO - 'cart', 10:15:05 INFO - 'tsvg_static']}, 10:15:05 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:15:05 INFO - 'talos_options': ['--disable-e10s'], 10:15:05 INFO - 'tests': ['tp5o']}, 10:15:05 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:15:05 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:15:05 INFO - 'talos_options': ['--disable-e10s', 10:15:05 INFO - '--xperf_path', 10:15:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:15:05 INFO - 'tests': ['tp5n']}, 10:15:05 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:15:05 INFO - 'talos_options': ['--xperf_path', 10:15:05 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:15:05 INFO - 'tests': ['tp5n']}}, 10:15:05 INFO - 'talos.zip': {'path': '', 10:15:05 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:15:05 INFO - Running post-action listener: _resource_record_post_action 10:15:05 INFO - [mozharness: 2017-05-15 17:15:05.406931Z] Finished populate-webroot step (success) 10:15:05 INFO - [mozharness: 2017-05-15 17:15:05.407070Z] Running create-virtualenv step. 10:15:05 INFO - Running pre-action listener: _resource_record_pre_action 10:15:05 INFO - Running main action method: create_virtualenv 10:15:05 INFO - Creating virtualenv /builds/slave/test/build/venv 10:15:05 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:15:05 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:15:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:15:05 INFO - Using real prefix '/tools/python27' 10:15:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:15:06 INFO - Installing distribute.............................................................................................................................................................................................done. 10:15:09 INFO - Installing pip.................done. 10:15:09 INFO - Return code: 0 10:15:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:15:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:15:09 INFO - Reading from file tmpfile_stdout 10:15:09 INFO - Output received: 10:15:09 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:15:09 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:15:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb3b57f710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015a6d30>, '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 0x1014cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10159bbe8>, '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.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15: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', 'pip>=1.5'] in /builds/slave/test/build 10:15: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 pip>=1.5 10:15:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJ9KWcoANr/Render', 10:15:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:09 INFO - 'HOME': '/Users/cltbld', 10:15:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:09 INFO - 'LOGNAME': 'cltbld', 10:15:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:09 INFO - 'MOZ_NO_REMOTE': '1', 10:15:09 INFO - 'NO_EM_RESTART': '1', 10:15:09 INFO - 'PAGER': '/bin/cat', 10:15:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:09 INFO - 'PWD': '/builds/slave/test', 10:15:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:09 INFO - 'SHELL': '/bin/bash', 10:15:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vvf0PtqMHs/Listeners', 10:15:09 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 10:15:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:09 INFO - 'USER': 'cltbld', 10:15:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:09 INFO - 'XPC_FLAGS': '0x0', 10:15:09 INFO - 'XPC_SERVICE_NAME': '0', 10:15:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:09 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:15:09 INFO - Cleaning up... 10:15:09 INFO - Return code: 0 10:15:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:15:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15: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', '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 0x1017c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb3b57f710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015a6d30>, '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 0x1014cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10159bbe8>, '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.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15: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', 'psutil>=3.1.1'] in /builds/slave/test/build 10:15: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 psutil>=3.1.1 10:15:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJ9KWcoANr/Render', 10:15:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:09 INFO - 'HOME': '/Users/cltbld', 10:15:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:09 INFO - 'LOGNAME': 'cltbld', 10:15:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:09 INFO - 'MOZ_NO_REMOTE': '1', 10:15:09 INFO - 'NO_EM_RESTART': '1', 10:15:09 INFO - 'PAGER': '/bin/cat', 10:15:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:09 INFO - 'PWD': '/builds/slave/test', 10:15:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:09 INFO - 'SHELL': '/bin/bash', 10:15:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vvf0PtqMHs/Listeners', 10:15:09 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 10:15:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:09 INFO - 'USER': 'cltbld', 10:15:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:09 INFO - 'XPC_FLAGS': '0x0', 10:15:09 INFO - 'XPC_SERVICE_NAME': '0', 10:15:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:09 INFO - Downloading/unpacking psutil>=3.1.1 10:15: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 10:15: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 10:15: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 10:15: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 10:15:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:15:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:15:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:15:11 INFO - Installing collected packages: psutil 10:15:11 INFO - Running setup.py install for psutil 10:15:11 INFO - building 'psutil._psutil_osx' extension 10:15:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:15:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:15:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:15:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:15:12 INFO - building 'psutil._psutil_posix' extension 10:15:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:15:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:15:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:15:12 INFO - ^ 10:15:12 INFO - 1 warning generated. 10:15:12 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:15:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:15:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:15:12 INFO - Successfully installed psutil 10:15:12 INFO - Cleaning up... 10:15:12 INFO - Return code: 0 10:15:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:15: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')]} 10:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15: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')]} 10:15:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb3b57f710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015a6d30>, '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 0x1014cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10159bbe8>, '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.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:15:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:15:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJ9KWcoANr/Render', 10:15:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:12 INFO - 'HOME': '/Users/cltbld', 10:15:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:12 INFO - 'LOGNAME': 'cltbld', 10:15:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:12 INFO - 'MOZ_NO_REMOTE': '1', 10:15:12 INFO - 'NO_EM_RESTART': '1', 10:15:12 INFO - 'PAGER': '/bin/cat', 10:15:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:12 INFO - 'PWD': '/builds/slave/test', 10:15:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:12 INFO - 'SHELL': '/bin/bash', 10:15:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vvf0PtqMHs/Listeners', 10:15:12 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 10:15:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:12 INFO - 'USER': 'cltbld', 10:15:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:12 INFO - 'XPC_FLAGS': '0x0', 10:15:12 INFO - 'XPC_SERVICE_NAME': '0', 10:15:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:15:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:15:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:15:14 INFO - Installing collected packages: mozsystemmonitor 10:15:14 INFO - Running setup.py install for mozsystemmonitor 10:15:14 INFO - Successfully installed mozsystemmonitor 10:15:14 INFO - Cleaning up... 10:15:14 INFO - Return code: 0 10:15:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:15:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1017c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb3b57f710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015a6d30>, '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 0x1014cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10159bbe8>, '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.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:15:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:15:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJ9KWcoANr/Render', 10:15:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:14 INFO - 'HOME': '/Users/cltbld', 10:15:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:14 INFO - 'LOGNAME': 'cltbld', 10:15:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:14 INFO - 'MOZ_NO_REMOTE': '1', 10:15:14 INFO - 'NO_EM_RESTART': '1', 10:15:14 INFO - 'PAGER': '/bin/cat', 10:15:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:14 INFO - 'PWD': '/builds/slave/test', 10:15:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:14 INFO - 'SHELL': '/bin/bash', 10:15:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vvf0PtqMHs/Listeners', 10:15:14 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 10:15:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:14 INFO - 'USER': 'cltbld', 10:15:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:14 INFO - 'XPC_FLAGS': '0x0', 10:15:14 INFO - 'XPC_SERVICE_NAME': '0', 10:15:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:14 INFO - Downloading/unpacking jsonschema==2.5.1 10:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:15:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:16 INFO - Downloading functools32-3.2.3-2.tar.gz 10:15:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:15:16 INFO - warning: no files found matching '*.txt' 10:15:16 INFO - no previously-included directories found matching 'build' 10:15:16 INFO - no previously-included directories found matching 'dist' 10:15:16 INFO - no previously-included directories found matching '.git*' 10:15:16 INFO - Installing collected packages: jsonschema, functools32 10:15:16 INFO - Running setup.py install for functools32 10:15:16 INFO - warning: no files found matching '*.txt' 10:15:16 INFO - no previously-included directories found matching 'build' 10:15:16 INFO - no previously-included directories found matching 'dist' 10:15:16 INFO - no previously-included directories found matching '.git*' 10:15:16 INFO - Successfully installed jsonschema functools32 10:15:16 INFO - Cleaning up... 10:15:16 INFO - Return code: 0 10:15:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:15:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15: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')]} 10:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:17 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 0x1017c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb3b57f710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015a6d30>, '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 0x1014cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10159bbe8>, '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.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:15:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJ9KWcoANr/Render', 10:15:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:17 INFO - 'HOME': '/Users/cltbld', 10:15:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:17 INFO - 'LOGNAME': 'cltbld', 10:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:17 INFO - 'MOZ_NO_REMOTE': '1', 10:15:17 INFO - 'NO_EM_RESTART': '1', 10:15:17 INFO - 'PAGER': '/bin/cat', 10:15:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:17 INFO - 'PWD': '/builds/slave/test', 10:15:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:17 INFO - 'SHELL': '/bin/bash', 10:15:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vvf0PtqMHs/Listeners', 10:15:17 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 10:15:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:17 INFO - 'USER': 'cltbld', 10:15:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:17 INFO - 'XPC_FLAGS': '0x0', 10:15:17 INFO - 'XPC_SERVICE_NAME': '0', 10:15:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:15:17 INFO - Cleaning up... 10:15:17 INFO - Return code: 0 10:15:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:15: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')]} 10:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15: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')]} 10:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1017c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb3b57f710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015a6d30>, '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 0x1014cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10159bbe8>, '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.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:15:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:15:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJ9KWcoANr/Render', 10:15:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:18 INFO - 'HOME': '/Users/cltbld', 10:15:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:18 INFO - 'LOGNAME': 'cltbld', 10:15:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:18 INFO - 'MOZ_NO_REMOTE': '1', 10:15:18 INFO - 'NO_EM_RESTART': '1', 10:15:18 INFO - 'PAGER': '/bin/cat', 10:15:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:18 INFO - 'PWD': '/builds/slave/test', 10:15:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:18 INFO - 'SHELL': '/bin/bash', 10:15:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vvf0PtqMHs/Listeners', 10:15:18 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 10:15:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:18 INFO - 'USER': 'cltbld', 10:15:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:18 INFO - 'XPC_FLAGS': '0x0', 10:15:18 INFO - 'XPC_SERVICE_NAME': '0', 10:15:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:18 INFO - Downloading/unpacking blobuploader==1.2.4 10:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:20 INFO - Downloading blobuploader-1.2.4.tar.gz 10:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:15:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:15:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:21 INFO - Downloading docopt-0.6.1.tar.gz 10:15:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:15:21 INFO - Installing collected packages: blobuploader, requests, docopt 10:15:21 INFO - Running setup.py install for blobuploader 10:15:21 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:15:21 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:15:21 INFO - Running setup.py install for requests 10:15:21 INFO - Running setup.py install for docopt 10:15:21 INFO - Successfully installed blobuploader requests docopt 10:15:21 INFO - Cleaning up... 10:15:21 INFO - Return code: 0 10:15:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:15: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')]} 10:15:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15: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')]} 10:15:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:21 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 0x1017c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb3b57f710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015a6d30>, '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 0x1014cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10159bbe8>, '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.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:15:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:15:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJ9KWcoANr/Render', 10:15:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:22 INFO - 'HOME': '/Users/cltbld', 10:15:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:22 INFO - 'LOGNAME': 'cltbld', 10:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:22 INFO - 'MOZ_NO_REMOTE': '1', 10:15:22 INFO - 'NO_EM_RESTART': '1', 10:15:22 INFO - 'PAGER': '/bin/cat', 10:15:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:22 INFO - 'PWD': '/builds/slave/test', 10:15:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:22 INFO - 'SHELL': '/bin/bash', 10:15:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vvf0PtqMHs/Listeners', 10:15:22 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 10:15:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:22 INFO - 'USER': 'cltbld', 10:15:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:22 INFO - 'XPC_FLAGS': '0x0', 10:15:22 INFO - 'XPC_SERVICE_NAME': '0', 10:15:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:15:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-lgcv03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:15:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-isfgbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:15:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-aObvAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:15:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-E71CYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:15:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-dzPAk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:15:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-1fpEqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:15:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-qB3OCx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:15:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:15:22 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-lW2j18-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:15:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-koe3x7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:15:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-olmZSd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:15:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-zKFHfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:15:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-8fni2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:15:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-Ivxg8Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:15:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-DV8h9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-l8tnqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:15:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-9TxDEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:15:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:15:23 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-ylXEct-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:15:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:15:24 INFO - Running setup.py install for manifestparser 10:15:24 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:15:24 INFO - Running setup.py install for mozcrash 10:15:24 INFO - Running setup.py install for mozdebug 10:15:24 INFO - Running setup.py install for mozdevice 10:15:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:15:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:15:24 INFO - Running setup.py install for mozfile 10:15:24 INFO - Running setup.py install for mozhttpd 10:15:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:15:24 INFO - Running setup.py install for mozinfo 10:15:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Running setup.py install for mozInstall 10:15:25 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Running setup.py install for mozleak 10:15:25 INFO - Running setup.py install for mozlog 10:15:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Running setup.py install for moznetwork 10:15:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:15:25 INFO - Running setup.py install for mozprocess 10:15:25 INFO - Running setup.py install for mozprofile 10:15:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:15:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:15:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:15:26 INFO - Running setup.py install for mozrunner 10:15:26 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:15:26 INFO - Running setup.py install for mozscreenshot 10:15:26 INFO - Running setup.py install for moztest 10:15:26 INFO - Running setup.py install for mozversion 10:15:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:15:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:15:26 INFO - Cleaning up... 10:15:26 INFO - Return code: 0 10:15:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:26 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 0x1017c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb3b57f710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015a6d30>, '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 0x1014cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10159bbe8>, '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.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:15:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:15:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJ9KWcoANr/Render', 10:15:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:26 INFO - 'HOME': '/Users/cltbld', 10:15:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:26 INFO - 'LOGNAME': 'cltbld', 10:15:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:26 INFO - 'MOZ_NO_REMOTE': '1', 10:15:26 INFO - 'NO_EM_RESTART': '1', 10:15:26 INFO - 'PAGER': '/bin/cat', 10:15:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:26 INFO - 'PWD': '/builds/slave/test', 10:15:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:26 INFO - 'SHELL': '/bin/bash', 10:15:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vvf0PtqMHs/Listeners', 10:15:26 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 10:15:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:26 INFO - 'USER': 'cltbld', 10:15:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:26 INFO - 'XPC_FLAGS': '0x0', 10:15:26 INFO - 'XPC_SERVICE_NAME': '0', 10:15:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:15:26 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-rrqbeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:15:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-uQcZyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:15:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-H2SMeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:15:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-GeqHIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:15:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-fB9bh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:15:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-nyLZ0a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:15:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-KdqKxd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:15:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-xX0_ZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:15:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-4MAjza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:15:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:15:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:15:27 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-KqHn2z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:15:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-J3T9Vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:15:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-tmA02y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:15:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-qK9N1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:15:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-EFuIQG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-AEoyE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:15:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-IZ8o4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:15:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:15:28 INFO - Running setup.py (path:/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/pip-bmUqAP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:15:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:15:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:15:30 INFO - Downloading blessings-1.6.tar.gz 10:15:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:15:30 INFO - Installing collected packages: blessings 10:15:30 INFO - Running setup.py install for blessings 10:15:30 INFO - Successfully installed blessings 10:15:30 INFO - Cleaning up... 10:15:30 INFO - Return code: 0 10:15:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:15:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:15:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:15:30 INFO - Reading from file tmpfile_stdout 10:15:30 INFO - Current package versions: 10:15:30 INFO - blessings == 1.6 10:15:30 INFO - blobuploader == 1.2.4 10:15:30 INFO - docopt == 0.6.1 10:15:30 INFO - functools32 == 3.2.3-2 10:15:30 INFO - jsonschema == 2.5.1 10:15:30 INFO - manifestparser == 1.1 10:15:30 INFO - mozInstall == 1.12 10:15:30 INFO - mozcrash == 1.0 10:15:30 INFO - mozdebug == 0.1 10:15:30 INFO - mozdevice == 0.49 10:15:30 INFO - mozfile == 1.2 10:15:30 INFO - mozhttpd == 0.7 10:15:30 INFO - mozinfo == 0.9 10:15:30 INFO - mozleak == 0.1 10:15:30 INFO - mozlog == 3.4 10:15:30 INFO - moznetwork == 0.27 10:15:30 INFO - mozprocess == 0.25 10:15:30 INFO - mozprofile == 0.28 10:15:30 INFO - mozrunner == 6.13 10:15:30 INFO - mozscreenshot == 0.1 10:15:30 INFO - mozsystemmonitor == 0.3 10:15:30 INFO - moztest == 0.8 10:15:30 INFO - mozversion == 1.4 10:15:30 INFO - psutil == 3.1.1 10:15:30 INFO - requests == 1.2.3 10:15:30 INFO - wsgiref == 0.1.2 10:15:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:15:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:30 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 0x1017c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb3b57f710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015a6d30>, '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 0x1014cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10159bbe8>, '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.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:15:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:15:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJ9KWcoANr/Render', 10:15:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:30 INFO - 'HOME': '/Users/cltbld', 10:15:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:30 INFO - 'LOGNAME': 'cltbld', 10:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:30 INFO - 'MOZ_NO_REMOTE': '1', 10:15:30 INFO - 'NO_EM_RESTART': '1', 10:15:30 INFO - 'PAGER': '/bin/cat', 10:15:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:30 INFO - 'PWD': '/builds/slave/test', 10:15:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:30 INFO - 'SHELL': '/bin/bash', 10:15:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vvf0PtqMHs/Listeners', 10:15:30 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 10:15:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:30 INFO - 'USER': 'cltbld', 10:15:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:30 INFO - 'XPC_FLAGS': '0x0', 10:15:30 INFO - 'XPC_SERVICE_NAME': '0', 10:15:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:15:31 INFO - Cleaning up... 10:15:31 INFO - Return code: 0 10:15:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:15:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:15:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:15:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1017c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1017cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb3b57f710>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1015a6d30>, '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 0x1014cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10159bbe8>, '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.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:15:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:15:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJ9KWcoANr/Render', 10:15:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:31 INFO - 'HOME': '/Users/cltbld', 10:15:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:31 INFO - 'LOGNAME': 'cltbld', 10:15:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:31 INFO - 'MOZ_NO_REMOTE': '1', 10:15:31 INFO - 'NO_EM_RESTART': '1', 10:15:31 INFO - 'PAGER': '/bin/cat', 10:15:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:31 INFO - 'PWD': '/builds/slave/test', 10:15:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:31 INFO - 'SHELL': '/bin/bash', 10:15:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vvf0PtqMHs/Listeners', 10:15:31 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 10:15:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:31 INFO - 'USER': 'cltbld', 10:15:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:31 INFO - 'XPC_FLAGS': '0x0', 10:15:31 INFO - 'XPC_SERVICE_NAME': '0', 10:15:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:15:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:15:31 INFO - Cleaning up... 10:15:31 INFO - Return code: 0 10:15:31 INFO - Running post-action listener: _resource_record_post_action 10:15:31 INFO - Running post-action listener: _start_resource_monitoring 10:15:31 INFO - Starting resource monitoring. 10:15:31 INFO - [mozharness: 2017-05-15 17:15:31.336256Z] Finished create-virtualenv step (success) 10:15:31 INFO - [mozharness: 2017-05-15 17:15:31.337234Z] Running install step. 10:15:31 INFO - Running pre-action listener: _resource_record_pre_action 10:15:31 INFO - Running main action method: install 10:15:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:15:31 INFO - Reading from file tmpfile_stdout 10:15:31 INFO - Detecting whether we're running mozinstall >=1.0... 10:15:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:15:31 INFO - Reading from file tmpfile_stdout 10:15:31 INFO - Output received: 10:15:31 INFO - Usage: mozinstall [options] installer 10:15:31 INFO - Options: 10:15:31 INFO - -h, --help show this help message and exit 10:15:31 INFO - -d DEST, --destination=DEST 10:15:31 INFO - Directory to install application into. [default: 10:15:31 INFO - "/builds/slave/test"] 10:15:31 INFO - --app=APP Application being installed. [default: firefox] 10:15:31 INFO - mkdir: /builds/slave/test/build/application 10:15:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:15:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 10:15:47 INFO - Reading from file tmpfile_stdout 10:15:47 INFO - Output received: 10:15:47 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 10:15:47 INFO - Running post-action listener: _resource_record_post_action 10:15:47 INFO - [mozharness: 2017-05-15 17:15:47.122315Z] Finished install step (success) 10:15:47 INFO - [mozharness: 2017-05-15 17:15:47.122457Z] Running run-tests step. 10:15:47 INFO - Running pre-action listener: _resource_record_pre_action 10:15:47 INFO - Running main action method: run_tests 10:15:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:15:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:15:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:15:47 INFO - 2017-05-15 10:15:47.137 screenresolution[1961:10273] starting screenresolution argv=screenresolution get 10:15:47 INFO - 2017-05-15 10:15:47.140 screenresolution[1961:10273] Display 0: 1600x1200x32@60 10:15:47 INFO - 2017-05-15 10:15:47.146 screenresolution[1962:10275] starting screenresolution argv=screenresolution list 10:15:47 INFO - Available Modes on Display 0 10:15:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:15:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:15:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:15:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:15:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:15:47 INFO - Intel Iris: 10:15:47 INFO - Chipset Model: Intel Iris 10:15:47 INFO - Type: GPU 10:15:47 INFO - Bus: Built-In 10:15:47 INFO - VRAM (Dynamic, Max): 1536 MB 10:15:47 INFO - Vendor: Intel (0x8086) 10:15:47 INFO - Device ID: 0x0a2e 10:15:47 INFO - Revision ID: 0x0009 10:15:47 INFO - Displays: 10:15:47 INFO - Display: 10:15:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:15:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:15:47 INFO - Display Serial Number: 1600x1200 60 10:15:47 INFO - Main Display: Yes 10:15:47 INFO - Mirror: Off 10:15:47 INFO - Online: Yes 10:15:47 INFO - Rotation: Supported 10:15:47 INFO - Return code: 0 10:15:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:15:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:15:47 INFO - Python 2.7.3 10:15:47 INFO - Return code: 0 10:15:47 INFO - grabbing minidump binary from tooltool 10:15:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:15:47 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 0x1015a6d30>, '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 0x1014cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10159bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:15:47 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:15:47 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:15:47 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:15:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:15:47 INFO - Return code: 0 10:15:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:15:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:15:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:15:47 INFO - ENV: RUST_BACKTRACE is now 1 10:15:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:15:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:15:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:15:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0072 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:15:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.tJ9KWcoANr/Render', 10:15:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:15:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:15:47 INFO - 'HOME': '/Users/cltbld', 10:15:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:15:47 INFO - 'LOGNAME': 'cltbld', 10:15:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:15:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:15:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:15:47 INFO - 'MOZ_NO_REMOTE': '1', 10:15:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:15:47 INFO - 'NO_EM_RESTART': '1', 10:15:47 INFO - 'PAGER': '/bin/cat', 10:15:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:15:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:15:47 INFO - 'PWD': '/builds/slave/test', 10:15:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:15:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:15:47 INFO - 'RUST_BACKTRACE': '1', 10:15:47 INFO - 'SHELL': '/bin/bash', 10:15:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Vvf0PtqMHs/Listeners', 10:15:47 INFO - 'TMPDIR': '/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/', 10:15:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:15:47 INFO - 'USER': 'cltbld', 10:15:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:15:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:15:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:15:47 INFO - 'XPC_FLAGS': '0x0', 10:15:47 INFO - 'XPC_SERVICE_NAME': '0', 10:15:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:15:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0072', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:15:47 INFO - mozversion application_buildid: 20170515090825 10:15:47 INFO - mozversion application_changeset: b0a01d9a55898e9cdaa3a544c8183da2141199fb 10:15:47 INFO - mozversion application_display_name: Firefox 10:15:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:15:47 INFO - mozversion application_name: Firefox 10:15:47 INFO - mozversion application_remotingname: firefox 10:15:47 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:15:47 INFO - mozversion application_vendor: Mozilla 10:15:47 INFO - mozversion application_version: 54.0 10:15:47 INFO - mozversion platform_buildid: 20170515090825 10:15:47 INFO - mozversion platform_changeset: b0a01d9a55898e9cdaa3a544c8183da2141199fb 10:15:47 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:15:47 INFO - mozversion platform_version: 54.0 10:15:47 INFO - using testdate: 1494868547 10:15:47 INFO - actual date: 1494868547 10:15:47 INFO - starting webserver on 'localhost:49327' 10:15:47 INFO - SUITE-START | Running 2 tests 10:15:47 INFO - TEST-START | dromaeo_css 10:15:47 INFO - operating with platform_type : mac_ 10:15:47 INFO - Initialising browser for dromaeo_css test... 10:15:47 INFO - TEST-INFO | started process 1972 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmp_Bh_GR/profile http://localhost:49327/getInfo.html) 10:15:50 INFO - PROCESS | 1972 | 2017-05-15 10:15:50.650 plugin-container[1975:10444] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 10:15:50 INFO - PROCESS | 1972 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:15:50 INFO - PROCESS | 1972 | 2017-05-15 10:15:50.651 plugin-container[1975:10444] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e2b, name = 'com.apple.CFPasteboardClient' 10:15:50 INFO - PROCESS | 1972 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:15:50 INFO - PROCESS | 1972 | 2017-05-15 10:15:50.651 plugin-container[1975:10444] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:15:50 INFO - PROCESS | 1972 | __metrics Screen width/height:1600/1200 10:15:50 INFO - PROCESS | 1972 | colorDepth:24 10:15:50 INFO - PROCESS | 1972 | Browser inner width/height: 1024/689 10:15:50 INFO - PROCESS | 1972 | __metrics 10:15:50 ERROR - PROCESS | 1972 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 10:15:51 INFO - TEST-INFO | 1972: exit 0 10:15:51 INFO - Browser initialized. 10:15:51 INFO - Running cycle 1/1 for dromaeo_css test... 10:15:51 INFO - TEST-INFO | started process 1976 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmp_Bh_GR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:15:53 INFO - PROCESS | 1976 | 2017-05-15 10:15:53.652 plugin-container[1977:10577] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8643, name = 'com.apple.tsm.portname' 10:15:53 INFO - PROCESS | 1976 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:15:53 INFO - PROCESS | 1976 | 2017-05-15 10:15:53.653 plugin-container[1977:10577] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8753, name = 'com.apple.CFPasteboardClient' 10:15:53 INFO - PROCESS | 1976 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:15:53 INFO - PROCESS | 1976 | 2017-05-15 10:15:53.653 plugin-container[1977:10577] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:16:03 INFO - PROCESS | 1976 | RSS: Main: 174379008 10:16:03 INFO - PROCESS | 1976 | 10:19:06 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49327/tests/dromaeo/cssquery-ext.html) 10:19:08 INFO - PROCESS | 1976 | RSS: Main: 189898752 10:19:08 INFO - PROCESS | 1976 | 10:22:10 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/dromaeo/cssquery-ext.html (next: http://localhost:49327/tests/dromaeo/cssquery-jquery.html) 10:22:11 INFO - PROCESS | 1976 | RSS: Main: 202113024 10:22:11 INFO - PROCESS | 1976 | 10:25:13 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49327/tests/dromaeo/cssquery-mootools.html) 10:25:13 INFO - PROCESS | 1976 | RSS: Main: 203640832 10:25:13 INFO - PROCESS | 1976 | 10:28:15 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49327/tests/dromaeo/cssquery-prototype.html) 10:28:16 INFO - PROCESS | 1976 | RSS: Main: 206688256 10:28:16 INFO - PROCESS | 1976 | 10:31:19 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49327/tests/dromaeo/cssquery-yui.html) 10:31:19 INFO - PROCESS | 1976 | RSS: Main: 210055168 10:31:19 INFO - PROCESS | 1976 | 10:34:22 INFO - PROCESS | 1976 | Cycle 1(1): loaded http://localhost:49327/tests/dromaeo/cssquery-yui.html (next: http://localhost:49327/tests/dromaeo/cssquery-dojo.html) 10:34:23 INFO - PROCESS | 1976 | RSS: Main: 208220160 10:34:23 INFO - PROCESS | 1976 | 10:34:23 INFO - PROCESS | 1976 | __start_tp_report 10:34:23 INFO - PROCESS | 1976 | _x_x_mozilla_page_load 10:34:23 INFO - PROCESS | 1976 | _x_x_mozilla_page_load_details 10:34:23 INFO - PROCESS | 1976 | |i|pagename|runs| 10:34:23 INFO - PROCESS | 1976 | |0;dojo.html;3083.2754302738854;3603.117236277111;3645.2891686121216;3649.3066317399685;3652.123490362314;6094.634321940681;6094.847628809276;6148.292946311176;6174.598651087676;6179.5983261087995;6102.145699602059;6173.074038894169;6176.91903916815;6183.19618449601;6199.349068347826;4304.97847510762;5568.081266591018;5654.519365853907;5698;5777.768889244425;4896.534829191221;5739.282589676291;5754.683492407937;5773.682447465388;5775.509081728037;4826.745046287955;5518.475744804237;5556.055470570012;5759.568032397566;5769.884602307951;5752.942470575283;5795.449432303945;5866.120081987693;5889.352171261158;5945.16767652529;5682.566938881437;5689.203511508392;5737;5772.826815195551;5782.421757824226;4771.308160316769;6196.256449226122;6272.184615999937;6286.057091436322;6292.5595208335435;6227.564070515065;6304.085907543381;6305.527085468608;6468.029795530661;6473.611583305017;6687.465002799813;6772.5936443813525;6775.1531058617675;6776.525643204977;6778.491613129035;6191.102290167901;6223.96888015566;6270.2475702915945;6276.968615156895;6281.654509002003;5176.456472070454;6499.3175716549085;6521.47828173745;6586.967065164644;6599.340065993362;6395.6482393468805;6406.839829004312;6550.868982620321;6573.638449885302;6586.407223349922;6687.966560167168;6708.6981085850275;6755.527113102035;6763.966180169067;6769.39075483199;6236.719347629277;6558.311377305409;6669.266380698119;6675.732970681119;6688.83277918056;6378.043293505918;6388.361163883575;6443.742250309935;6475.061116138134;6475.255345635218;5329.440408757102;5348.384935732441;5352.785888564414;5380.704061276589;5404.8108316208745;4761.4524329701835;4772.928406073912;4785.832495862628;4801.327814106028;4821.807127714922;6142.897836592581;6180.969095154495;6183.96908015457;6222.937770622327;6278.4035516625845;5174.197999310113;6056.394360563909;6335.588186767845;6773.796786096326;6785.5250132490255;5165.586753059746;6003.669798161056;6140.2281855057;6182.443580077815;6294.276158241769;5351.892962140735;5937.614055086406;5940.940590594125;5962.25471816023;6035.064564992434;6784.830379240559;6825.726970921306;6922.10012698346;6957.512974091967;6981.79054628362;6723.798286051427;6737.66311684404;6739.123913891162;6759.729610815512;6779.89830152558;6412.294647588855;6598.581305019444;6928.411085057625;6942.895856562257;6964.582125072512;6272.8118156455375;6279.026750853625;6452.741890324334;6458.935410645833;6578.210614726264;5498.945010549844;6622.503312251697;6630.535862489773;6680.732770689312;6690.19717633887;6389.424951754178;6418.101465794886;6432.09950606906;6444;6476.4495017924;6183.258009038765;6228.501719862309;6336;6466.224053113657;6477.967610161919;6223.906641400473;6286.37136286386;6340.809775706736;6354.142190804182;6367.172267605181;6495.967520162558;6502.992036234391;6505.349465053457;6519.054737063005;6519.119918810899;6182.319944805978;6194;6290.2766181889665;6301.243850737757;6319.652419116993 10:34:23 INFO - PROCESS | 1976 | |1;ext.html;755.66372964031;757.5341165183413;839.8614228652275;964.9614015439382;977.628501169555;32344;36898.26203475929;41165.35338586457;43441.565584344185;43493.56506434935;41098.767036988866;41230;41333;41446.58553414465;41469.792651036776;7134.536255143399;8420.915790842075;11080.833787493195;11342.71643208918;11524.19330646855;11253.493592788343;11368.97679208871;11378.37418941958;11397;11397.48711307989;11393.601223957163;11395.943020284887;11401.885981140165;11427.657170284863;11460.885391146065;11293.153013523977;11322.999999999958;11336;11342.149338799623;11353.545858165664;9878.209743220525;11057.447127643665;11086.390248536327;11094.167937404727;11107.222494425394;35519;35690.821545892366;35747.642523574694;35826.28347433037;35878.28243435143;3777.452269420946;3913.1391093959282;3926.3128952433494;3974.880753577397;4036.1322315702264;26156.607650885253;26379.868100659565;26479.602805957926;28085.438291234062;28132.578011329846;3156.4002839460427;3187.5378070179695;3200.039988003613;3268.542404063433;3269.1500209945584;343639;345064;347274;351830.2408487941;356149.2192539021;37004.88985330444;44635.776821116335;44696.3295550567;44734.32898506525;44792.77603611961;46503;46535.06929861384;46567;46636.533634663894;46721.83195420074;14679.926600366929;14790.556283311518;14794.852051479562;14800.889933255048;14870.776938345933;12673.112882098165;12702.745945081047;12717.491300348067;12722.681932951751;12725.236485810696;11115.666530004113;12138;12252.632421027383;12269.202501837352;12380.195287306296;5508.55931525477;5542.196381524656;5571.220029195916;5578;5583.3858275589655;1132.5130194016488;1159.2986243322837;1169.4445138559117;1171.3733152763232;1200;24355.512889742105;25739.742602574108;27127.728722712916;27589.86205068962;27680.584791228146;1116.6929094499078;1221.5297110612478;1280.2510531339085;1328.2827273272517;1335.3256605414203;11245.36942143401;14014.579562613138;14207;14376.568702938928;15209.467668631545;429489.8525507415;433465.83267084096;433882;433948;434369;42810.78594607007;52456;59360.40639593635;59575.21274361776;59888.802223955274;1336.9732605347838;1354.4514471639002;1465.479754687092;1493.57433131557;1511.5616471223223;29325.120246392642;30665;30976.22559436032;31267.53098703567;31441.8427907859;42898;42902;43012.35481467843;43098;43155.568444315155;314376;389783;391545;392160;393539;24220;30177.54733678953;30254.84872575623;30355.848220758755;32618;40061.79969100136;40598.797006015964;41040.384394233515;41136.97157571085;41524.58475415207;23192.536149277257;28256.858715707112;28328.85835570809;28429.147125586267;29285;28404.43191136207;28733.56899646465;29640.407191856473;29744.702552974195;30033.798648053835;27802.582961255193;33773.493397599545;33777;33826;33843.15392115217;29364.11907642774;29423.11730648084;29761;30256.84871575628;30512;20195.192192312144;21722.23972160967;22600.77854455162;24019.799010049777;24169.274921752374 10:34:23 INFO - PROCESS | 1976 | |2;jquery.html;403.56818204521164;416.45860381504036;417.25936462778543;426.6373582454914;451.3252687232588;19922.800771992293;21608.459788505294;21909.342719718396;22229.666555001648;22338.441538961513;16858.157092145404;22829.657555136688;22986.88506557469;23179.450260471334;23399.415014624643;6644.402003819668;7076.6815493302665;7080.327368899946;7088.964555177217;7108.964455177743;7302.963485182567;7622.3139917407425;7784.532928024309;7785.571793551352;7794.883076753853;6421.454176395012;7539.472236943416;7627.466077374586;7739.6517156727805;7800.960995195016;6522.967385163044;7669.386449084116;7699.961500192491;7711.884321735179;7737.226277372274;7493.775186744352;7494.362979146779;7494.812629684247;7504.962475187644;7527.962360188219;25136.371590710194;25257.368565786004;26099.217023489327;26181.607275890878;26450.73549264502;2523.634073059396;2988.835614041227;3045.6649768525444;3047.9847600761855;3069.478188707925;14981.550553483414;16170.514884553482;16246;16347.019178849188;16726.916365418216;2018.606371757508;2070.265055905162;2099.1813192854797;2118.6821976703463;2131.7335333083365;151545.24227378902;159449.60825587626;169160;173200;181920;20960.47598810042;21258.681119783214;21360;21375.57248855014;21396.893015535134;32118;32715.83642081822;32855.507167392505;32858;32943.01170964875;9006.549672516348;10555.384186019208;10908.290961087503;11126.388048657402;11244.775104497865;9198.310126740522;9199.586018629177;9201.63193472267;9208.769780755534;9324.766880828034;9598.760030999281;9693.030696930251;9702.466364349888;9765.267604929659;9774.02259774017;4193.728891464662;4271.8718438446895;4275.315949448073;4291.871243862689;4320.719153255029;961.4952150121243;1003.4882210072769;1083.285031878956;1092.229977865603;1097.7036200226055;21638.459038523848;21652.35042948714;21652.891735541223;21701.782982169978;21892;1114.2979922648678;1125.763589646167;1143.9542418303174;1158.9130815188894;1166.988330116688;10088.445135517617;10526.473676316153;11319.151063670257;11339.943300283447;11389.316641001566;677342.0664483329;692514;693799;699672;731424;145614;145645;150946;152230.39069192906;155144;1266.1643315411784;1270.3076823131416;1312.8293321868096;1343.375330471316;1354.6410201296467;19367.709484357463;19922.402327930184;20326.390208293775;20370.694439583123;20393.286234981704;64845.35154648393;65481.01778473422;65566.67216663886;65822;66777;91742;91825;92260;93050.53474732583;94381.58427623728;10382.844257336297;11216.495257713259;11411.144164187554;11439.714007149887;11852.407379631157;12793.360331983178;12805.615831525069;13121.409536571067;13197.472101115847;13241.602751917459;11061.174906140397;11069.944650277019;11158.553657853596;11248.26886252391;11324.660260192208;9079.954600226956;10417.218708597036;10453.059224669816;10650.786984260116;10786.406747628957;6366.77716279928;10240.743981400525;11166.05088567481;11486.597969071378;11598.07215422781;9820.901790982285;11410;11438.714032149262;11463;11471.770564588829;10954.616588419367;11008.834867477155;11369;11399.829002564802;11408.657740267805 10:34:23 INFO - PROCESS | 1976 | |3;mootools.html;1607.9276432560534;1653.2064608987685;1671.6907372136163;1672.0302224709667;1680.5966568023673;11028.44857757112;11504.482298296576;11752.236104653202;11780.528778848846;11939.701507462318;10424.166066714664;10549.84175237372;10554.261201715883;10569.577216911315;10582.047615714584;2146.570685862826;2452.7179374372;2490.9626355604682;2492.3769057735567;2501.949961000779;2287.931362059141;2487.067349743846;2526.3810366460243;2530.8354956927833;2536.9238922832346;2072;2472.913448029319;2485.1674688979215;2507.485965377093;2509;2446.4617784087473;2471.925842224727;2493.488834788873;2506.9247922562354;2529.519391315644;2485.888135033928;2502.94994100117;2514.9371265718505;2514.9874250628814;2518.7355327690693;11346.43267836613;11628.883711162865;11637.88362116385;11639.76720465586;11663.066954643607;6011.879762404727;6169.04379821124;6178.536609754242;6189.009758438673;6190;3707.870224542155;3795.7532760370486;3836.9424458632866;3838.42423636454;3910.120222949842;1267.8922291605131;1268.2517314784322;1293.7973249597596;1319.9538016169388;1330.1819381080663;14276;14377.883846486273;15444.0733555987;15850.682986340324;15956;4144.772037537964;4818.68678535901;4967.677100988424;4999.875003124878;5008;12065.577704780291;12082.818757718642;12090.516379344903;12118.969887559657;12122.636320910387;5310.628256022042;6012.789552365646;6077.179580756541;6078.6048906820915;6088.452039316394;5444.047291723932;5572.554195664337;5591.860203494945;5645.463680950304;5655.632383895034;4031.1937612477623;4072.53165885921;4120.876373708794;4148.191102734998;4157.417961485395;996.8205722969935;1061.1669839176209;1088.1077516436446;1090.6019302954523;1101.823708206683;973.7127547373542;991.4150651115876;1000.9399436033716;1008.0965232576634;1015.6496008876926;6724.596524208563;6769.8646027079185;6819.863602727918;6952.965235173791;7018.87659991299;1057.2757661002238;1074.0762943868267;1098.0940723902813;1112;1113.2318700096907;3734.775913445202;4140.710150289451;4222.514410842731;4401.427814384174;4557.749323787223;13905.235212063231;14513.637159071108;14537.345819438151;14538.200398977946;14539.200343981183;14064.71870562583;14077.577672669837;14449.133052016912;14521.419143234152;14545.127292362493;10541.789164216827;10566.894331056592;10644.095251903675;10677.252592318619;10718.89281107179;6291.276503202191;6799.694013769294;6930.757423490154;6955.756548520778;7042;11844.526218951145;12229.816552751894;12230.816537752118;12256.632301030982;12315.75368492643;12318;12341;12359;12521.87478125207;12547.121701480808;8723.214910658071;8861.911380886366;8863.601137948679;9395.85906211421;9870.062344077302;9227.815443690954;10959.726006849893;11024.448777560929;11040.33757974524;11076.39079850616;7836.451448398558;9061.592228349607;9088.59101340428;9158.542072896462;9186.448813071238;8943;8969.596368163318;8999.37004409685;9016.233620142362;9115.863262050942;7284.890726639211;7681.423893208143;7995.960020199862;8051.114377418597;8099.473534220257;5014.297998280316;5513.779448822002;5966.880662386641;5970.253718285215;6045.063015232646;5313.256144139901;6085.361037091042;6171.537134714825;6266.749330026748;6338.809835704937 10:34:23 INFO - PROCESS | 1976 | |4;prototype.html;350.7194244604317;384.45599476740415;386.750545897896;400.3814107204367;404.70456566706304;4573.896007836395;4645.001324715186;5300.416954135042;5381.3362385619075;5464.005637942152;4719.9468231319315;5295.875433494238;5335.839924802252;5352.6813375707015;5374.7043912584795;4062.329715596923;4287.464066991626;4472.396226509412;4511.917139886436;4530.705504142236;3920.8627698030573;3969.729686500321;4137.565555616662;4173;4391.231534481453;4132.132252227021;4332.935005974897;4489.753063581502;4508.278675411935;4548.272276435771;3813.408921617126;4088.855120566246;4269.6370808481315;4301.677374196932;4451.243288640939;3732.9066773330615;3810.333191691443;3841.673457756094;4060.2796126595254;4074.5110586729784;4649.674522783406;4675.462321832999;5120.590352771769;5211.843644690627;5258.92111618326;4809.711417314972;4821.54195351441;5092.59259259262;5118.923216151761;5122.590192784568;5261.394939581921;5485.75314110862;5515.172724091378;5720.710591409956;5745.569082318842;3863.4398012288343;4099.97950010248;4261.488621365425;4560.931586026212;4616.460946479457;5080.441151473372;5791.855203619901;6088.573799834014;6102.938970610281;6104.328523862371;4655.742949403642;4682.682072374197;4688.218476978726;4690.388400931264;4727.983483550985;4188.874333769992;4305.289627211475;4349.173657005159;4602.318145483667;4688.757479267997;4747.240441529339;4875.658703890765;5059.797608095709;5143.8456846294675;5144.742762861842;4861.440934292602;5041.974790126026;5274.3670759509105;5637.605367624227;5646.661200327993;1447.8190226221723;1552.5793898582897;1591.2680167123021;1596.5769071196141;1616.2969108437867;1134.6199023327279;1182.7930112230326;1214.3624597086423;1229.6188181663713;1236.9381530923417;4553.609355088605;4786.880327991828;5331.5201631853315;5344.305240318734;5428.5928555358505;4982.2775697524385;5465.327145290798;5563.666180029211;5930.2883653960935;5947.375525569752;4838.975805120952;5120.948790512121;5153.768080436389;5182.823214250024;5362.946370536245;2482.689663792026;2703.8377697338224;2962.6296712910885;3088.9536656950163;3092.659807421182;4910.8772280693265;5837.67892765902;6108.5723999319625;6176.567640265228;6185.082624386455;4856.2958371036;5198.428172900978;5329.573634109262;5583.69289689071;5902.409759024149;5151.922721159186;5378.731063446812;5636.774529018793;5883.793822266425;6070.785842831451;5059.949400506095;5388.434214407431;5555.694436806015;5979.953508135972;6019.939800602113;5234.97382513085;5642.915356269577;5840.912386314124;5928.67392293428;5945.910811337747;5677.51741102011;6163.120248324304;6188.81433556994;6203.797651833715;6274.341194174728;4408.470983482002;4516.232240519185;4690.701556237487;4694.319323698113;4723.905521889612;5218.869528261824;5261.763220655156;5305.204219367126;5540.833774986757;5778.884422311614;5147.40804807437;5403.378611459732;5647.435256474319;5745.396733342939;5765.308163020464;5063.025420086243;5064.518870707277;5128.405391024982;5290.709011004432;5730.856728581819;4669.0428462165855;4879.658423910292;5278.89442211146;5541.085720856014;5602.562885144975;5162;5242.790288388422;5388.568914486753;5467.863303417446;5748.597598168255;4796.8081276748535;5140.291919045681;5421.728913554385;5460.5085542301385;5518.599409256986 10:34:23 INFO - PROCESS | 1976 | |5;yui.html;666.7899611622336;734.0054226523058;802.39018346057;802.6668932393058;913.7350168451147;16226.432074877383;17046.403375881848;18149.81850181501;18415.07924603768;18460.44618661439;14418.711625767504;16417.17914104293;16561.91719041406;16746.24641891115;16876.66246675066;995.4525011243824;1019.0675531888313;1023.3706270643538;1024.390487659845;1024.5799222318851;901.8466860633708;966.5119114846992;1015.9796804063951;1026.4457193115709;1028.2339656955578;1080.9945950270237;1083.106437189318;1083.2471432354516;1083.6423980086552;1089.1776708585007;1063.388551582839;1073.280901795791;1079.4656644960717;1080.4813689429118;1080.983785243222;794.9602519873984;795.1690483444814;795.5147360110328;796.80079980005;797.1112209885994;17372.391966281244;17656.823431765773;17732.822671773247;18332.63334733311;18462.90768546162;14751;14842.25788710571;14906.36030036695;14978.452575715328;15218.391264349413;11590.768184636345;11927.343996080212;12003.57987470443;12013.459394327276;12163.51345946161;3387.8814241501427;3496.8426420811124;3728.571214310362;3845.2693988142164;3995.7802320872343;104517;106934.46532767364;116487.41756291164;118904.81095189023;125418.37290813579;11301.49143288554;11419.429028548539;11494.310341379543;11513.194076414738;11545.103035068198;23830.88084559566;23864.761352386253;23964.281071567882;24013.399665008164;24455.144069957467;7733;7943.324817390587;8013.11855695873;8038.155993620702;8124.146964568635;4555.9772201138785;4565.657575681837;4604.447466303998;4638.628909687217;4723.95276047242;3659.2681463707363;3719.5536535615897;3735.364987952055;3749.4375843623675;3763.209725957525;664.5879554676035;667.436016566001;671.419222372654;686.2142846440897;686.5468790598181;606.3542327421283;678.3691167214538;714.8070021094276;727.5016613619688;727.7198278662753;10239.539220734936;10540.420276884692;10757.56969721218;10843.295185812896;11106.777864442665;648.5622205011598;656.9947979591233;694.6422592364936;718.3318079501303;741.4624397311906;5161.870953226199;5255.264263003182;5277.973610131925;5290.259363689087;5332.9733351333;98178;101683;101898.49050754699;102260.48869755604;103226.48386758019;84247.57876210702;86634;87235.69146462808;88404.5579772097;88536.6719499208;78756.21243787488;79659;80161.19838801654;80962.59518702487;81962.77055844167;10053.396796192252;10089.041541053588;10222.182225422097;10223.642172523925;10288.485575721334;52714.68213294698;53058.20412693735;53669.571630018974;54084.18873716976;55577.72211138919;66879;67715;68336.97494537686;68729.96905046329;69531;11527.76398132532;11552.59565915189;11592.768144636892;12151.75696486083;12221.144519883523;12990.805137922749;13377.197368157942;13397.464101435833;13528.661783455493;13530.661733456742;9534.761630959281;11827.994620457013;11993.100517461051;12327.445264962918;12602.810957835456;11753;11764.117691173025;12323.322217278135;12537.874621254035;12660.430280637209;11103.866693874592;11230.157738169833;11348.262362946381;12045.698857528281;12081.697957551132;9293.953530232306;9560.139587437165;9629;9867.111959923353;10092.798144036931;9954.850677239992;10583.682489525327;10636.042756151983;10650.89349106499;10659.520321585393 10:34:23 INFO - PROCESS | 1976 | __end_tp_report 10:34:23 INFO - PROCESS | 1976 | __start_cc_report 10:34:23 INFO - PROCESS | 1976 | _x_x_mozilla_cycle_collect,399 10:34:23 INFO - PROCESS | 1976 | __end_cc_report 10:34:23 INFO - PROCESS | 1976 | __startTimestamp1494869663169__endTimestamp 10:34:23 INFO - PROCESS | 1976 | 10:34:23 INFO - PROCESS | 1976 | ------- Summary: start ------- 10:34:23 INFO - PROCESS | 1976 | Number of tests: 6 10:34:23 INFO - PROCESS | 1976 | 10:34:23 INFO - PROCESS | 1976 | [#0] dojo.html Cycles:165 Average:6097.19 Median:6278.40 stddev:700.61 (11.2%) stddev-sans-first:661.66 10:34:23 INFO - PROCESS | 1976 | Values: 3083.3 3603.1 3645.3 3649.3 3652.1 6094.6 6094.8 6148.3 6174.6 6179.6 6102.1 6173.1 6176.9 6183.2 6199.3 4305.0 5568.1 5654.5 5698.0 5777.8 4896.5 5739.3 5754.7 5773.7 5775.5 4826.7 5518.5 5556.1 5759.6 5769.9 5752.9 5795.4 5866.1 5889.4 5945.2 5682.6 5689.2 5737.0 5772.8 5782.4 4771.3 6196.3 6272.2 6286.1 6292.6 6227.6 6304.1 6305.5 6468.0 6473.6 6687.5 6772.6 6775.2 6776.5 6778.5 6191.1 6224.0 6270.2 6277.0 6281.7 5176.5 6499.3 6521.5 6587.0 6599.3 6395.6 6406.8 6550.9 6573.6 6586.4 6688.0 6708.7 6755.5 6764.0 6769.4 6236.7 6558.3 6669.3 6675.7 6688.8 6378.0 6388.4 6443.7 6475.1 6475.3 5329.4 5348.4 5352.8 5380.7 5404.8 4761.5 4772.9 4785.8 4801.3 4821.8 6142.9 6181.0 6184.0 6222.9 6278.4 5174.2 6056.4 6335.6 6773.8 6785.5 5165.6 6003.7 6140.2 6182.4 6294.3 5351.9 5937.6 5940.9 5962.3 6035.1 6784.8 6825.7 6922.1 6957.5 6981.8 6723.8 6737.7 6739.1 6759.7 6779.9 6412.3 6598.6 6928.4 6942.9 6964.6 6272.8 6279.0 6452.7 6458.9 6578.2 5498.9 6622.5 6630.5 6680.7 6690.2 6389.4 6418.1 6432.1 6444.0 6476.4 6183.3 6228.5 6336.0 6466.2 6478.0 6223.9 6286.4 6340.8 6354.1 6367.2 6496.0 6503.0 6505.3 6519.1 6519.1 6182.3 6194.0 6290.3 6301.2 6319.7 10:34:23 INFO - PROCESS | 1976 | 10:34:23 INFO - PROCESS | 1976 | [#1] ext.html Cycles:180 Average:52342.07 Median:30306.35 stddev:102622.97 (338.6%) stddev-sans-first:102837.77 10:34:23 INFO - PROCESS | 1976 | Values: 755.7 757.5 839.9 965.0 977.6 32344.0 36898.3 41165.4 43441.6 43493.6 41098.8 41230.0 41333.0 41446.6 41469.8 7134.5 8420.9 11080.8 11342.7 11524.2 11253.5 11369.0 11378.4 11397.0 11397.5 11393.6 11395.9 11401.9 11427.7 11460.9 11293.2 11323.0 11336.0 11342.1 11353.5 9878.2 11057.4 11086.4 11094.2 11107.2 35519.0 35690.8 35747.6 35826.3 35878.3 3777.5 3913.1 3926.3 3974.9 4036.1 26156.6 26379.9 26479.6 28085.4 28132.6 3156.4 3187.5 3200.0 3268.5 3269.2 343639.0 345064.0 347274.0 351830.2 356149.2 37004.9 44635.8 44696.3 44734.3 44792.8 46503.0 46535.1 46567.0 46636.5 46721.8 14679.9 14790.6 14794.9 14800.9 14870.8 12673.1 12702.7 12717.5 12722.7 12725.2 11115.7 12138.0 12252.6 12269.2 12380.2 5508.6 5542.2 5571.2 5578.0 5583.4 1132.5 1159.3 1169.4 1171.4 1200.0 24355.5 25739.7 27127.7 27589.9 27680.6 1116.7 1221.5 1280.3 1328.3 1335.3 11245.4 14014.6 14207.0 14376.6 15209.5 429489.9 433465.8 433882.0 433948.0 434369.0 42810.8 52456.0 59360.4 59575.2 59888.8 1337.0 1354.5 1465.5 1493.6 1511.6 29325.1 30665.0 30976.2 31267.5 31441.8 42898.0 42902.0 43012.4 43098.0 43155.6 314376.0 389783.0 391545.0 392160.0 393539.0 24220.0 30177.5 30254.8 30355.8 32618.0 40061.8 40598.8 41040.4 41137.0 41524.6 23192.5 28256.9 28328.9 28429.1 29285.0 28404.4 28733.6 29640.4 29744.7 30033.8 27802.6 33773.5 33777.0 33826.0 33843.2 29364.1 29423.1 29761.0 30256.8 30512.0 20195.2 21722.2 22600.8 24019.8 24169.3 10:34:23 INFO - PROCESS | 1976 | 10:34:23 INFO - PROCESS | 1976 | [#2] jquery.html Cycles:180 Average:42343.43 Median:21677.34 stddev:117420.45 (541.7%) stddev-sans-first:117707.62 10:34:23 INFO - PROCESS | 1976 | Values: 403.6 416.5 417.3 426.6 451.3 19922.8 21608.5 21909.3 22229.7 22338.4 16858.2 22829.7 22986.9 23179.5 23399.4 6644.4 7076.7 7080.3 7089.0 7109.0 7303.0 7622.3 7784.5 7785.6 7794.9 6421.5 7539.5 7627.5 7739.7 7801.0 6523.0 7669.4 7700.0 7711.9 7737.2 7493.8 7494.4 7494.8 7505.0 7528.0 25136.4 25257.4 26099.2 26181.6 26450.7 2523.6 2988.8 3045.7 3048.0 3069.5 14981.6 16170.5 16246.0 16347.0 16726.9 2018.6 2070.3 2099.2 2118.7 2131.7 151545.2 159449.6 169160.0 173200.0 181920.0 20960.5 21258.7 21360.0 21375.6 21396.9 32118.0 32715.8 32855.5 32858.0 32943.0 9006.5 10555.4 10908.3 11126.4 11244.8 9198.3 9199.6 9201.6 9208.8 9324.8 9598.8 9693.0 9702.5 9765.3 9774.0 4193.7 4271.9 4275.3 4291.9 4320.7 961.5 1003.5 1083.3 1092.2 1097.7 21638.5 21652.4 21652.9 21701.8 21892.0 1114.3 1125.8 1144.0 1158.9 1167.0 10088.4 10526.5 11319.2 11339.9 11389.3 677342.1 692514.0 693799.0 699672.0 731424.0 145614.0 145645.0 150946.0 152230.4 155144.0 1266.2 1270.3 1312.8 1343.4 1354.6 19367.7 19922.4 20326.4 20370.7 20393.3 64845.4 65481.0 65566.7 65822.0 66777.0 91742.0 91825.0 92260.0 93050.5 94381.6 10382.8 11216.5 11411.1 11439.7 11852.4 12793.4 12805.6 13121.4 13197.5 13241.6 11061.2 11069.9 11158.6 11248.3 11324.7 9080.0 10417.2 10453.1 10650.8 10786.4 6366.8 10240.7 11166.1 11486.6 11598.1 9820.9 11410.0 11438.7 11463.0 11471.8 10954.6 11008.8 11369.0 11399.8 11408.7 10:34:23 INFO - PROCESS | 1976 | 10:34:23 INFO - PROCESS | 1976 | [#3] mootools.html Cycles:180 Average:6912.12 Median:2504.94 stddev:4300.51 (171.7%) stddev-sans-first:4294.11 10:34:23 INFO - PROCESS | 1976 | Values: 1607.9 1653.2 1671.7 1672.0 1680.6 11028.4 11504.5 11752.2 11780.5 11939.7 10424.2 10549.8 10554.3 10569.6 10582.0 2146.6 2452.7 2491.0 2492.4 2501.9 2287.9 2487.1 2526.4 2530.8 2536.9 2072.0 2472.9 2485.2 2507.5 2509.0 2446.5 2471.9 2493.5 2506.9 2529.5 2485.9 2502.9 2514.9 2515.0 2518.7 11346.4 11628.9 11637.9 11639.8 11663.1 6011.9 6169.0 6178.5 6189.0 6190.0 3707.9 3795.8 3836.9 3838.4 3910.1 1267.9 1268.3 1293.8 1320.0 1330.2 14276.0 14377.9 15444.1 15850.7 15956.0 4144.8 4818.7 4967.7 4999.9 5008.0 12065.6 12082.8 12090.5 12119.0 12122.6 5310.6 6012.8 6077.2 6078.6 6088.5 5444.0 5572.6 5591.9 5645.5 5655.6 4031.2 4072.5 4120.9 4148.2 4157.4 996.8 1061.2 1088.1 1090.6 1101.8 973.7 991.4 1000.9 1008.1 1015.6 6724.6 6769.9 6819.9 6953.0 7018.9 1057.3 1074.1 1098.1 1112.0 1113.2 3734.8 4140.7 4222.5 4401.4 4557.7 13905.2 14513.6 14537.3 14538.2 14539.2 14064.7 14077.6 14449.1 14521.4 14545.1 10541.8 10566.9 10644.1 10677.3 10718.9 6291.3 6799.7 6930.8 6955.8 7042.0 11844.5 12229.8 12230.8 12256.6 12315.8 12318.0 12341.0 12359.0 12521.9 12547.1 8723.2 8861.9 8863.6 9395.9 9870.1 9227.8 10959.7 11024.4 11040.3 11076.4 7836.5 9061.6 9088.6 9158.5 9186.4 8943.0 8969.6 8999.4 9016.2 9115.9 7284.9 7681.4 7996.0 8051.1 8099.5 5014.3 5513.8 5966.9 5970.3 6045.1 5313.3 6085.4 6171.5 6266.7 6338.8 10:34:23 INFO - PROCESS | 1976 | 10:34:23 INFO - PROCESS | 1976 | [#4] prototype.html Cycles:180 Average:4696.47 Median:5105.76 stddev:1308.33 (25.6%) stddev-sans-first:1270.69 10:34:23 INFO - PROCESS | 1976 | Values: 350.7 384.5 386.8 400.4 404.7 4573.9 4645.0 5300.4 5381.3 5464.0 4719.9 5295.9 5335.8 5352.7 5374.7 4062.3 4287.5 4472.4 4511.9 4530.7 3920.9 3969.7 4137.6 4173.0 4391.2 4132.1 4332.9 4489.8 4508.3 4548.3 3813.4 4088.9 4269.6 4301.7 4451.2 3732.9 3810.3 3841.7 4060.3 4074.5 4649.7 4675.5 5120.6 5211.8 5258.9 4809.7 4821.5 5092.6 5118.9 5122.6 5261.4 5485.8 5515.2 5720.7 5745.6 3863.4 4100.0 4261.5 4560.9 4616.5 5080.4 5791.9 6088.6 6102.9 6104.3 4655.7 4682.7 4688.2 4690.4 4728.0 4188.9 4305.3 4349.2 4602.3 4688.8 4747.2 4875.7 5059.8 5143.8 5144.7 4861.4 5042.0 5274.4 5637.6 5646.7 1447.8 1552.6 1591.3 1596.6 1616.3 1134.6 1182.8 1214.4 1229.6 1236.9 4553.6 4786.9 5331.5 5344.3 5428.6 4982.3 5465.3 5563.7 5930.3 5947.4 4839.0 5120.9 5153.8 5182.8 5362.9 2482.7 2703.8 2962.6 3089.0 3092.7 4910.9 5837.7 6108.6 6176.6 6185.1 4856.3 5198.4 5329.6 5583.7 5902.4 5151.9 5378.7 5636.8 5883.8 6070.8 5059.9 5388.4 5555.7 5980.0 6019.9 5235.0 5642.9 5840.9 5928.7 5945.9 5677.5 6163.1 6188.8 6203.8 6274.3 4408.5 4516.2 4690.7 4694.3 4723.9 5218.9 5261.8 5305.2 5540.8 5778.9 5147.4 5403.4 5647.4 5745.4 5765.3 5063.0 5064.5 5128.4 5290.7 5730.9 4669.0 4879.7 5278.9 5541.1 5602.6 5162.0 5242.8 5388.6 5467.9 5748.6 4796.8 5140.3 5421.7 5460.5 5518.6 10:34:23 INFO - PROCESS | 1976 | 10:34:23 INFO - PROCESS | 1976 | [#5] yui.html Cycles:180 Average:20948.28 Median:18241.23 stddev:30136.16 (165.2%) stddev-sans-first:30182.22 10:34:23 INFO - PROCESS | 1976 | Values: 666.8 734.0 802.4 802.7 913.7 16226.4 17046.4 18149.8 18415.1 18460.4 14418.7 16417.2 16561.9 16746.2 16876.7 995.5 1019.1 1023.4 1024.4 1024.6 901.8 966.5 1016.0 1026.4 1028.2 1081.0 1083.1 1083.2 1083.6 1089.2 1063.4 1073.3 1079.5 1080.5 1081.0 795.0 795.2 795.5 796.8 797.1 17372.4 17656.8 17732.8 18332.6 18462.9 14751.0 14842.3 14906.4 14978.5 15218.4 11590.8 11927.3 12003.6 12013.5 12163.5 3387.9 3496.8 3728.6 3845.3 3995.8 104517.0 106934.5 116487.4 118904.8 125418.4 11301.5 11419.4 11494.3 11513.2 11545.1 23830.9 23864.8 23964.3 24013.4 24455.1 7733.0 7943.3 8013.1 8038.2 8124.1 4556.0 4565.7 4604.4 4638.6 4724.0 3659.3 3719.6 3735.4 3749.4 3763.2 664.6 667.4 671.4 686.2 686.5 606.4 678.4 714.8 727.5 727.7 10239.5 10540.4 10757.6 10843.3 11106.8 648.6 657.0 694.6 718.3 741.5 5161.9 5255.3 5278.0 5290.3 5333.0 98178.0 101683.0 101898.5 102260.5 103226.5 84247.6 86634.0 87235.7 88404.6 88536.7 78756.2 79659.0 80161.2 80962.6 81962.8 10053.4 10089.0 10222.2 10223.6 10288.5 52714.7 53058.2 53669.6 54084.2 55577.7 66879.0 67715.0 68337.0 68730.0 69531.0 11527.8 11552.6 11592.8 12151.8 12221.1 12990.8 13377.2 13397.5 13528.7 13530.7 9534.8 11828.0 11993.1 12327.4 12602.8 11753.0 11764.1 12323.3 12537.9 12660.4 11103.9 11230.2 11348.3 12045.7 12081.7 9294.0 9560.1 9629.0 9867.1 10092.8 9954.9 10583.7 10636.0 10650.9 10659.5 10:34:23 INFO - PROCESS | 1976 | -------- Summary: end -------- 10:34:23 INFO - PROCESS | 1976 | 10:34:23 INFO - TEST-INFO | 1976: exit 0 10:34:23 INFO - TEST-OK | dromaeo_css | took 1116068ms 10:34:23 INFO - TEST-START | kraken 10:34:23 INFO - operating with platform_type : mac_ 10:34:23 INFO - Initialising browser for kraken test... 10:34:23 INFO - TEST-INFO | started process 1981 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpz0H3NO/profile http://localhost:49327/getInfo.html) 10:34:26 INFO - PROCESS | 1981 | 2017-05-15 10:34:26.401 plugin-container[1983:12734] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 10:34:26 INFO - PROCESS | 1981 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:26 INFO - PROCESS | 1981 | 2017-05-15 10:34:26.402 plugin-container[1983:12734] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 10:34:26 INFO - PROCESS | 1981 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:26 INFO - PROCESS | 1981 | 2017-05-15 10:34:26.402 plugin-container[1983:12734] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:34:26 INFO - PROCESS | 1981 | __metrics Screen width/height:1600/1200 10:34:26 INFO - PROCESS | 1981 | colorDepth:24 10:34:26 INFO - PROCESS | 1981 | Browser inner width/height: 1024/689 10:34:26 INFO - PROCESS | 1981 | __metrics 10:34:26 ERROR - PROCESS | 1981 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 10:34:27 INFO - TEST-INFO | 1981: exit 0 10:34:27 INFO - Browser initialized. 10:34:27 INFO - Running cycle 1/1 for kraken test... 10:34:27 INFO - TEST-INFO | started process 1984 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/T/tmpz0H3NO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:34:29 INFO - PROCESS | 1984 | 2017-05-15 10:34:29.438 plugin-container[1985:12865] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x854f, name = 'com.apple.tsm.portname' 10:34:29 INFO - PROCESS | 1984 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:29 INFO - PROCESS | 1984 | 2017-05-15 10:34:29.439 plugin-container[1985:12865] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8553, name = 'com.apple.CFPasteboardClient' 10:34:29 INFO - PROCESS | 1984 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:34:29 INFO - PROCESS | 1984 | 2017-05-15 10:34:29.439 plugin-container[1985:12865] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:34:39 INFO - PROCESS | 1984 | RSS: Main: 174194688 10:34:39 INFO - PROCESS | 1984 | 10:34:42 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49327/tests/kraken/driver.html?testName=audio-beat-detection) 10:34:43 INFO - PROCESS | 1984 | RSS: Main: 187695104 10:34:43 INFO - PROCESS | 1984 | 10:34:45 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49327/tests/kraken/driver.html?testName=audio-dft) 10:34:46 INFO - PROCESS | 1984 | RSS: Main: 195702784 10:34:46 INFO - PROCESS | 1984 | 10:34:49 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49327/tests/kraken/driver.html?testName=audio-fft) 10:34:50 INFO - PROCESS | 1984 | RSS: Main: 197353472 10:34:50 INFO - PROCESS | 1984 | 10:34:52 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49327/tests/kraken/driver.html?testName=audio-oscillator) 10:34:52 INFO - PROCESS | 1984 | RSS: Main: 200118272 10:34:52 INFO - PROCESS | 1984 | 10:34:54 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49327/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:34:55 INFO - PROCESS | 1984 | RSS: Main: 200507392 10:34:55 INFO - PROCESS | 1984 | 10:34:58 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49327/tests/kraken/driver.html?testName=imaging-darkroom) 10:34:59 INFO - PROCESS | 1984 | RSS: Main: 205008896 10:34:59 INFO - PROCESS | 1984 | 10:35:02 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49327/tests/kraken/driver.html?testName=imaging-desaturate) 10:35:03 INFO - PROCESS | 1984 | RSS: Main: 204349440 10:35:03 INFO - PROCESS | 1984 | 10:35:06 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49327/tests/kraken/driver.html?testName=json-parse-financial) 10:35:06 INFO - PROCESS | 1984 | RSS: Main: 204386304 10:35:06 INFO - PROCESS | 1984 | 10:35:08 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49327/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:35:09 INFO - PROCESS | 1984 | RSS: Main: 203837440 10:35:09 INFO - PROCESS | 1984 | 10:35:10 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:35:11 INFO - PROCESS | 1984 | RSS: Main: 203796480 10:35:11 INFO - PROCESS | 1984 | 10:35:13 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:35:14 INFO - PROCESS | 1984 | RSS: Main: 203857920 10:35:14 INFO - PROCESS | 1984 | 10:35:17 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:35:18 INFO - PROCESS | 1984 | RSS: Main: 204300288 10:35:18 INFO - PROCESS | 1984 | 10:35:21 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:35:21 INFO - PROCESS | 1984 | RSS: Main: 203980800 10:35:21 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | Cycle 1(1): loaded http://localhost:49327/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49327/tests/kraken/driver.html?testName=ai-astar) 10:35:23 INFO - PROCESS | 1984 | RSS: Main: 206053376 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | __start_tp_report 10:35:23 INFO - PROCESS | 1984 | _x_x_mozilla_page_load 10:35:23 INFO - PROCESS | 1984 | _x_x_mozilla_page_load_details 10:35:23 INFO - PROCESS | 1984 | |i|pagename|runs| 10:35:23 INFO - PROCESS | 1984 | |0;ai-astar;106;119;119;99;176;166;136;121;119;119 10:35:23 INFO - PROCESS | 1984 | |1;audio-beat-detection;141;106;130;142;110;114;110;112;129;110 10:35:23 INFO - PROCESS | 1984 | |2;audio-dft;199;197;196;205;220;192;194;279;274;195 10:35:23 INFO - PROCESS | 1984 | |3;audio-fft;83;58;63;88;60;60;60;60;76;60 10:35:23 INFO - PROCESS | 1984 | |4;audio-oscillator;93;70;94;71;57;86;110;63;86;69 10:35:23 INFO - PROCESS | 1984 | |5;imaging-gaussian-blur;114;94;101;130;93;92;104;129;93;129 10:35:23 INFO - PROCESS | 1984 | |6;imaging-darkroom;98;99;98;98;99;98;99;99;99;129 10:35:23 INFO - PROCESS | 1984 | |7;imaging-desaturate;90;98;89;136;86;142;89;139;137;90 10:35:23 INFO - PROCESS | 1984 | |8;json-parse-financial;54;57;61;73;69;57;95;63;64;67 10:35:23 INFO - PROCESS | 1984 | |9;json-stringify-tinderbox;59;66;65;67;61;59;60;60;62;60 10:35:23 INFO - PROCESS | 1984 | |10;stanford-crypto-aes;83;85;86;80;80;85;84;101;89;88 10:35:23 INFO - PROCESS | 1984 | |11;stanford-crypto-ccm;137;141;145;138;134;137;139;161;208;180 10:35:23 INFO - PROCESS | 1984 | |12;stanford-crypto-pbkdf2;171;152;210;175;170;177;170;213;175;169 10:35:23 INFO - PROCESS | 1984 | |13;stanford-crypto-sha256-iterative;67;78;80;83;79;84;84;68;64;64 10:35:23 INFO - PROCESS | 1984 | __end_tp_report 10:35:23 INFO - PROCESS | 1984 | __start_cc_report 10:35:23 INFO - PROCESS | 1984 | _x_x_mozilla_cycle_collect,989 10:35:23 INFO - PROCESS | 1984 | __end_cc_report 10:35:23 INFO - PROCESS | 1984 | __startTimestamp1494869723792__endTimestamp 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | ------- Summary: start ------- 10:35:23 INFO - PROCESS | 1984 | Number of tests: 14 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | [#0] ai-astar Cycles:10 Average:128.00 Median:128.50 stddev:24.74 (19.3%) stddev-sans-first:24.93 10:35:23 INFO - PROCESS | 1984 | Values: 106.0 119.0 119.0 99.0 176.0 166.0 136.0 121.0 119.0 119.0 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | [#1] audio-beat-detection Cycles:10 Average:120.40 Median:121.50 stddev:13.75 (11.3%) stddev-sans-first:12.39 10:35:23 INFO - PROCESS | 1984 | Values: 141.0 106.0 130.0 142.0 110.0 114.0 110.0 112.0 129.0 110.0 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | [#2] audio-dft Cycles:10 Average:215.10 Median:202.00 stddev:33.35 (16.5%) stddev-sans-first:34.87 10:35:23 INFO - PROCESS | 1984 | Values: 199.0 197.0 196.0 205.0 220.0 192.0 194.0 279.0 274.0 195.0 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | [#3] audio-fft Cycles:10 Average:66.80 Median:61.50 stddev:11.15 (18.1%) stddev-sans-first:10.17 10:35:23 INFO - PROCESS | 1984 | Values: 83.0 58.0 63.0 88.0 60.0 60.0 60.0 60.0 76.0 60.0 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | [#4] audio-oscillator Cycles:10 Average:79.90 Median:78.50 stddev:16.52 (21.0%) stddev-sans-first:16.83 10:35:23 INFO - PROCESS | 1984 | Values: 93.0 70.0 94.0 71.0 57.0 86.0 110.0 63.0 86.0 69.0 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | [#5] imaging-gaussian-blur Cycles:10 Average:107.90 Median:111.00 stddev:16.22 (14.6%) stddev-sans-first:17.06 10:35:23 INFO - PROCESS | 1984 | Values: 114.0 94.0 101.0 130.0 93.0 92.0 104.0 129.0 93.0 129.0 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | [#6] imaging-darkroom Cycles:10 Average:101.60 Median:99.00 stddev:9.64 (9.7%) stddev-sans-first:10.14 10:35:23 INFO - PROCESS | 1984 | Values: 98.0 99.0 98.0 98.0 99.0 98.0 99.0 99.0 99.0 129.0 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | [#7] imaging-desaturate Cycles:10 Average:109.60 Median:89.00 stddev:25.10 (28.2%) stddev-sans-first:25.60 10:35:23 INFO - PROCESS | 1984 | Values: 90.0 98.0 89.0 136.0 86.0 142.0 89.0 139.0 137.0 90.0 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | [#8] json-parse-financial Cycles:10 Average:66.00 Median:65.50 stddev:11.76 (17.9%) stddev-sans-first:11.64 10:35:23 INFO - PROCESS | 1984 | Values: 54.0 57.0 61.0 73.0 69.0 57.0 95.0 63.0 64.0 67.0 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | [#9] json-stringify-tinderbox Cycles:10 Average:61.90 Median:61.50 stddev:3.00 (4.9%) stddev-sans-first:2.99 10:35:23 INFO - PROCESS | 1984 | Values: 59.0 66.0 65.0 67.0 61.0 59.0 60.0 60.0 62.0 60.0 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | [#10] stanford-crypto-aes Cycles:10 Average:86.10 Median:85.00 stddev:6.01 (7.1%) stddev-sans-first:6.27 10:35:23 INFO - PROCESS | 1984 | Values: 83.0 85.0 86.0 80.0 80.0 85.0 84.0 101.0 89.0 88.0 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | [#11] stanford-crypto-ccm Cycles:10 Average:152.00 Median:143.00 stddev:24.29 (17.0%) stddev-sans-first:25.15 10:35:23 INFO - PROCESS | 1984 | Values: 137.0 141.0 145.0 138.0 134.0 137.0 139.0 161.0 208.0 180.0 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.20 Median:175.00 stddev:18.86 (10.8%) stddev-sans-first:19.82 10:35:23 INFO - PROCESS | 1984 | Values: 171.0 152.0 210.0 175.0 170.0 177.0 170.0 213.0 175.0 169.0 10:35:23 INFO - PROCESS | 1984 | 10:35:23 INFO - PROCESS | 1984 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.10 Median:79.50 stddev:8.37 (10.5%) stddev-sans-first:8.35 10:35:23 INFO - PROCESS | 1984 | Values: 67.0 78.0 80.0 83.0 79.0 84.0 84.0 68.0 64.0 64.0 10:35:23 INFO - PROCESS | 1984 | -------- Summary: end -------- 10:35:23 INFO - PROCESS | 1984 | 10:35:24 INFO - TEST-INFO | 1984: exit 0 10:35:24 INFO - TEST-OK | kraken | took 60800ms 10:35:24 INFO - SUITE-END | took 1176s 10:35:24 INFO - Completed test suite (00:19:37) 10:35:24 INFO - javascript benchmark 10:35:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7879.426942194994, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3083.2754302738854, 3603.117236277111, 3645.2891686121216, 3649.3066317399685, 3652.123490362314, 6094.634321940681, 6094.847628809276, 6148.292946311176, 6174.598651087676, 6179.5983261087995, 6102.145699602059, 6173.074038894169, 6176.91903916815, 6183.19618449601, 6199.349068347826, 4304.97847510762, 5568.081266591018, 5654.519365853907, 5698.0, 5777.768889244425, 4896.534829191221, 5739.282589676291, 5754.683492407937, 5773.682447465388, 5775.509081728037, 4826.745046287955, 5518.475744804237, 5556.055470570012, 5759.568032397566, 5769.884602307951, 5752.942470575283, 5795.449432303945, 5866.120081987693, 5889.352171261158, 5945.16767652529, 5682.566938881437, 5689.203511508392, 5737.0, 5772.826815195551, 5782.421757824226, 4771.308160316769, 6196.256449226122, 6272.184615999937, 6286.057091436322, 6292.5595208335435, 6227.564070515065, 6304.085907543381, 6305.527085468608, 6468.029795530661, 6473.611583305017, 6687.465002799813, 6772.5936443813525, 6775.1531058617675, 6776.525643204977, 6778.491613129035, 6191.102290167901, 6223.96888015566, 6270.2475702915945, 6276.968615156895, 6281.654509002003, 5176.456472070454, 6499.3175716549085, 6521.47828173745, 6586.967065164644, 6599.340065993362, 6395.6482393468805, 6406.839829004312, 6550.868982620321, 6573.638449885302, 6586.407223349922, 6687.966560167168, 6708.6981085850275, 6755.527113102035, 6763.966180169067, 6769.39075483199, 6236.719347629277, 6558.311377305409, 6669.266380698119, 6675.732970681119, 6688.83277918056, 6378.043293505918, 6388.361163883575, 6443.742250309935, 6475.061116138134, 6475.255345635218, 5329.440408757102, 5348.384935732441, 5352.785888564414, 5380.704061276589, 5404.8108316208745, 4761.4524329701835, 4772.928406073912, 4785.832495862628, 4801.327814106028, 4821.807127714922, 6142.897836592581, 6180.969095154495, 6183.96908015457, 6222.937770622327, 6278.4035516625845, 5174.197999310113, 6056.394360563909, 6335.588186767845, 6773.796786096326, 6785.5250132490255, 5165.586753059746, 6003.669798161056, 6140.2281855057, 6182.443580077815, 6294.276158241769, 5351.892962140735, 5937.614055086406, 5940.940590594125, 5962.25471816023, 6035.064564992434, 6784.830379240559, 6825.726970921306, 6922.10012698346, 6957.512974091967, 6981.79054628362, 6723.798286051427, 6737.66311684404, 6739.123913891162, 6759.729610815512, 6779.89830152558, 6412.294647588855, 6598.581305019444, 6928.411085057625, 6942.895856562257, 6964.582125072512, 6272.8118156455375, 6279.026750853625, 6452.741890324334, 6458.935410645833, 6578.210614726264, 5498.945010549844, 6622.503312251697, 6630.535862489773, 6680.732770689312, 6690.19717633887, 6389.424951754178, 6418.101465794886, 6432.09950606906, 6444.0, 6476.4495017924, 6183.258009038765, 6228.501719862309, 6336.0, 6466.224053113657, 6477.967610161919, 6223.906641400473, 6286.37136286386, 6340.809775706736, 6354.142190804182, 6367.172267605181, 6495.967520162558, 6502.992036234391, 6505.349465053457, 6519.054737063005, 6519.119918810899, 6182.319944805978, 6194.0, 6290.2766181889665, 6301.243850737757, 6319.652419116993], "value": 6055.414887608957, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.66372964031, 757.5341165183413, 839.8614228652275, 964.9614015439382, 977.628501169555, 32344.0, 36898.26203475929, 41165.35338586457, 43441.565584344185, 43493.56506434935, 41098.767036988866, 41230.0, 41333.0, 41446.58553414465, 41469.792651036776, 7134.536255143399, 8420.915790842075, 11080.833787493195, 11342.71643208918, 11524.19330646855, 11253.493592788343, 11368.97679208871, 11378.37418941958, 11397.0, 11397.48711307989, 11393.601223957163, 11395.943020284887, 11401.885981140165, 11427.657170284863, 11460.885391146065, 11293.153013523977, 11322.999999999958, 11336.0, 11342.149338799623, 11353.545858165664, 9878.209743220525, 11057.447127643665, 11086.390248536327, 11094.167937404727, 11107.222494425394, 35519.0, 35690.821545892366, 35747.642523574694, 35826.28347433037, 35878.28243435143, 3777.452269420946, 3913.1391093959282, 3926.3128952433494, 3974.880753577397, 4036.1322315702264, 26156.607650885253, 26379.868100659565, 26479.602805957926, 28085.438291234062, 28132.578011329846, 3156.4002839460427, 3187.5378070179695, 3200.039988003613, 3268.542404063433, 3269.1500209945584, 343639.0, 345064.0, 347274.0, 351830.2408487941, 356149.2192539021, 37004.88985330444, 44635.776821116335, 44696.3295550567, 44734.32898506525, 44792.77603611961, 46503.0, 46535.06929861384, 46567.0, 46636.533634663894, 46721.83195420074, 14679.926600366929, 14790.556283311518, 14794.852051479562, 14800.889933255048, 14870.776938345933, 12673.112882098165, 12702.745945081047, 12717.491300348067, 12722.681932951751, 12725.236485810696, 11115.666530004113, 12138.0, 12252.632421027383, 12269.202501837352, 12380.195287306296, 5508.55931525477, 5542.196381524656, 5571.220029195916, 5578.0, 5583.3858275589655, 1132.5130194016488, 1159.2986243322837, 1169.4445138559117, 1171.3733152763232, 1200.0, 24355.512889742105, 25739.742602574108, 27127.728722712916, 27589.86205068962, 27680.584791228146, 1116.6929094499078, 1221.5297110612478, 1280.2510531339085, 1328.2827273272517, 1335.3256605414203, 11245.36942143401, 14014.579562613138, 14207.0, 14376.568702938928, 15209.467668631545, 429489.8525507415, 433465.83267084096, 433882.0, 433948.0, 434369.0, 42810.78594607007, 52456.0, 59360.40639593635, 59575.21274361776, 59888.802223955274, 1336.9732605347838, 1354.4514471639002, 1465.479754687092, 1493.57433131557, 1511.5616471223223, 29325.120246392642, 30665.0, 30976.22559436032, 31267.53098703567, 31441.8427907859, 42898.0, 42902.0, 43012.35481467843, 43098.0, 43155.568444315155, 314376.0, 389783.0, 391545.0, 392160.0, 393539.0, 24220.0, 30177.54733678953, 30254.84872575623, 30355.848220758755, 32618.0, 40061.79969100136, 40598.797006015964, 41040.384394233515, 41136.97157571085, 41524.58475415207, 23192.536149277257, 28256.858715707112, 28328.85835570809, 28429.147125586267, 29285.0, 28404.43191136207, 28733.56899646465, 29640.407191856473, 29744.702552974195, 30033.798648053835, 27802.582961255193, 33773.493397599545, 33777.0, 33826.0, 33843.15392115217, 29364.11907642774, 29423.11730648084, 29761.0, 30256.84871575628, 30512.0, 20195.192192312144, 21722.23972160967, 22600.77854455162, 24019.799010049777, 24169.274921752374], "value": 18555.0831121415, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [403.56818204521164, 416.45860381504036, 417.25936462778543, 426.6373582454914, 451.3252687232588, 19922.800771992293, 21608.459788505294, 21909.342719718396, 22229.666555001648, 22338.441538961513, 16858.157092145404, 22829.657555136688, 22986.88506557469, 23179.450260471334, 23399.415014624643, 6644.402003819668, 7076.6815493302665, 7080.327368899946, 7088.964555177217, 7108.964455177743, 7302.963485182567, 7622.3139917407425, 7784.532928024309, 7785.571793551352, 7794.883076753853, 6421.454176395012, 7539.472236943416, 7627.466077374586, 7739.6517156727805, 7800.960995195016, 6522.967385163044, 7669.386449084116, 7699.961500192491, 7711.884321735179, 7737.226277372274, 7493.775186744352, 7494.362979146779, 7494.812629684247, 7504.962475187644, 7527.962360188219, 25136.371590710194, 25257.368565786004, 26099.217023489327, 26181.607275890878, 26450.73549264502, 2523.634073059396, 2988.835614041227, 3045.6649768525444, 3047.9847600761855, 3069.478188707925, 14981.550553483414, 16170.514884553482, 16246.0, 16347.019178849188, 16726.916365418216, 2018.606371757508, 2070.265055905162, 2099.1813192854797, 2118.6821976703463, 2131.7335333083365, 151545.24227378902, 159449.60825587626, 169160.0, 173200.0, 181920.0, 20960.47598810042, 21258.681119783214, 21360.0, 21375.57248855014, 21396.893015535134, 32118.0, 32715.83642081822, 32855.507167392505, 32858.0, 32943.01170964875, 9006.549672516348, 10555.384186019208, 10908.290961087503, 11126.388048657402, 11244.775104497865, 9198.310126740522, 9199.586018629177, 9201.63193472267, 9208.769780755534, 9324.766880828034, 9598.760030999281, 9693.030696930251, 9702.466364349888, 9765.267604929659, 9774.02259774017, 4193.728891464662, 4271.8718438446895, 4275.315949448073, 4291.871243862689, 4320.719153255029, 961.4952150121243, 1003.4882210072769, 1083.285031878956, 1092.229977865603, 1097.7036200226055, 21638.459038523848, 21652.35042948714, 21652.891735541223, 21701.782982169978, 21892.0, 1114.2979922648678, 1125.763589646167, 1143.9542418303174, 1158.9130815188894, 1166.988330116688, 10088.445135517617, 10526.473676316153, 11319.151063670257, 11339.943300283447, 11389.316641001566, 677342.0664483329, 692514.0, 693799.0, 699672.0, 731424.0, 145614.0, 145645.0, 150946.0, 152230.39069192906, 155144.0, 1266.1643315411784, 1270.3076823131416, 1312.8293321868096, 1343.375330471316, 1354.6410201296467, 19367.709484357463, 19922.402327930184, 20326.390208293775, 20370.694439583123, 20393.286234981704, 64845.35154648393, 65481.01778473422, 65566.67216663886, 65822.0, 66777.0, 91742.0, 91825.0, 92260.0, 93050.53474732583, 94381.58427623728, 10382.844257336297, 11216.495257713259, 11411.144164187554, 11439.714007149887, 11852.407379631157, 12793.360331983178, 12805.615831525069, 13121.409536571067, 13197.472101115847, 13241.602751917459, 11061.174906140397, 11069.944650277019, 11158.553657853596, 11248.26886252391, 11324.660260192208, 9079.954600226956, 10417.218708597036, 10453.059224669816, 10650.786984260116, 10786.406747628957, 6366.77716279928, 10240.743981400525, 11166.05088567481, 11486.597969071378, 11598.07215422781, 9820.901790982285, 11410.0, 11438.714032149262, 11463.0, 11471.770564588829, 10954.616588419367, 11008.834867477155, 11369.0, 11399.829002564802, 11408.657740267805], "value": 11913.094583945773, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1607.9276432560534, 1653.2064608987685, 1671.6907372136163, 1672.0302224709667, 1680.5966568023673, 11028.44857757112, 11504.482298296576, 11752.236104653202, 11780.528778848846, 11939.701507462318, 10424.166066714664, 10549.84175237372, 10554.261201715883, 10569.577216911315, 10582.047615714584, 2146.570685862826, 2452.7179374372, 2490.9626355604682, 2492.3769057735567, 2501.949961000779, 2287.931362059141, 2487.067349743846, 2526.3810366460243, 2530.8354956927833, 2536.9238922832346, 2072.0, 2472.913448029319, 2485.1674688979215, 2507.485965377093, 2509.0, 2446.4617784087473, 2471.925842224727, 2493.488834788873, 2506.9247922562354, 2529.519391315644, 2485.888135033928, 2502.94994100117, 2514.9371265718505, 2514.9874250628814, 2518.7355327690693, 11346.43267836613, 11628.883711162865, 11637.88362116385, 11639.76720465586, 11663.066954643607, 6011.879762404727, 6169.04379821124, 6178.536609754242, 6189.009758438673, 6190.0, 3707.870224542155, 3795.7532760370486, 3836.9424458632866, 3838.42423636454, 3910.120222949842, 1267.8922291605131, 1268.2517314784322, 1293.7973249597596, 1319.9538016169388, 1330.1819381080663, 14276.0, 14377.883846486273, 15444.0733555987, 15850.682986340324, 15956.0, 4144.772037537964, 4818.68678535901, 4967.677100988424, 4999.875003124878, 5008.0, 12065.577704780291, 12082.818757718642, 12090.516379344903, 12118.969887559657, 12122.636320910387, 5310.628256022042, 6012.789552365646, 6077.179580756541, 6078.6048906820915, 6088.452039316394, 5444.047291723932, 5572.554195664337, 5591.860203494945, 5645.463680950304, 5655.632383895034, 4031.1937612477623, 4072.53165885921, 4120.876373708794, 4148.191102734998, 4157.417961485395, 996.8205722969935, 1061.1669839176209, 1088.1077516436446, 1090.6019302954523, 1101.823708206683, 973.7127547373542, 991.4150651115876, 1000.9399436033716, 1008.0965232576634, 1015.6496008876926, 6724.596524208563, 6769.8646027079185, 6819.863602727918, 6952.965235173791, 7018.87659991299, 1057.2757661002238, 1074.0762943868267, 1098.0940723902813, 1112.0, 1113.2318700096907, 3734.775913445202, 4140.710150289451, 4222.514410842731, 4401.427814384174, 4557.749323787223, 13905.235212063231, 14513.637159071108, 14537.345819438151, 14538.200398977946, 14539.200343981183, 14064.71870562583, 14077.577672669837, 14449.133052016912, 14521.419143234152, 14545.127292362493, 10541.789164216827, 10566.894331056592, 10644.095251903675, 10677.252592318619, 10718.89281107179, 6291.276503202191, 6799.694013769294, 6930.757423490154, 6955.756548520778, 7042.0, 11844.526218951145, 12229.816552751894, 12230.816537752118, 12256.632301030982, 12315.75368492643, 12318.0, 12341.0, 12359.0, 12521.87478125207, 12547.121701480808, 8723.214910658071, 8861.911380886366, 8863.601137948679, 9395.85906211421, 9870.062344077302, 9227.815443690954, 10959.726006849893, 11024.448777560929, 11040.33757974524, 11076.39079850616, 7836.451448398558, 9061.592228349607, 9088.59101340428, 9158.542072896462, 9186.448813071238, 8943.0, 8969.596368163318, 8999.37004409685, 9016.233620142362, 9115.863262050942, 7284.890726639211, 7681.423893208143, 7995.960020199862, 8051.114377418597, 8099.473534220257, 5014.297998280316, 5513.779448822002, 5966.880662386641, 5970.253718285215, 6045.063015232646, 5313.256144139901, 6085.361037091042, 6171.537134714825, 6266.749330026748, 6338.809835704937], "value": 5304.079522595812, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.7194244604317, 384.45599476740415, 386.750545897896, 400.3814107204367, 404.70456566706304, 4573.896007836395, 4645.001324715186, 5300.416954135042, 5381.3362385619075, 5464.005637942152, 4719.9468231319315, 5295.875433494238, 5335.839924802252, 5352.6813375707015, 5374.7043912584795, 4062.329715596923, 4287.464066991626, 4472.396226509412, 4511.917139886436, 4530.705504142236, 3920.8627698030573, 3969.729686500321, 4137.565555616662, 4173.0, 4391.231534481453, 4132.132252227021, 4332.935005974897, 4489.753063581502, 4508.278675411935, 4548.272276435771, 3813.408921617126, 4088.855120566246, 4269.6370808481315, 4301.677374196932, 4451.243288640939, 3732.9066773330615, 3810.333191691443, 3841.673457756094, 4060.2796126595254, 4074.5110586729784, 4649.674522783406, 4675.462321832999, 5120.590352771769, 5211.843644690627, 5258.92111618326, 4809.711417314972, 4821.54195351441, 5092.59259259262, 5118.923216151761, 5122.590192784568, 5261.394939581921, 5485.75314110862, 5515.172724091378, 5720.710591409956, 5745.569082318842, 3863.4398012288343, 4099.97950010248, 4261.488621365425, 4560.931586026212, 4616.460946479457, 5080.441151473372, 5791.855203619901, 6088.573799834014, 6102.938970610281, 6104.328523862371, 4655.742949403642, 4682.682072374197, 4688.218476978726, 4690.388400931264, 4727.983483550985, 4188.874333769992, 4305.289627211475, 4349.173657005159, 4602.318145483667, 4688.757479267997, 4747.240441529339, 4875.658703890765, 5059.797608095709, 5143.8456846294675, 5144.742762861842, 4861.440934292602, 5041.974790126026, 5274.3670759509105, 5637.605367624227, 5646.661200327993, 1447.8190226221723, 1552.5793898582897, 1591.2680167123021, 1596.5769071196141, 1616.2969108437867, 1134.6199023327279, 1182.7930112230326, 1214.3624597086423, 1229.6188181663713, 1236.9381530923417, 4553.609355088605, 4786.880327991828, 5331.5201631853315, 5344.305240318734, 5428.5928555358505, 4982.2775697524385, 5465.327145290798, 5563.666180029211, 5930.2883653960935, 5947.375525569752, 4838.975805120952, 5120.948790512121, 5153.768080436389, 5182.823214250024, 5362.946370536245, 2482.689663792026, 2703.8377697338224, 2962.6296712910885, 3088.9536656950163, 3092.659807421182, 4910.8772280693265, 5837.67892765902, 6108.5723999319625, 6176.567640265228, 6185.082624386455, 4856.2958371036, 5198.428172900978, 5329.573634109262, 5583.69289689071, 5902.409759024149, 5151.922721159186, 5378.731063446812, 5636.774529018793, 5883.793822266425, 6070.785842831451, 5059.949400506095, 5388.434214407431, 5555.694436806015, 5979.953508135972, 6019.939800602113, 5234.97382513085, 5642.915356269577, 5840.912386314124, 5928.67392293428, 5945.910811337747, 5677.51741102011, 6163.120248324304, 6188.81433556994, 6203.797651833715, 6274.341194174728, 4408.470983482002, 4516.232240519185, 4690.701556237487, 4694.319323698113, 4723.905521889612, 5218.869528261824, 5261.763220655156, 5305.204219367126, 5540.833774986757, 5778.884422311614, 5147.40804807437, 5403.378611459732, 5647.435256474319, 5745.396733342939, 5765.308163020464, 5063.025420086243, 5064.518870707277, 5128.405391024982, 5290.709011004432, 5730.856728581819, 4669.0428462165855, 4879.658423910292, 5278.89442211146, 5541.085720856014, 5602.562885144975, 5162.0, 5242.790288388422, 5388.568914486753, 5467.863303417446, 5748.597598168255, 4796.8081276748535, 5140.291919045681, 5421.728913554385, 5460.5085542301385, 5518.599409256986], "value": 4315.495713593425, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [666.7899611622336, 734.0054226523058, 802.39018346057, 802.6668932393058, 913.7350168451147, 16226.432074877383, 17046.403375881848, 18149.81850181501, 18415.07924603768, 18460.44618661439, 14418.711625767504, 16417.17914104293, 16561.91719041406, 16746.24641891115, 16876.66246675066, 995.4525011243824, 1019.0675531888313, 1023.3706270643538, 1024.390487659845, 1024.5799222318851, 901.8466860633708, 966.5119114846992, 1015.9796804063951, 1026.4457193115709, 1028.2339656955578, 1080.9945950270237, 1083.106437189318, 1083.2471432354516, 1083.6423980086552, 1089.1776708585007, 1063.388551582839, 1073.280901795791, 1079.4656644960717, 1080.4813689429118, 1080.983785243222, 794.9602519873984, 795.1690483444814, 795.5147360110328, 796.80079980005, 797.1112209885994, 17372.391966281244, 17656.823431765773, 17732.822671773247, 18332.63334733311, 18462.90768546162, 14751.0, 14842.25788710571, 14906.36030036695, 14978.452575715328, 15218.391264349413, 11590.768184636345, 11927.343996080212, 12003.57987470443, 12013.459394327276, 12163.51345946161, 3387.8814241501427, 3496.8426420811124, 3728.571214310362, 3845.2693988142164, 3995.7802320872343, 104517.0, 106934.46532767364, 116487.41756291164, 118904.81095189023, 125418.37290813579, 11301.49143288554, 11419.429028548539, 11494.310341379543, 11513.194076414738, 11545.103035068198, 23830.88084559566, 23864.761352386253, 23964.281071567882, 24013.399665008164, 24455.144069957467, 7733.0, 7943.324817390587, 8013.11855695873, 8038.155993620702, 8124.146964568635, 4555.9772201138785, 4565.657575681837, 4604.447466303998, 4638.628909687217, 4723.95276047242, 3659.2681463707363, 3719.5536535615897, 3735.364987952055, 3749.4375843623675, 3763.209725957525, 664.5879554676035, 667.436016566001, 671.419222372654, 686.2142846440897, 686.5468790598181, 606.3542327421283, 678.3691167214538, 714.8070021094276, 727.5016613619688, 727.7198278662753, 10239.539220734936, 10540.420276884692, 10757.56969721218, 10843.295185812896, 11106.777864442665, 648.5622205011598, 656.9947979591233, 694.6422592364936, 718.3318079501303, 741.4624397311906, 5161.870953226199, 5255.264263003182, 5277.973610131925, 5290.259363689087, 5332.9733351333, 98178.0, 101683.0, 101898.49050754699, 102260.48869755604, 103226.48386758019, 84247.57876210702, 86634.0, 87235.69146462808, 88404.5579772097, 88536.6719499208, 78756.21243787488, 79659.0, 80161.19838801654, 80962.59518702487, 81962.77055844167, 10053.396796192252, 10089.041541053588, 10222.182225422097, 10223.642172523925, 10288.485575721334, 52714.68213294698, 53058.20412693735, 53669.571630018974, 54084.18873716976, 55577.72211138919, 66879.0, 67715.0, 68336.97494537686, 68729.96905046329, 69531.0, 11527.76398132532, 11552.59565915189, 11592.768144636892, 12151.75696486083, 12221.144519883523, 12990.805137922749, 13377.197368157942, 13397.464101435833, 13528.661783455493, 13530.661733456742, 9534.761630959281, 11827.994620457013, 11993.100517461051, 12327.445264962918, 12602.810957835456, 11753.0, 11764.117691173025, 12323.322217278135, 12537.874621254035, 12660.430280637209, 11103.866693874592, 11230.157738169833, 11348.262362946381, 12045.698857528281, 12081.697957551132, 9293.953530232306, 9560.139587437165, 9629.0, 9867.111959923353, 10092.798144036931, 9954.850677239992, 10583.682489525327, 10636.042756151983, 10650.89349106499, 10659.520321585393], "value": 7810.101830233201, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1548.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 119.0, 119.0, 99.0, 176.0, 166.0, 136.0, 121.0, 119.0, 119.0], "value": 128.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 106.0, 130.0, 142.0, 110.0, 114.0, 110.0, 112.0, 129.0, 110.0], "value": 120.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 197.0, 196.0, 205.0, 220.0, 192.0, 194.0, 279.0, 274.0, 195.0], "value": 215.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 63.0, 88.0, 60.0, 60.0, 60.0, 60.0, 76.0, 60.0], "value": 66.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 70.0, 94.0, 71.0, 57.0, 86.0, 110.0, 63.0, 86.0, 69.0], "value": 79.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 94.0, 101.0, 130.0, 93.0, 92.0, 104.0, 129.0, 93.0, 129.0], "value": 107.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 98.0, 98.0, 99.0, 98.0, 99.0, 99.0, 99.0, 129.0], "value": 101.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 98.0, 89.0, 136.0, 86.0, 142.0, 89.0, 139.0, 137.0, 90.0], "value": 109.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 57.0, 61.0, 73.0, 69.0, 57.0, 95.0, 63.0, 64.0, 67.0], "value": 66.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 66.0, 65.0, 67.0, 61.0, 59.0, 60.0, 60.0, 62.0, 60.0], "value": 61.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 86.0, 80.0, 80.0, 85.0, 84.0, 101.0, 89.0, 88.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 141.0, 145.0, 138.0, 134.0, 137.0, 139.0, 161.0, 208.0, 180.0], "value": 152.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 152.0, 210.0, 175.0, 170.0, 177.0, 170.0, 213.0, 175.0, 169.0], "value": 178.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 78.0, 80.0, 83.0, 79.0, 84.0, 84.0, 68.0, 64.0, 64.0], "value": 75.1, "unit": "score"}]}]} 10:35:25 INFO - Return code: 0 10:35:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:35:25 ERROR - # TBPL SUCCESS # 10:35:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:35:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:35:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:35:25 INFO - 2017-05-15 10:35:25.124 screenresolution[1989:13242] starting screenresolution argv=screenresolution get 10:35:25 INFO - 2017-05-15 10:35:25.126 screenresolution[1989:13242] Display 0: 1600x1200x32@60 10:35:25 INFO - 2017-05-15 10:35:25.132 screenresolution[1990:13245] starting screenresolution argv=screenresolution list 10:35:25 INFO - Available Modes on Display 0 10:35:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:35:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:35:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:35:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:35:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:35:25 INFO - Intel Iris: 10:35:25 INFO - Chipset Model: Intel Iris 10:35:25 INFO - Type: GPU 10:35:25 INFO - Bus: Built-In 10:35:25 INFO - VRAM (Dynamic, Max): 1536 MB 10:35:25 INFO - Vendor: Intel (0x8086) 10:35:25 INFO - Device ID: 0x0a2e 10:35:25 INFO - Revision ID: 0x0009 10:35:25 INFO - Displays: 10:35:25 INFO - Display: 10:35:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:35:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:35:25 INFO - Display Serial Number: 1600x1200 60 10:35:25 INFO - Main Display: Yes 10:35:25 INFO - Mirror: Off 10:35:25 INFO - Online: Yes 10:35:25 INFO - Rotation: Supported 10:35:25 INFO - Return code: 0 10:35:25 INFO - Running post-action listener: _resource_record_post_action 10:35:25 INFO - [mozharness: 2017-05-15 17:35:25.365152Z] Finished run-tests step (success) 10:35:25 INFO - Running post-run listener: _resource_record_post_run 10:35:25 INFO - Total resource usage - Wall time: 1193s; CPU: 55.0%; Read bytes: 1351680; Write bytes: 428956160; Read time: 14; Write time: 1625 10:35:25 INFO - TinderboxPrint: CPU usage
54.8% 10:35:25 INFO - TinderboxPrint: I/O read bytes / time
1,351,680 / 14 10:35:25 INFO - TinderboxPrint: I/O write bytes / time
428,956,160 / 1,625 10:35:25 INFO - TinderboxPrint: CPU idle
2,156.4 (45.2%) 10:35:25 INFO - TinderboxPrint: CPU system
966.4 (20.2%) 10:35:25 INFO - TinderboxPrint: CPU user
1,650.9 (34.6%) 10:35:25 INFO - TinderboxPrint: Swap in / out
122,732,544 / 0 10:35:25 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 114070016; Write bytes: 117598208; Read time: 11128; Write time: 305 10:35:25 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 1351680; Write bytes: 301433344; Read time: 14; Write time: 1291 10:35:25 INFO - Running post-run listener: _upload_blobber_files 10:35:25 INFO - Blob upload gear active. 10:35:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:35:25 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 10:35:25 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'] 10:35:25 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 10:35:26 INFO - (blobuploader) - INFO - Open directory for files ... 10:35:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:35:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:35:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:35:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:35:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:35:26 INFO - (blobuploader) - INFO - Done attempting. 10:35:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:35:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:35:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:35:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:35:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:35:27 INFO - (blobuploader) - INFO - Done attempting. 10:35:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:35:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:35:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:35:28 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:35:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:35:28 INFO - (blobuploader) - INFO - Done attempting. 10:35:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:35:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:35:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:35:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:35:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:35:31 INFO - (blobuploader) - INFO - Done attempting. 10:35:31 INFO - (blobuploader) - INFO - Iteration through files over. 10:35:31 INFO - Return code: 0 10:35:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:35:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:35:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/724c770ab11b09b68c074be6d6bd258fcf9d36b6e13c269b655199ec9340853770ea8d3fa2d48d4476eb86bccea608ae824fa008afddda78a0553dc6e1d0b84b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8ea48ec7af8dfe09f4a744bb7533affb77b4f0427811e11d26b5fcaeae6ac8da3f2a4f9ff525aab57da37aae253d58cf8dd3765665f49df757089dd0b521bd88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d9d82fc07c38368c2bd95263c23ba9b4c97c0d4e91f213a88c5676f57072d60c2f17ad15b8db0afe51031f63ef928854b801f6e8a1db8db15f094050689172b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20c47b557cf13c554a04d4a6876d58b995ec68250cf70fb2e538733114b8a8ee7a94067f6b0911c0f1e580cf9183a65bb001ef618c8ada5f7f15e7acf2da3e59"} 10:35:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:35:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:35:31 INFO - Contents: 10:35:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/724c770ab11b09b68c074be6d6bd258fcf9d36b6e13c269b655199ec9340853770ea8d3fa2d48d4476eb86bccea608ae824fa008afddda78a0553dc6e1d0b84b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8ea48ec7af8dfe09f4a744bb7533affb77b4f0427811e11d26b5fcaeae6ac8da3f2a4f9ff525aab57da37aae253d58cf8dd3765665f49df757089dd0b521bd88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d9d82fc07c38368c2bd95263c23ba9b4c97c0d4e91f213a88c5676f57072d60c2f17ad15b8db0afe51031f63ef928854b801f6e8a1db8db15f094050689172b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20c47b557cf13c554a04d4a6876d58b995ec68250cf70fb2e538733114b8a8ee7a94067f6b0911c0f1e580cf9183a65bb001ef618c8ada5f7f15e7acf2da3e59"} 10:35:31 INFO - Running post-run listener: copy_logs_to_upload_dir 10:35:31 INFO - Copying logs to upload dir... 10:35:31 INFO - mkdir: /builds/slave/test/build/upload/logs 10:35:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.545992 ========= master_lag: 0.68 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2017-05-15 10:35:31.766569) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 10:35:31.769689) ========= 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.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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/724c770ab11b09b68c074be6d6bd258fcf9d36b6e13c269b655199ec9340853770ea8d3fa2d48d4476eb86bccea608ae824fa008afddda78a0553dc6e1d0b84b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8ea48ec7af8dfe09f4a744bb7533affb77b4f0427811e11d26b5fcaeae6ac8da3f2a4f9ff525aab57da37aae253d58cf8dd3765665f49df757089dd0b521bd88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d9d82fc07c38368c2bd95263c23ba9b4c97c0d4e91f213a88c5676f57072d60c2f17ad15b8db0afe51031f63ef928854b801f6e8a1db8db15f094050689172b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20c47b557cf13c554a04d4a6876d58b995ec68250cf70fb2e538733114b8a8ee7a94067f6b0911c0f1e580cf9183a65bb001ef618c8ada5f7f15e7acf2da3e59"} build_url:https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011375 build_url: 'https://queue.taskcluster.net/v1/task/HP6vrUUYQxOsWsGlbb_wXg/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/724c770ab11b09b68c074be6d6bd258fcf9d36b6e13c269b655199ec9340853770ea8d3fa2d48d4476eb86bccea608ae824fa008afddda78a0553dc6e1d0b84b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8ea48ec7af8dfe09f4a744bb7533affb77b4f0427811e11d26b5fcaeae6ac8da3f2a4f9ff525aab57da37aae253d58cf8dd3765665f49df757089dd0b521bd88", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d9d82fc07c38368c2bd95263c23ba9b4c97c0d4e91f213a88c5676f57072d60c2f17ad15b8db0afe51031f63ef928854b801f6e8a1db8db15f094050689172b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/20c47b557cf13c554a04d4a6876d58b995ec68250cf70fb2e538733114b8a8ee7a94067f6b0911c0f1e580cf9183a65bb001ef618c8ada5f7f15e7acf2da3e59"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 10:35:31.806737) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:35:31.807075) ========= 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.tJ9KWcoANr/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.Vvf0PtqMHs/Listeners TMPDIR=/var/folders/_n/kpth4c2s6392plpy6hkyfjq000000w/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.004745 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 10:35:31.855014) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 10:35:31.855357) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 10:35:31.855749) ========= ========= Total master_lag: 0.82 =========