builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0356 starttime: 1495804251.43 results: success (0) buildid: 20170526051316 builduid: 824a5726da8640f6b69092bc3b08c616 revision: a6718df8269d161f0e2685b1266f7dcc981a751d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 06:10:51.435365) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-26 06:10:51.436102) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 06:10:51.436594) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-26 06:10:51.463437) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 06:10:51.464115) ========= 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.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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-26 06:10:51-- 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% 16.9M=0.001s 2017-05-26 06:10:51 (16.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.095157 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 06:10:51.583913) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 06:10:51.584325) ========= 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.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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.027041 ========= master_lag: 0.22 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 06:10:51.831418) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-26 06:10:51.831784) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev a6718df8269d161f0e2685b1266f7dcc981a751d --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 a6718df8269d161f0e2685b1266f7dcc981a751d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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-26 06:10:51,935 truncating revision to first 12 chars 2017-05-26 06:10:51,935 Setting DEBUG logging. 2017-05-26 06:10:51,936 attempt 1/10 2017-05-26 06:10:51,936 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/a6718df8269d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-26 06:10:53,923 unpacking tar archive at: mozilla-beta-a6718df8269d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.270654 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-26 06:10:54.161827) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 06:10:54.162634) ========= script_repo_revision: a6718df8269d161f0e2685b1266f7dcc981a751d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-26 06:10:54.163449) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 06:10:54.163950) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-26 06:10:54.184892) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-26 06:10:54.185594) ========= /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.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 06:10:54 INFO - MultiFileLogger online at 20170526 06:10:54 in /builds/slave/test 06:10:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 06:10:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:10:54 INFO - {'append_to_log': False, 06:10:54 INFO - 'base_work_dir': '/builds/slave/test', 06:10:54 INFO - 'blob_upload_branch': 'Mozilla-Beta', 06:10:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:10:54 INFO - 'branch': 'Mozilla-Beta', 06:10:54 INFO - 'buildbot_json_path': 'buildprops.json', 06:10:54 INFO - 'config_files': ('talos/mac_config.py',), 06:10:54 INFO - 'default_actions': ('clobber', 06:10:54 INFO - 'read-buildbot-config', 06:10:54 INFO - 'download-and-extract', 06:10:54 INFO - 'populate-webroot', 06:10:54 INFO - 'create-virtualenv', 06:10:54 INFO - 'install', 06:10:54 INFO - 'run-tests'), 06:10:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:10:54 INFO - 'download_minidump_stackwalk': True, 06:10:54 INFO - 'download_symbols': 'ondemand', 06:10:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:10:54 INFO - 'tooltool.py': '/tools/tooltool.py', 06:10:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:10:54 INFO - '/tools/misc-python/virtualenv.py')}, 06:10:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:10:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:10:54 INFO - 'gecko_profile': False, 06:10:54 INFO - 'gecko_profile_interval': 0, 06:10:54 INFO - 'installer_path': 'installer.exe', 06:10:54 INFO - 'log_level': 'info', 06:10:54 INFO - 'log_name': 'talos', 06:10:54 INFO - 'log_to_console': True, 06:10:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:10:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:10:54 INFO - 'opt_config_files': (), 06:10:54 INFO - 'pip_index': False, 06:10:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:10:54 INFO - 'cmd': ('bash', 06:10:54 INFO - '-c', 06:10:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:10:54 INFO - 'enabled': True, 06:10:54 INFO - 'halt_on_failure': False, 06:10:54 INFO - 'name': 'check_screen_resolution'},), 06:10:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:10:54 INFO - 'cmd': ('bash', 06:10:54 INFO - '-c', 06:10:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:10:54 INFO - 'enabled': True, 06:10:54 INFO - 'halt_on_failure': False, 06:10:54 INFO - 'name': 'check_screen_resolution'},), 06:10:54 INFO - 'run_cmd_checks_enabled': True, 06:10:54 INFO - 'suite': 'dromaeojs-e10s', 06:10:54 INFO - 'system_bits': '32', 06:10:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:10:54 INFO - 'title': 't-yosemite-r7-0356', 06:10:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:10:54 INFO - 'use_talos_json': True, 06:10:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:10:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:10:54 INFO - 'work_dir': 'build'} 06:10:54 INFO - [mozharness: 2017-05-26 13:10:54.396553Z] Running clobber step. 06:10:54 INFO - Running pre-action listener: _resource_record_pre_action 06:10:54 INFO - Running main action method: clobber 06:10:54 INFO - rmtree: /builds/slave/test/build 06:10:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:10:56 INFO - Running post-action listener: _resource_record_post_action 06:10:56 INFO - [mozharness: 2017-05-26 13:10:56.914222Z] Finished clobber step (success) 06:10:56 INFO - [mozharness: 2017-05-26 13:10:56.914297Z] Running read-buildbot-config step. 06:10:56 INFO - Running pre-action listener: _resource_record_pre_action 06:10:56 INFO - Running main action method: read_buildbot_config 06:10:56 INFO - Using buildbot properties: 06:10:56 INFO - { 06:10:56 INFO - "project": "", 06:10:56 INFO - "product": "firefox", 06:10:56 INFO - "script_repo_revision": "production", 06:10:56 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 06:10:56 INFO - "repository": "", 06:10:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 06:10:56 INFO - "buildid": "20170526051316", 06:10:56 INFO - "pgo_build": "False", 06:10:56 INFO - "basedir": "/builds/slave/test", 06:10:56 INFO - "buildnumber": 6, 06:10:56 INFO - "slavename": "t-yosemite-r7-0356", 06:10:56 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 06:10:56 INFO - "platform": "yosemite_r7", 06:10:56 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 06:10:56 INFO - "branch": "mozilla-beta", 06:10:56 INFO - "repo_path": "releases/mozilla-beta", 06:10:56 INFO - "revision": "a6718df8269d161f0e2685b1266f7dcc981a751d", 06:10:56 INFO - "stage_platform": "macosx64", 06:10:56 INFO - "builduid": "824a5726da8640f6b69092bc3b08c616", 06:10:56 INFO - "slavebuilddir": "test" 06:10:56 INFO - } 06:10:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 06:10:56 INFO - Running post-action listener: _resource_record_post_action 06:10:56 INFO - [mozharness: 2017-05-26 13:10:56.917637Z] Finished read-buildbot-config step (success) 06:10:56 INFO - [mozharness: 2017-05-26 13:10:56.917777Z] Running download-and-extract step. 06:10:56 INFO - Running pre-action listener: _resource_record_pre_action 06:10:56 INFO - Running main action method: download_and_extract 06:10:56 INFO - mkdir: /builds/slave/test/build/tests 06:10:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:10:56 INFO - trying https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 06:10:56 INFO - Downloading https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 06:10:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 06:10:58 INFO - Downloaded 1287 bytes. 06:10:58 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 06:10:58 INFO - Using the following test package requirements: 06:10:58 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:10:58 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 06:10:58 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 06:10:58 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:10:58 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 06:10:58 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:10:58 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 06:10:58 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 06:10:58 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:10:58 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 06:10:58 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 06:10:58 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:10:58 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 06:10:58 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:10:58 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 06:10:58 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:10:58 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 06:10:58 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 06:10:58 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 06:10:58 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 06:10:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 06:10:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 06:10:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 06:10:59 INFO - Expected file size: 51393577 06:10:59 INFO - Obtained file size: 51393577 06:11:15 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 06:11:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 06:11:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 06:11:15 INFO - Fetch https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 06:11:17 INFO - Expected file size: 51393577 06:11:17 INFO - Obtained file size: 51393577 06:11:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 06:11:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 06:11:32 INFO - Fetch https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 06:11:33 INFO - Expected file size: 14404449 06:11:33 INFO - Obtained file size: 14404449 06:11:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:34 INFO - trying https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:11:34 INFO - Downloading https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 06:11:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 06:11:35 INFO - Downloaded 59647708 bytes. 06:11:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:11:35 INFO - mkdir: /builds/slave/test/properties 06:11:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:11:35 INFO - Writing to file /builds/slave/test/properties/build_url 06:11:35 INFO - Contents: 06:11:35 INFO - build_url:https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.dmg 06:11:36 INFO - Running post-action listener: _resource_record_post_action 06:11:36 INFO - Running post-action listener: set_extra_try_arguments 06:11:36 INFO - [mozharness: 2017-05-26 13:11:36.457834Z] Finished download-and-extract step (success) 06:11:36 INFO - [mozharness: 2017-05-26 13:11:36.457992Z] Running populate-webroot step. 06:11:36 INFO - Running pre-action listener: _resource_record_pre_action 06:11:36 INFO - Running main action method: populate_webroot 06:11:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:11:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:11:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:11:36 INFO - '--tppagecycles', 06:11:36 INFO - '7'], 06:11:36 INFO - 'tests': ['tsvgm']}}, 06:11:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:11:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:11:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:11:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:11:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:11:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:11:36 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 06:11:36 INFO - 'talos_options': ['--disable-e10s'], 06:11:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:11:36 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 06:11:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:11:36 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 06:11:36 INFO - 'talos_options': ['--disable-e10s'], 06:11:36 INFO - 'tests': ['damp', 'tps']}, 06:11:36 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 06:11:36 INFO - 'tests': ['damp', 'tps']}, 06:11:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:11:36 INFO - 'tests': ['dromaeo_dom']}, 06:11:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:11:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:11:36 INFO - 'tests': ['basic_compositor_video']}, 06:11:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:11:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:11:36 INFO - 'tests': ['a11yr', 06:11:36 INFO - 'ts_paint', 06:11:36 INFO - 'tpaint', 06:11:36 INFO - 'sessionrestore', 06:11:36 INFO - 'sessionrestore_no_auto_restore', 06:11:36 INFO - 'tabpaint']}, 06:11:36 INFO - 'other-e10s': {'tests': ['a11yr', 06:11:36 INFO - 'ts_paint', 06:11:36 INFO - 'tpaint', 06:11:36 INFO - 'sessionrestore', 06:11:36 INFO - 'sessionrestore_no_auto_restore', 06:11:36 INFO - 'tabpaint']}, 06:11:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:11:36 INFO - 'ts_paint', 06:11:36 INFO - 'tpaint', 06:11:36 INFO - 'sessionrestore', 06:11:36 INFO - 'sessionrestore_no_auto_restore', 06:11:36 INFO - 'tabpaint']}, 06:11:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:11:36 INFO - 'ts_paint', 06:11:36 INFO - 'tpaint', 06:11:36 INFO - 'sessionrestore', 06:11:36 INFO - 'sessionrestore_no_auto_restore', 06:11:36 INFO - 'tabpaint']}, 06:11:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:11:36 INFO - 'tests': ['a11yr', 06:11:36 INFO - 'ts_paint', 06:11:36 INFO - 'tpaint', 06:11:36 INFO - 'sessionrestore', 06:11:36 INFO - 'sessionrestore_no_auto_restore', 06:11:36 INFO - 'tabpaint']}, 06:11:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:11:36 INFO - 'tests': ['a11yr', 06:11:36 INFO - 'ts_paint', 06:11:36 INFO - 'tpaint', 06:11:36 INFO - 'sessionrestore', 06:11:36 INFO - 'sessionrestore_no_auto_restore', 06:11:36 INFO - 'tabpaint']}, 06:11:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:11:36 INFO - 'tests': ['tsvgx', 06:11:36 INFO - 'tsvgr_opacity', 06:11:36 INFO - 'tart', 06:11:36 INFO - 'tscrollx', 06:11:36 INFO - 'cart', 06:11:36 INFO - 'tsvg_static']}, 06:11:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:11:36 INFO - 'tsvgr_opacity', 06:11:36 INFO - 'tart', 06:11:36 INFO - 'tscrollx', 06:11:36 INFO - 'cart', 06:11:36 INFO - 'tsvg_static']}, 06:11:36 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 06:11:36 INFO - 'talos_options': ['--disable-e10s'], 06:11:36 INFO - 'tests': ['tp5o']}, 06:11:36 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 06:11:36 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 06:11:36 INFO - 'talos_options': ['--disable-e10s', 06:11:36 INFO - '--xperf_path', 06:11:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:11:36 INFO - 'tests': ['tp5n']}, 06:11:36 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 06:11:36 INFO - 'talos_options': ['--xperf_path', 06:11:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:11:36 INFO - 'tests': ['tp5n']}}, 06:11:36 INFO - 'talos.zip': {'path': '', 06:11:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:11:36 INFO - Running post-action listener: _resource_record_post_action 06:11:36 INFO - [mozharness: 2017-05-26 13:11:36.478252Z] Finished populate-webroot step (success) 06:11:36 INFO - [mozharness: 2017-05-26 13:11:36.478387Z] Running create-virtualenv step. 06:11:36 INFO - Running pre-action listener: _resource_record_pre_action 06:11:36 INFO - Running main action method: create_virtualenv 06:11:36 INFO - Creating virtualenv /builds/slave/test/build/venv 06:11:36 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 06:11:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:11:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:11:36 INFO - Using real prefix '/tools/python27' 06:11:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:11:37 INFO - Installing distribute.............................................................................................................................................................................................done. 06:11:40 INFO - Installing pip.................done. 06:11:40 INFO - Return code: 0 06:11:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 06:11:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 06:11:40 INFO - Reading from file tmpfile_stdout 06:11:40 INFO - Output received: 06:11:40 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 06:11:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:40 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 0x104aadb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ab0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec40d9c590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488bd30>, '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 0x1047b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104880be8>, '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.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:11:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 06:11:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 06:11:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cd1ueBuj8Q/Render', 06:11:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:40 INFO - 'HOME': '/Users/cltbld', 06:11:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:40 INFO - 'LOGNAME': 'cltbld', 06:11:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:40 INFO - 'MOZ_NO_REMOTE': '1', 06:11:40 INFO - 'NO_EM_RESTART': '1', 06:11:40 INFO - 'PAGER': '/bin/cat', 06:11:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:40 INFO - 'PWD': '/builds/slave/test', 06:11:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:40 INFO - 'SHELL': '/bin/bash', 06:11:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qFXvqXJyUK/Listeners', 06:11:40 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 06:11:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:40 INFO - 'USER': 'cltbld', 06:11:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:40 INFO - 'XPC_FLAGS': '0x0', 06:11:40 INFO - 'XPC_SERVICE_NAME': '0', 06:11:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:40 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 06:11:40 INFO - Cleaning up... 06:11:40 INFO - Return code: 0 06:11:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:40 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 0x104aadb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ab0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec40d9c590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488bd30>, '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 0x1047b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104880be8>, '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.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:11:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 06:11:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 06:11:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cd1ueBuj8Q/Render', 06:11:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:40 INFO - 'HOME': '/Users/cltbld', 06:11:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:40 INFO - 'LOGNAME': 'cltbld', 06:11:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:40 INFO - 'MOZ_NO_REMOTE': '1', 06:11:40 INFO - 'NO_EM_RESTART': '1', 06:11:40 INFO - 'PAGER': '/bin/cat', 06:11:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:40 INFO - 'PWD': '/builds/slave/test', 06:11:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:40 INFO - 'SHELL': '/bin/bash', 06:11:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qFXvqXJyUK/Listeners', 06:11:40 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 06:11:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:40 INFO - 'USER': 'cltbld', 06:11:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:40 INFO - 'XPC_FLAGS': '0x0', 06:11:40 INFO - 'XPC_SERVICE_NAME': '0', 06:11:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:40 INFO - Downloading/unpacking psutil>=3.1.1 06:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:11:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:11:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:11:42 INFO - Installing collected packages: psutil 06:11:42 INFO - Running setup.py install for psutil 06:11:43 INFO - building 'psutil._psutil_osx' extension 06:11:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 06:11:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 06:11:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 06:11:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 06:11:43 INFO - building 'psutil._psutil_posix' extension 06:11:43 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 06:11:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:11:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:11:43 INFO - ^ 06:11:43 INFO - 1 warning generated. 06:11:43 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 06:11:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:11:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:11:44 INFO - Successfully installed psutil 06:11:44 INFO - Cleaning up... 06:11:44 INFO - Return code: 0 06:11:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104aadb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ab0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec40d9c590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488bd30>, '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 0x1047b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104880be8>, '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.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:11:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 06:11:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 06:11:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cd1ueBuj8Q/Render', 06:11:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:44 INFO - 'HOME': '/Users/cltbld', 06:11:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:44 INFO - 'LOGNAME': 'cltbld', 06:11:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:44 INFO - 'MOZ_NO_REMOTE': '1', 06:11:44 INFO - 'NO_EM_RESTART': '1', 06:11:44 INFO - 'PAGER': '/bin/cat', 06:11:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:44 INFO - 'PWD': '/builds/slave/test', 06:11:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:44 INFO - 'SHELL': '/bin/bash', 06:11:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qFXvqXJyUK/Listeners', 06:11:44 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 06:11:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:44 INFO - 'USER': 'cltbld', 06:11:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:44 INFO - 'XPC_FLAGS': '0x0', 06:11:44 INFO - 'XPC_SERVICE_NAME': '0', 06:11:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:11:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:11:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:11:45 INFO - Installing collected packages: mozsystemmonitor 06:11:45 INFO - Running setup.py install for mozsystemmonitor 06:11:45 INFO - Successfully installed mozsystemmonitor 06:11:45 INFO - Cleaning up... 06:11:46 INFO - Return code: 0 06:11:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:46 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 0x104aadb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ab0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec40d9c590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488bd30>, '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 0x1047b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104880be8>, '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.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:11:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:11:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 06:11:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cd1ueBuj8Q/Render', 06:11:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:46 INFO - 'HOME': '/Users/cltbld', 06:11:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:46 INFO - 'LOGNAME': 'cltbld', 06:11:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:46 INFO - 'MOZ_NO_REMOTE': '1', 06:11:46 INFO - 'NO_EM_RESTART': '1', 06:11:46 INFO - 'PAGER': '/bin/cat', 06:11:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:46 INFO - 'PWD': '/builds/slave/test', 06:11:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:46 INFO - 'SHELL': '/bin/bash', 06:11:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qFXvqXJyUK/Listeners', 06:11:46 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 06:11:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:46 INFO - 'USER': 'cltbld', 06:11:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:46 INFO - 'XPC_FLAGS': '0x0', 06:11:46 INFO - 'XPC_SERVICE_NAME': '0', 06:11:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:46 INFO - Downloading/unpacking jsonschema==2.5.1 06:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:11:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:11:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:49 INFO - Downloading functools32-3.2.3-2.tar.gz 06:11:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:11:49 INFO - warning: no files found matching '*.txt' 06:11:49 INFO - no previously-included directories found matching 'build' 06:11:49 INFO - no previously-included directories found matching 'dist' 06:11:49 INFO - no previously-included directories found matching '.git*' 06:11:49 INFO - Installing collected packages: jsonschema, functools32 06:11:49 INFO - Running setup.py install for functools32 06:11:49 INFO - warning: no files found matching '*.txt' 06:11:49 INFO - no previously-included directories found matching 'build' 06:11:49 INFO - no previously-included directories found matching 'dist' 06:11:49 INFO - no previously-included directories found matching '.git*' 06:11:49 INFO - Successfully installed jsonschema functools32 06:11:49 INFO - Cleaning up... 06:11:49 INFO - Return code: 0 06:11:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:50 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 0x104aadb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ab0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec40d9c590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488bd30>, '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 0x1047b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104880be8>, '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.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:11:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 06:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 06:11:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cd1ueBuj8Q/Render', 06:11:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:50 INFO - 'HOME': '/Users/cltbld', 06:11:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:50 INFO - 'LOGNAME': 'cltbld', 06:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:50 INFO - 'MOZ_NO_REMOTE': '1', 06:11:50 INFO - 'NO_EM_RESTART': '1', 06:11:50 INFO - 'PAGER': '/bin/cat', 06:11:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:50 INFO - 'PWD': '/builds/slave/test', 06:11:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:50 INFO - 'SHELL': '/bin/bash', 06:11:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qFXvqXJyUK/Listeners', 06:11:50 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 06:11:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:50 INFO - 'USER': 'cltbld', 06:11:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:50 INFO - 'XPC_FLAGS': '0x0', 06:11:50 INFO - 'XPC_SERVICE_NAME': '0', 06:11:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:11:50 INFO - Cleaning up... 06:11:50 INFO - Return code: 0 06:11:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:50 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 0x104aadb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ab0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec40d9c590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488bd30>, '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 0x1047b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104880be8>, '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.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:11:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:11:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 06:11:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cd1ueBuj8Q/Render', 06:11:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:50 INFO - 'HOME': '/Users/cltbld', 06:11:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:50 INFO - 'LOGNAME': 'cltbld', 06:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:50 INFO - 'MOZ_NO_REMOTE': '1', 06:11:50 INFO - 'NO_EM_RESTART': '1', 06:11:50 INFO - 'PAGER': '/bin/cat', 06:11:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:50 INFO - 'PWD': '/builds/slave/test', 06:11:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:50 INFO - 'SHELL': '/bin/bash', 06:11:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qFXvqXJyUK/Listeners', 06:11:50 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 06:11:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:50 INFO - 'USER': 'cltbld', 06:11:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:50 INFO - 'XPC_FLAGS': '0x0', 06:11:50 INFO - 'XPC_SERVICE_NAME': '0', 06:11:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:51 INFO - Downloading/unpacking blobuploader==1.2.4 06:11:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:52 INFO - Downloading blobuploader-1.2.4.tar.gz 06:11:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:11:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:11:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:11:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:11:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:11:53 INFO - Downloading docopt-0.6.1.tar.gz 06:11:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:11:53 INFO - Installing collected packages: blobuploader, requests, docopt 06:11:53 INFO - Running setup.py install for blobuploader 06:11:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:11:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:11:53 INFO - Running setup.py install for requests 06:11:54 INFO - Running setup.py install for docopt 06:11:54 INFO - Successfully installed blobuploader requests docopt 06:11:54 INFO - Cleaning up... 06:11:54 INFO - Return code: 0 06:11:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:54 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 0x104aadb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ab0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec40d9c590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488bd30>, '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 0x1047b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104880be8>, '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.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:11:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:11:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:11:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cd1ueBuj8Q/Render', 06:11:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:54 INFO - 'HOME': '/Users/cltbld', 06:11:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:54 INFO - 'LOGNAME': 'cltbld', 06:11:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:54 INFO - 'MOZ_NO_REMOTE': '1', 06:11:54 INFO - 'NO_EM_RESTART': '1', 06:11:54 INFO - 'PAGER': '/bin/cat', 06:11:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:54 INFO - 'PWD': '/builds/slave/test', 06:11:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:54 INFO - 'SHELL': '/bin/bash', 06:11:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qFXvqXJyUK/Listeners', 06:11:54 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 06:11:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:54 INFO - 'USER': 'cltbld', 06:11:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:54 INFO - 'XPC_FLAGS': '0x0', 06:11:54 INFO - 'XPC_SERVICE_NAME': '0', 06:11:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:11:54 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-O7nYBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:11:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-J9q3eJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:11:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-PI7cHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:11:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-1Z2F4B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:11:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-earjOq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:11:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-dvVSL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:11:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-6_D5_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:11:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-Jxc9g5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:11:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-IP_efg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:11:55 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-mjyQcx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:11:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-IRd5Dy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:11:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-I4Im4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:11:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-fPXb3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:11:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-bZP49j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:11:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-UeoaXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:11:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-wa1vWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:11:56 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-4_rRXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:11:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:11:56 INFO - Running setup.py install for manifestparser 06:11:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:11:56 INFO - Running setup.py install for mozcrash 06:11:57 INFO - Running setup.py install for mozdebug 06:11:57 INFO - Running setup.py install for mozdevice 06:11:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:11:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:11:57 INFO - Running setup.py install for mozfile 06:11:57 INFO - Running setup.py install for mozhttpd 06:11:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:11:57 INFO - Running setup.py install for mozinfo 06:11:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:11:57 INFO - Running setup.py install for mozInstall 06:11:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:11:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:11:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:11:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:11:57 INFO - Running setup.py install for mozleak 06:11:58 INFO - Running setup.py install for mozlog 06:11:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:11:58 INFO - Running setup.py install for moznetwork 06:11:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:11:58 INFO - Running setup.py install for mozprocess 06:11:58 INFO - Running setup.py install for mozprofile 06:11:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:11:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:11:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:11:58 INFO - Running setup.py install for mozrunner 06:11:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:11:58 INFO - Running setup.py install for mozscreenshot 06:11:59 INFO - Running setup.py install for moztest 06:11:59 INFO - Running setup.py install for mozversion 06:11:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:11:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:11:59 INFO - Cleaning up... 06:11:59 INFO - Return code: 0 06:11:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:11:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:11:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:11:59 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 0x104aadb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ab0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec40d9c590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488bd30>, '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 0x1047b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104880be8>, '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.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:11:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 06:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:11:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cd1ueBuj8Q/Render', 06:11:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:11:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:11:59 INFO - 'HOME': '/Users/cltbld', 06:11:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:11:59 INFO - 'LOGNAME': 'cltbld', 06:11:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:11:59 INFO - 'MOZ_NO_REMOTE': '1', 06:11:59 INFO - 'NO_EM_RESTART': '1', 06:11:59 INFO - 'PAGER': '/bin/cat', 06:11:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:11:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:11:59 INFO - 'PWD': '/builds/slave/test', 06:11:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:11:59 INFO - 'SHELL': '/bin/bash', 06:11:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qFXvqXJyUK/Listeners', 06:11:59 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 06:11:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:11:59 INFO - 'USER': 'cltbld', 06:11:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:11:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:11:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:11:59 INFO - 'XPC_FLAGS': '0x0', 06:11:59 INFO - 'XPC_SERVICE_NAME': '0', 06:11:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:11:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:11:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:11:59 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-tktht5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 06:11:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:11:59 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-k0a6TS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:11:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:11:59 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-ml3MoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:11:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:11:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:11:59 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-6rq4Su-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:12:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:12:00 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-8UCenJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:12:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:12:00 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-Ldxskl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:12:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:12:00 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-SypUt0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:12:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:12:00 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-gktLmG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:12:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:12:00 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-1B25sL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:12:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:12:00 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-30gkyx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:12:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:12:00 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-dpkOUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:12:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:12:00 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-jw0UGE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:12:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:12:00 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-LgVzug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:12:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 06:12:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:12:00 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-SjiM2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:12:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:12:01 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-U8yQ9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:12:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 06:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:12:01 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-P61zil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:12:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:12:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:12:01 INFO - Running setup.py (path:/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/pip-Zcdt0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:12:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 06:12:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:12:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:12:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 06:12:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:12:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:12:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:12:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:12:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:12:02 INFO - Downloading blessings-1.6.tar.gz 06:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:12:02 INFO - Installing collected packages: blessings 06:12:02 INFO - Running setup.py install for blessings 06:12:03 INFO - Successfully installed blessings 06:12:03 INFO - Cleaning up... 06:12:03 INFO - Return code: 0 06:12:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:12:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:12:03 INFO - Reading from file tmpfile_stdout 06:12:03 INFO - Current package versions: 06:12:03 INFO - blessings == 1.6 06:12:03 INFO - blobuploader == 1.2.4 06:12:03 INFO - docopt == 0.6.1 06:12:03 INFO - functools32 == 3.2.3-2 06:12:03 INFO - jsonschema == 2.5.1 06:12:03 INFO - manifestparser == 1.1 06:12:03 INFO - mozInstall == 1.12 06:12:03 INFO - mozcrash == 1.0 06:12:03 INFO - mozdebug == 0.1 06:12:03 INFO - mozdevice == 0.49 06:12:03 INFO - mozfile == 1.2 06:12:03 INFO - mozhttpd == 0.7 06:12:03 INFO - mozinfo == 0.9 06:12:03 INFO - mozleak == 0.1 06:12:03 INFO - mozlog == 3.4 06:12:03 INFO - moznetwork == 0.27 06:12:03 INFO - mozprocess == 0.25 06:12:03 INFO - mozprofile == 0.28 06:12:03 INFO - mozrunner == 6.13 06:12:03 INFO - mozscreenshot == 0.1 06:12:03 INFO - mozsystemmonitor == 0.3 06:12:03 INFO - moztest == 0.8 06:12:03 INFO - mozversion == 1.4 06:12:03 INFO - psutil == 3.1.1 06:12:03 INFO - requests == 1.2.3 06:12:03 INFO - wsgiref == 0.1.2 06:12:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:12:03 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 0x104aadb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ab0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec40d9c590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488bd30>, '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 0x1047b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104880be8>, '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.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 06:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 06:12:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cd1ueBuj8Q/Render', 06:12:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:12:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:12:03 INFO - 'HOME': '/Users/cltbld', 06:12:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:12:03 INFO - 'LOGNAME': 'cltbld', 06:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:12:03 INFO - 'MOZ_NO_REMOTE': '1', 06:12:03 INFO - 'NO_EM_RESTART': '1', 06:12:03 INFO - 'PAGER': '/bin/cat', 06:12:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:12:03 INFO - 'PWD': '/builds/slave/test', 06:12:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:12:03 INFO - 'SHELL': '/bin/bash', 06:12:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qFXvqXJyUK/Listeners', 06:12:03 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 06:12:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:12:03 INFO - 'USER': 'cltbld', 06:12:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:12:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:12:03 INFO - 'XPC_FLAGS': '0x0', 06:12:03 INFO - 'XPC_SERVICE_NAME': '0', 06:12:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 06:12:03 INFO - Cleaning up... 06:12:03 INFO - Return code: 0 06:12:03 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:12:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104aadb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ab0768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fec40d9c590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10488bd30>, '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 0x1047b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104880be8>, '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.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 06:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 06:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 06:12:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cd1ueBuj8Q/Render', 06:12:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:12:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:12:03 INFO - 'HOME': '/Users/cltbld', 06:12:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:12:03 INFO - 'LOGNAME': 'cltbld', 06:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:12:03 INFO - 'MOZ_NO_REMOTE': '1', 06:12:03 INFO - 'NO_EM_RESTART': '1', 06:12:03 INFO - 'PAGER': '/bin/cat', 06:12:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:12:03 INFO - 'PWD': '/builds/slave/test', 06:12:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:12:03 INFO - 'SHELL': '/bin/bash', 06:12:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qFXvqXJyUK/Listeners', 06:12:03 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 06:12:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:12:03 INFO - 'USER': 'cltbld', 06:12:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:12:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:12:03 INFO - 'XPC_FLAGS': '0x0', 06:12:03 INFO - 'XPC_SERVICE_NAME': '0', 06:12:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:12:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:12:03 INFO - Cleaning up... 06:12:03 INFO - Return code: 0 06:12:03 INFO - Running post-action listener: _resource_record_post_action 06:12:03 INFO - Running post-action listener: _start_resource_monitoring 06:12:03 INFO - Starting resource monitoring. 06:12:03 INFO - [mozharness: 2017-05-26 13:12:03.974479Z] Finished create-virtualenv step (success) 06:12:03 INFO - [mozharness: 2017-05-26 13:12:03.975409Z] Running install step. 06:12:03 INFO - Running pre-action listener: _resource_record_pre_action 06:12:03 INFO - Running main action method: install 06:12:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:12:04 INFO - Reading from file tmpfile_stdout 06:12:04 INFO - Detecting whether we're running mozinstall >=1.0... 06:12:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:12:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:12:04 INFO - Reading from file tmpfile_stdout 06:12:04 INFO - Output received: 06:12:04 INFO - Usage: mozinstall [options] installer 06:12:04 INFO - Options: 06:12:04 INFO - -h, --help show this help message and exit 06:12:04 INFO - -d DEST, --destination=DEST 06:12:04 INFO - Directory to install application into. [default: 06:12:04 INFO - "/builds/slave/test"] 06:12:04 INFO - --app=APP Application being installed. [default: firefox] 06:12:04 INFO - mkdir: /builds/slave/test/build/application 06:12:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:12:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 06:12:18 INFO - Reading from file tmpfile_stdout 06:12:18 INFO - Output received: 06:12:18 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 06:12:18 INFO - Running post-action listener: _resource_record_post_action 06:12:18 INFO - [mozharness: 2017-05-26 13:12:18.926690Z] Finished install step (success) 06:12:18 INFO - [mozharness: 2017-05-26 13:12:18.926810Z] Running run-tests step. 06:12:18 INFO - Running pre-action listener: _resource_record_pre_action 06:12:18 INFO - Running main action method: run_tests 06:12:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:12:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:12:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:12:18 INFO - 2017-05-26 06:12:18.941 screenresolution[1625:7398] starting screenresolution argv=screenresolution get 06:12:18 INFO - 2017-05-26 06:12:18.943 screenresolution[1625:7398] Display 0: 1600x1200x32@60 06:12:18 INFO - 2017-05-26 06:12:18.949 screenresolution[1626:7400] starting screenresolution argv=screenresolution list 06:12:18 INFO - Available Modes on Display 0 06:12:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:12:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:12:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:12:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:12:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:12:19 INFO - Intel Iris: 06:12:19 INFO - Chipset Model: Intel Iris 06:12:19 INFO - Type: GPU 06:12:19 INFO - Bus: Built-In 06:12:19 INFO - VRAM (Dynamic, Max): 1536 MB 06:12:19 INFO - Vendor: Intel (0x8086) 06:12:19 INFO - Device ID: 0x0a2e 06:12:19 INFO - Revision ID: 0x0009 06:12:19 INFO - Displays: 06:12:19 INFO - Display: 06:12:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:12:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:12:19 INFO - Display Serial Number: 1600x1200 60 06:12:19 INFO - Main Display: Yes 06:12:19 INFO - Mirror: Off 06:12:19 INFO - Online: Yes 06:12:19 INFO - Rotation: Supported 06:12:19 INFO - Return code: 0 06:12:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:12:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:12:19 INFO - Python 2.7.3 06:12:19 INFO - Return code: 0 06:12:19 INFO - grabbing minidump binary from tooltool 06:12:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:12:19 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 0x10488bd30>, '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 0x1047b2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104880be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:12:19 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 06:12:19 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 06:12:19 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 06:12:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:12:19 INFO - Return code: 0 06:12:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:12:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:12:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:12:19 INFO - ENV: RUST_BACKTRACE is now 1 06:12:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:12:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:12:19 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/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0356', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 06:12:19 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/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0356 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 06:12:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cd1ueBuj8Q/Render', 06:12:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:12:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:12:19 INFO - 'HOME': '/Users/cltbld', 06:12:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:12:19 INFO - 'LOGNAME': 'cltbld', 06:12:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:12:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:12:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:12:19 INFO - 'MOZ_NO_REMOTE': '1', 06:12:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:12:19 INFO - 'NO_EM_RESTART': '1', 06:12:19 INFO - 'PAGER': '/bin/cat', 06:12:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:12:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:12:19 INFO - 'PWD': '/builds/slave/test', 06:12:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:12:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:12:19 INFO - 'RUST_BACKTRACE': '1', 06:12:19 INFO - 'SHELL': '/bin/bash', 06:12:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.qFXvqXJyUK/Listeners', 06:12:19 INFO - 'TMPDIR': '/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/', 06:12:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:12:19 INFO - 'USER': 'cltbld', 06:12:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:12:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:12:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:12:19 INFO - 'XPC_FLAGS': '0x0', 06:12:19 INFO - 'XPC_SERVICE_NAME': '0', 06:12:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:12:19 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/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0356', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 06:12:19 INFO - mozversion application_buildid: 20170526051316 06:12:19 INFO - mozversion application_changeset: a6718df8269d161f0e2685b1266f7dcc981a751d 06:12:19 INFO - mozversion application_display_name: Firefox 06:12:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:12:19 INFO - mozversion application_name: Firefox 06:12:19 INFO - mozversion application_remotingname: firefox 06:12:19 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 06:12:19 INFO - mozversion application_vendor: Mozilla 06:12:19 INFO - mozversion application_version: 54.0 06:12:19 INFO - mozversion platform_buildid: 20170526051316 06:12:19 INFO - mozversion platform_changeset: a6718df8269d161f0e2685b1266f7dcc981a751d 06:12:19 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 06:12:19 INFO - mozversion platform_version: 54.0 06:12:19 INFO - using testdate: 1495804339 06:12:19 INFO - actual date: 1495804339 06:12:19 INFO - starting webserver on 'localhost:49224' 06:12:19 INFO - SUITE-START | Running 2 tests 06:12:19 INFO - TEST-START | dromaeo_css 06:12:19 INFO - operating with platform_type : mac_ 06:12:19 INFO - Initialising browser for dromaeo_css test... 06:12:19 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpTwqRL6/profile http://localhost:49224/getInfo.html) 06:12:22 INFO - PROCESS | 1636 | 2017-05-26 06:12:22.631 plugin-container[1639:7572] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4f, name = 'com.apple.tsm.portname' 06:12:22 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:12:22 INFO - PROCESS | 1636 | 2017-05-26 06:12:22.632 plugin-container[1639:7572] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e53, name = 'com.apple.CFPasteboardClient' 06:12:22 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:12:22 INFO - PROCESS | 1636 | 2017-05-26 06:12:22.632 plugin-container[1639:7572] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:12:22 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 06:12:22 INFO - PROCESS | 1636 | colorDepth:24 06:12:22 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 06:12:22 INFO - PROCESS | 1636 | __metrics 06:12:22 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 06:12:23 INFO - TEST-INFO | 1636: exit 0 06:12:23 INFO - Browser initialized. 06:12:23 INFO - Running cycle 1/1 for dromaeo_css test... 06:12:23 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmpTwqRL6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:12:25 INFO - PROCESS | 1640 | 2017-05-26 06:12:25.832 plugin-container[1641:7708] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x884b, name = 'com.apple.tsm.portname' 06:12:25 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:12:25 INFO - PROCESS | 1640 | 2017-05-26 06:12:25.834 plugin-container[1641:7708] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 06:12:25 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:12:25 INFO - PROCESS | 1640 | 2017-05-26 06:12:25.834 plugin-container[1641:7708] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:12:35 INFO - PROCESS | 1640 | RSS: Main: 174268416 06:12:35 INFO - PROCESS | 1640 | 06:15:39 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49224/tests/dromaeo/cssquery-ext.html) 06:15:40 INFO - PROCESS | 1640 | RSS: Main: 192675840 06:15:40 INFO - PROCESS | 1640 | 06:18:43 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-ext.html (next: http://localhost:49224/tests/dromaeo/cssquery-jquery.html) 06:18:43 INFO - PROCESS | 1640 | RSS: Main: 203071488 06:18:43 INFO - PROCESS | 1640 | 06:21:45 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49224/tests/dromaeo/cssquery-mootools.html) 06:21:46 INFO - PROCESS | 1640 | RSS: Main: 205127680 06:21:46 INFO - PROCESS | 1640 | 06:24:48 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49224/tests/dromaeo/cssquery-prototype.html) 06:24:48 INFO - PROCESS | 1640 | RSS: Main: 208244736 06:24:48 INFO - PROCESS | 1640 | 06:27:51 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49224/tests/dromaeo/cssquery-yui.html) 06:27:52 INFO - PROCESS | 1640 | RSS: Main: 210382848 06:27:52 INFO - PROCESS | 1640 | 06:30:55 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49224/tests/dromaeo/cssquery-yui.html (next: http://localhost:49224/tests/dromaeo/cssquery-dojo.html) 06:30:55 INFO - PROCESS | 1640 | RSS: Main: 208150528 06:30:55 INFO - PROCESS | 1640 | 06:30:55 INFO - PROCESS | 1640 | __start_tp_report 06:30:55 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 06:30:55 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 06:30:55 INFO - PROCESS | 1640 | |i|pagename|runs| 06:30:55 INFO - PROCESS | 1640 | |0;dojo.html;2779.596958441026;3471.704905083068;3652.634736526347;3684.3184010957966;3697.9630203697957;5029.874253143668;5955.196048533452;6192.349803270659;6206.503479721623;6251.59364641298;6218.657973811439;6240.313565507802;6260.311365749775;6269.9373006269925;6293.811185664437;5596.552275817935;5649.983003059448;5682.062459694166;5699.287589051369;5751.539876809866;5691.646228241765;5721.370649228582;5728.198052272685;5750.942490575104;5762.078067509199;5463.9180412293845;5491.0116179087745;5579.191017302489;5721.914171287434;5747.827565173052;5604.691741954191;5617;5628.409017053233;5655.830325090254;5658.830235092953;5517.44825517449;5642.379338272786;5658.321001479808;5663.688497132656;5722.856928576777;4987.351644286219;5106.463821298785;6070.514358851281;6266.185395898549;6311.179546658951;6532.673366331712;6561.048647946069;6569.178852643419;6684.298148694365;6729.6971636276485;6789.796306110825;6807.829804254884;6814.046033555257;6820.113385259934;6829.282925292872;6253.374662533801;6302.338254483305;6321.1782468278825;6346.30190679025;6350.174477317919;6577.408033277028;6711.228208755958;6794.286599907037;6798.354156355237;6845.041694162821;6603.570767900071;6603.966980165164;6604.174478190226;6605.174353205849;6625.039369291522;6656.134702488743;6728.192616885907;6748.460123190133;6771.28901465339;6776.83057923546;6425.453836423956;6489.1888513935755;6514.3811337922825;6549.9345006550275;6664.366885145903;5577.9163312550345;6341.968290158519;6652.201735791736;6716.093327400738;6731.158605174353;5204.505571970657;5252.343457067866;5304.832936753908;5318.25544423781;5345.518903298722;4726.1492931272705;4778.976105119452;4790.880227994258;4805.687630304019;4848.200046992207;6125.296497837281;6175.96912015437;6318.210223722035;6324.873502530016;6325.9051114233325;6593.109930159367;6656.467482601381;6716.597004179765;6717.596944183365;6736.696848641823;6336.493080553545;6340.619562826245;6350.33321501245;6358.205224346957;6428.164338636041;5681.488666020078;5932.940670593325;5944.494717948936;5977.820665380045;5996.760129594854;6763.560368576125;6872.072270243453;6885.414739746977;6929.237783843874;6955.756548520778;6720.865582688417;6744.831379215559;6785.796426107225;6795.626240556619;6812.352826481477;5414.729263536886;6998.860022799414;7021.368076873106;7031.507794454339;7047.224805271314;6340;6396.200474940632;6562.310957349409;6590.176227971504;6622.403983641495;6768;6770.119884414995;6772.25505194438;6773.254941956479;6786.830329241809;5371.865703357291;5483.150111732688;6477.8704425912165;6485.962246040611;6586;6253.843653908507;6300.338464461349;6324.209473815773;6449.838754031187;6463.838404039937;6354.745810167541;6388.425041746265;6454.806355809334;6457.515686323639;6466.256380516113;6268.1851359323;6539.378759017885;6540.084388185753;6550.967245163743;6567.507436942344;6218.844528886814;6267.561270711189;6322.146510221061;6425.903611445926;6438.484921206198 06:30:55 INFO - PROCESS | 1640 | |1;ext.html;753.4876284126793;776.5224387001992;843.4433274039134;873.8028900406445;973.922086233101;33025.33949321015;40016.79991600042;41254.58745412545;41338.793306033425;41396.58603413965;39450.40824387629;39715;39718;39740.006499837444;39746;8279.917200828006;8425.031121421023;11153.386563738992;11375.658730238107;11561.595344162955;11293.887061129366;11354.48904799286;11369.829452558219;11375.829362559569;11379.089672826154;11369.829452558219;11409.37248451335;11414.42927853604;11419.48612312448;11420.942895285554;9665.758356041086;11286.830697539543;11322.773544529146;11327.660170194866;11332.490037948312;11072.335659860353;11125.998660120627;11153.72115697106;11160.386178760165;11184;35075.123121921904;35365.64634353675;35417.64582354195;35527.82236088803;35628.82185589081;3887.32142057414;3895.0651843557625;3926;4007.218592374459;4159.168166366739;20721;25980.090696825708;26181.869090654614;26377.604335934975;26400.735992640213;3137.9372412551847;3230.3054843208824;3301.042697617688;3370.0900756795527;3393.474011528217;307449;310714;354852;363551;466428;44888.3266750999;45011.54988450139;45077.77461112739;45082.549174508495;45265.54734452679;46445;46732;46780.06439871184;46829.76585117121;46873.82815429642;14661.85338146605;14727.705445891022;14751.77872331916;14752.409903603735;14769;12475.563355282522;12526.436310366056;12706.491740330468;12729.872701272869;12735;12044.518219271305;12049.457774400173;12096.879031209575;12104.910558049818;12137.939310303393;5828.59199856006;5833.679147646835;5845.853853653693;5866.970665146647;5902.114682797529;1059.359087751899;1064.4890452582804;1088.689723428819;1097.4512743628186;1139.4701463819363;16496.42262520806;16776;16866.746998795028;17011.91494042522;17047.40334088326;985.0445068283753;1159.756451145252;1175.7589694112687;1179.2865316483403;1221.3648902570792;8419.410641255176;8512.233898949124;8610.397272190887;8710.47737135774;8833;411720;415974;416155.91922040196;417409;417654;46185.76907115443;52096;56131.71934140303;56229.43770562242;56852;1431.8926080544002;1492.3359105198083;1503.225838693074;1505.6160679026777;1518.567208345616;15327.693446131238;15580.68838623244;16456.67086658236;16773.496795096165;16950.745738814174;45734.313985289584;45908;46003.53996460127;46008.30987535256;46018.76990615026;365480.1725991353;365624;365659.1717041398;365956.1702191578;366547;27086.05198818032;30747.9238226661;31026.069217923497;32106.83946580252;32335.353292933538;32972.50541241931;39838.80483585497;39914.403423863885;40491.59508404996;41653.958651033965;22968.851557422397;28872.855635721688;28955.42089158163;29072.41855162927;30074.849625751733;23284.883575582015;29076;29210;29657.555136672534;29868;26676.7332326682;34236.82881585576;34479.827600861834;34505.82747086249;34513.48229776606;27838.58242126329;28299.434011319245;29778.10665680033;29834.850825745732;30034.84982575073;23339.649905251095;23445.648315274942;23606;23743.643845341987;24068.51862962766 06:30:55 INFO - PROCESS | 1640 | |2;jquery.html;368.70872011111226;385.42763995466726;420.5941266677655;420.69499612780754;436.299738919035;20757.377278681644;21469.4632634184;21784.673229901564;21942.012609432575;22169.55660886783;15368.923155384235;20852.687209691867;21528.461788455297;22174.778252217475;22653.20713775018;6706.094677218574;7030;7051.647417629124;7059.611721355323;7071.504994650377;6280.152179455784;6920.584764914096;6935.930640693579;6943.930560694404;6966.651667416634;6583.736650533973;6923.549969252008;6927.722891084376;6930.237673855871;6936.0636314097455;6834.316568343175;6985.9999999999745;6988.61562614056;6996.790096297119;6997.82505437363;6445.709943052527;6729.663516824188;6768.864622707568;6775.085363475966;6833.111695479605;27603.750997605704;27690.892364305404;27842.747076381613;27989.580156297467;28279.434411311864;3010.5333673280675;3032.3328867649084;3053.603031605877;3104.580881581003;3131.62420509538;16740.497785066345;17571.12144392788;17769.11154442273;17837.910810445996;17912.91043544774;2155.1164022750754;2179.9128034878745;2183.825293976478;2187.0923566719794;2215.2578886073184;132312.33843830816;134354;135367.64632353748;138184;138988;21944.902754862193;22717.318480445614;23167.768322316897;23330;23350.7664923352;38200.8089959554;38333.233335333134;38661.03347416337;39147.80426097908;39190.80404597959;10490.632827850988;11070.944645276722;11138.665840024812;11159.442027898735;11310.264832785842;9238.214751746042;9333.346665733468;9345.859812102824;9522.381045232038;9529.809403811885;9759;9911.315076437024;10009.199264059003;10037.598496060222;10101;4320.395144679747;4417.8232870684815;4423.8230470781455;4429.7785110744335;4438.60052595268;872.9519876406858;898.1961144775389;921.8985911549725;945.9479728614855;962.4802606592501;21293.78706212918;22503.212387566356;22508;22534;22643.660345094835;948.2414068744977;950.2255661635745;971.7230589282162;973.8490533967247;976.345848281653;7122.643867806589;11231.21381503287;11655.53377864876;11662.94168529152;11674.76650467003;741336.4665883397;798691;799621;802743;813439;122755;138790.30604846912;154124;155160;157445;1310.5020092364841;1313.0414797197907;1333.48660765604;1338.3910320804011;1345.2466618693368;18703.71944420862;19689.310874119335;19709.605807883476;19760.703589446457;19795.505112372306;63646;64355;64548.677256615294;65688.67155664192;66353;89324.6601300968;90088.5495572518;91258.63112053192;92187.61718574361;92725.21937276499;11630.767384652429;11703.941480292544;11981.76036479283;12092.576759813715;12165.148439609497;13226.8757155639;13275;13283.86716132865;13293.734125317245;13542.729145417234;9574.425534467675;11234.438278086227;11353.432328383382;11452.885471145182;11525.538978440769;10491.580336786443;10524.421156836448;10756.946215268874;10808.945955270488;10926.562937482411;11757.764844702888;11919.642410727693;12015.399230038289;12086.335251560897;12143.757124857277;11773;11819.940900295443;11877.168598198043;12090.335031573348;12164.817527736914;8804.207621314112;10429.634962776267;11472.94263528677;11506.884931150582;11660.358680272666 06:30:55 INFO - PROCESS | 1640 | |3;mootools.html;1374.924379159146;1614.5237155039263;1620.2222932992163;1635.1252080137122;1643.9095849728265;9691.515424228777;9983.700488985342;10898.891011089896;11621.825672614907;11640.650780476579;10868.945655271713;11205.159613029015;11237.83143252852;11253.493592788323;11302;2503.123906632673;2530.177692250017;2536.797056235506;2540.720520742717;2551.578989466745;2490.3898544856534;2505.4237525369085;2525.1288305534563;2525.229804909502;2528.9494210115786;2218.7226596675414;2481.280428675682;2487.266256454341;2492.8628925409093;2493.3143385568933;2215.966760498594;2265.558216147852;2380.9523809523885;2460.6432067350224;2464.383904023994;2128.4891626009803;2303.2744685424036;2317.9420514487106;2324.4886125052462;2329.33613920032;9774.462404567746;9874.55564499592;9917.603295868157;9919.900800992042;9920.751981200456;6050.788222412194;6134.478569321613;6200.627962322275;6211.937880621181;6223.813285601439;3732.122951106488;3825.7321987460887;3848.2303539291975;3883.4951456310782;3890.9416358754643;1243.1919252485957;1248.8813562711528;1277.910546261762;1301.44688507384;1303.5176984515695;15797.131157786202;15927.362905483767;15941.202939852961;15950.521484355488;16019.038857668576;4308.3968244445805;4937.1606826839525;5177.637565370464;5193.610479214074;5221.869453263699;11549.133814963743;13207.141535800141;13243.735125297633;13267.004974626941;13315.334233288297;5956.940430595725;6094.055421409688;6165.167702360213;6170.81487555374;6176.073588961602;4670.602998745145;5190.584753219733;5253.50091741284;5506.476884696028;5551.361593416728;3585.713142948558;3642.8889188797143;4173.603507666767;4285.121550082225;4293.82824687009;1122.3714719756963;1189.4885199364182;1190.1966172833304;1190.4999900042003;1204.7771162334996;929.9398685498537;931.725141083382;933.1415098109755;937.2923442800642;989.7129832348538;6466.321036291216;6547.500099916093;6648.700808463534;6903.99892015665;6922.619255940972;836.9790755231046;968.9467079310706;995.5022488755623;1008.436922769704;1033.03927347566;3413.35146322198;4102.118044620421;4102.3026085565525;4133;4210.263203939298;13599.728005439836;14041.368138433794;14101;14106.71786564263;14260.786088208684;13820.585382438543;13891.652708682364;14065.648358791112;14221.786673199911;14243.359048842827;10320.690379288633;10465.42440165783;10474.52864621109;10478.37129772216;10496.422696751752;6424.518161138027;6533.542652014314;6583.0454584086;6689.063531105552;6764.830879227862;12350.320732359807;12404.503819847105;12408.379581020732;12449.066320025853;12540.811887821872;11280.2667826591;12640.810387844374;12816.871831281567;12923.15999460032;13109.475620975054;8317.835503029703;9136.588853501475;9153.77115572116;9262.861057084014;9425.858612120686;10533.315334503233;10545.156387488829;10627.256092073481;10643.94678026605;10711.517981690688;9088;9127.08729127082;9151.993280739247;9261.490618016074;9283.860742088738;7286.744963926237;8922.86615700778;8995.41529800587;8997.865032024394;9014.594343254701;7997.560134192442;8050.879236811335;8112.351011918914;8167.3466122711225;8168.754937351889;5828.038373668297;5871.354151043467;5914.408559144051;6050.546209034428;6110.297315808739;5922.585419020627;6019.097135429721;6047.425494578008;6161.599496032923;6207.503399727921 06:30:55 INFO - PROCESS | 1640 | |4;prototype.html;341.5713279833808;378.0360081791431;396.87101691950124;397.4912112496005;402.579304626665;5192.376914770228;5217.191335343018;5318.787248510064;5500.147477141039;5525.0883604205255;4384.0135969406865;4664.833791552112;4844.2457095750815;5275.551578115856;5359.222912677659;3703.8703645372484;4009.5565596385277;4406.339049142623;4424.225760491901;4469.994251293466;3614.8373323200517;4253.787310634471;4327.221100201962;4440.42274504314;4454.688171827974;3860.7607587599127;4352.804123814436;4367.956320436802;4373.956260437387;4427.358033085216;3882.515190278224;3925;4123.731957422788;4393.538678438766;4440.467143942731;3748.269087527933;3964.8414063437426;4084.6936479764136;4101.91796164075;4125.91748165038;5080.364954380702;5138.332016837825;5196.324477817897;5239.834331706303;5242.68543887368;4897.2898929655;5135.79308862417;5165.819196328111;5189.558887494568;5205.973970130163;4618.884527886796;5170.431252562215;5635.21107045014;5685.232493613351;5734.741936612821;3769.321522126016;4585.72485650862;4585.770711464447;4602.964333025043;4609.216433206363;5267.183586544092;5364.463553644604;5845.181674565607;6035.818925432244;6104.4200800923845;3924.60753924611;4462.906587885924;4640.559146881041;4695.577398034194;4745.762711864393;4530.433695788027;4718.95281047192;4771.260454629538;4775.6657034007985;4823.879403014883;4263.531011588722;4987.875303117451;5073.8731531712;5089.51649593288;5138.486151384832;4646.83736069236;5014.623903207274;5626.465485778926;5666.518345940559;5692.686902220335;1360.5034162530803;1454.2001898955532;1626.983730162707;1629.6229685915382;1634.0849124490323;1032.8347464405658;1106.9373401534456;1206.9215500992407;1223.7980733178927;1244.8858271846827;4466.508684044752;5265.314280003936;5301.3373328333955;5306.6550674206055;5327.7910450743475;5711.572581652781;5860.589758716934;5860.619059761102;5915.83502825986;5920.4773159969745;4430.867073987785;4459.308807134899;4855.417349918032;5282.7622756976025;5321.057813897977;2493.862837543916;2547.0193975319635;2819.126070918022;3012.9246768830953;3067.0798760372245;5650.039493286151;5791.420857914259;5825.854353641193;6095.6037857540005;6168.599041062316;5602.4033150551895;5648.4633959773755;5867.735951882176;5991.472174595538;5996.2504686914135;5528.585356098309;5961.4038596140035;6067.206906543869;6071.150038994632;6102.511799056154;4719.38647975761;4761.952380476151;4950.282209079735;5223.686578805284;5499.84503254321;5148.43367229597;5329.893402131858;5391.62258641907;5719.48524632785;5744.391570360306;4878.438979517259;5542.337298810421;6121.979746683494;6143.61768602061;6181.84545386369;4137.358709400048;4644.140833945731;4654.976725116353;4681.010570507588;4697.976510117564;4823.938733478629;5534.122256426716;5857.970710146422;5876.529877609695;5895.823125306248;5212.609054321017;5273.34083239595;5673.432656734459;5725.854829034293;5728.484436400744;4841.443234027991;4883.487233840396;5133.512316329943;5209.95800839826;5552.278203833475;4679.695819771704;4774;5154.989554073839;5214.9570085982195;5596.496315331639;5042.512458824729;5096.617753668415;5401.378841433286;5720.882119245105;5762;5024.924626130684;5115.979188220706;5424.647397919122;5602.635828671123;5626.35296940857 06:30:55 INFO - PROCESS | 1640 | |5;yui.html;677.4140368581179;779.2285637219147;803.4456225204608;900.5362238447199;910.9134632209938;16407.589810254736;17397.91301043496;17448.214830332632;17471.475855724333;17498.65002699947;13452.932735336333;13469.393877275521;13534.864651353484;13550.457981680745;15399.999000065016;973.9220862330997;991.6380521109797;1012.817692815293;1036.6112707734599;1036.7304500829764;1018.6485662446444;1019.9031092046281;1021.8313978193589;1023.9744006399825;1025.5538840604324;1070.4380200394778;1078.4607696151925;1080.9081228095622;1082.5994382078643;1083.49617427896;952.766572189811;1076.7361996310876;1077.1113831089344;1080.076534562949;1080.6271836216572;795.8408318336297;796.195842199377;796.9880451793227;797.11520212564;798.2336956521746;16367.345306187739;16910.408135715315;17269.740953885703;17295.22171502273;17663.11684415774;14819.333130009176;14835.406583736638;14846.554603361916;14849.70300593982;15005.624859378493;12053.096017798613;12108.576199832964;12125.211861228992;12194.695132621666;12497.93751031239;3684.5762737285286;3722.143906901401;3745.0637340664834;3791.2607041626893;3842.9231415371814;87932;118452;119174.21238681427;120090;120441.79558204394;11508.482118304697;11626.476808543635;11694.941525292319;11739.530418783324;11761.411929403495;24170.274891753277;24524.75475245225;24546.386340341276;24600.876995614908;24608;8126.39052071097;8361.581920903931;8405.663773449116;8484.533350665773;8609.655613775503;4608.377868987711;4613.884652883705;4690.64820138491;4700.412448443944;4819.108464933998;3432.828358582061;3496.423090190141;3518.964810351915;3527.894163175109;3537.4340105582983;664.5647101148755;673.4982438083659;673.619405036153;681.9965900170467;683;692.9826754331182;694.6040756768695;700.432649553863;725.6661935509619;761.5050217358651;10220.795584088426;10256.12822910046;10551.947240263904;10659.253852230424;10766.676999690022;678.5962352400314;698.9580625162506;705.8200158959432;728.49005696013;731.7256028989129;5146.794128234904;5211.960013747136;5222.164453687392;5323.787048518016;5329.307190065267;105414.47292763487;106313;107217.46391267993;109487;109780;75642;76045;77065.61467192628;80268;86299;72574.18564535823;77355.83966240511;77490.61254693803;77698.05754856174;79247.20752792398;9710.368826026286;10834.620788272372;11408.201425900108;11513.366764828015;11650.009749171077;46383.3042504356;49894;50682.74658626683;51563.74218128886;51707.741461292455;64181;64836.379090523114;69531.60936781338;71229.6438517804;72992.6350368266;13410.865891341353;13510.324483775968;13628;13909.443622254996;14018.719625607635;15157.090574565562;15243.314050867959;15367;15373.923130384277;15646.608834779221;14046.508372206923;14403.351849166604;14505.637359066108;14536.418543258575;14764.557063288119;13989.510367137102;14557.92721036388;14612.926935365254;14728.55814325572;15189.544313670607;13306.800397994231;13340.332983350987;14193;14209.703285887537;14256.786148207577;9999.900001000187;10419.322744021614;10564.313319634199;10602.20483463728;10968.396738179477;8898.86651700238;9475.857862131936;11533.423328833356;11781.234219775688;11806.881931180578 06:30:55 INFO - PROCESS | 1640 | __end_tp_report 06:30:55 INFO - PROCESS | 1640 | __start_cc_report 06:30:55 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,398 06:30:55 INFO - PROCESS | 1640 | __end_cc_report 06:30:55 INFO - PROCESS | 1640 | __startTimestamp1495805455468__endTimestamp 06:30:55 INFO - PROCESS | 1640 | 06:30:55 INFO - PROCESS | 1640 | ------- Summary: start ------- 06:30:55 INFO - PROCESS | 1640 | Number of tests: 6 06:30:55 INFO - PROCESS | 1640 | 06:30:55 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:6152.78 Median:6341.97 stddev:724.13 (11.4%) stddev-sans-first:676.27 06:30:55 INFO - PROCESS | 1640 | Values: 2779.6 3471.7 3652.6 3684.3 3698.0 5029.9 5955.2 6192.3 6206.5 6251.6 6218.7 6240.3 6260.3 6269.9 6293.8 5596.6 5650.0 5682.1 5699.3 5751.5 5691.6 5721.4 5728.2 5750.9 5762.1 5463.9 5491.0 5579.2 5721.9 5747.8 5604.7 5617.0 5628.4 5655.8 5658.8 5517.4 5642.4 5658.3 5663.7 5722.9 4987.4 5106.5 6070.5 6266.2 6311.2 6532.7 6561.0 6569.2 6684.3 6729.7 6789.8 6807.8 6814.0 6820.1 6829.3 6253.4 6302.3 6321.2 6346.3 6350.2 6577.4 6711.2 6794.3 6798.4 6845.0 6603.6 6604.0 6604.2 6605.2 6625.0 6656.1 6728.2 6748.5 6771.3 6776.8 6425.5 6489.2 6514.4 6549.9 6664.4 5577.9 6342.0 6652.2 6716.1 6731.2 5204.5 5252.3 5304.8 5318.3 5345.5 4726.1 4779.0 4790.9 4805.7 4848.2 6125.3 6176.0 6318.2 6324.9 6325.9 6593.1 6656.5 6716.6 6717.6 6736.7 6336.5 6340.6 6350.3 6358.2 6428.2 5681.5 5932.9 5944.5 5977.8 5996.8 6763.6 6872.1 6885.4 6929.2 6955.8 6720.9 6744.8 6785.8 6795.6 6812.4 5414.7 6998.9 7021.4 7031.5 7047.2 6340.0 6396.2 6562.3 6590.2 6622.4 6768.0 6770.1 6772.3 6773.3 6786.8 5371.9 5483.2 6477.9 6486.0 6586.0 6253.8 6300.3 6324.2 6449.8 6463.8 6354.7 6388.4 6454.8 6457.5 6466.3 6268.2 6539.4 6540.1 6551.0 6567.5 6218.8 6267.6 6322.1 6425.9 6438.5 06:30:55 INFO - PROCESS | 1640 | 06:30:55 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:51068.49 Median:170870.03 stddev:101497.05 (59.4%) stddev-sans-first:101711.47 06:30:55 INFO - PROCESS | 1640 | Values: 753.5 776.5 843.4 873.8 973.9 33025.3 40016.8 41254.6 41338.8 41396.6 39450.4 39715.0 39718.0 39740.0 39746.0 8279.9 8425.0 11153.4 11375.7 11561.6 11293.9 11354.5 11369.8 11375.8 11379.1 11369.8 11409.4 11414.4 11419.5 11420.9 9665.8 11286.8 11322.8 11327.7 11332.5 11072.3 11126.0 11153.7 11160.4 11184.0 35075.1 35365.6 35417.6 35527.8 35628.8 3887.3 3895.1 3926.0 4007.2 4159.2 20721.0 25980.1 26181.9 26377.6 26400.7 3137.9 3230.3 3301.0 3370.1 3393.5 307449.0 310714.0 354852.0 363551.0 466428.0 44888.3 45011.5 45077.8 45082.5 45265.5 46445.0 46732.0 46780.1 46829.8 46873.8 14661.9 14727.7 14751.8 14752.4 14769.0 12475.6 12526.4 12706.5 12729.9 12735.0 12044.5 12049.5 12096.9 12104.9 12137.9 5828.6 5833.7 5845.9 5867.0 5902.1 1059.4 1064.5 1088.7 1097.5 1139.5 16496.4 16776.0 16866.7 17011.9 17047.4 985.0 1159.8 1175.8 1179.3 1221.4 8419.4 8512.2 8610.4 8710.5 8833.0 411720.0 415974.0 416155.9 417409.0 417654.0 46185.8 52096.0 56131.7 56229.4 56852.0 1431.9 1492.3 1503.2 1505.6 1518.6 15327.7 15580.7 16456.7 16773.5 16950.7 45734.3 45908.0 46003.5 46008.3 46018.8 365480.2 365624.0 365659.2 365956.2 366547.0 27086.1 30747.9 31026.1 32106.8 32335.4 32972.5 39838.8 39914.4 40491.6 41654.0 22968.9 28872.9 28955.4 29072.4 30074.8 23284.9 29076.0 29210.0 29657.6 29868.0 26676.7 34236.8 34479.8 34505.8 34513.5 27838.6 28299.4 29778.1 29834.9 30034.8 23339.6 23445.6 23606.0 23743.6 24068.5 06:30:55 INFO - PROCESS | 1640 | 06:30:55 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:44168.26 Median:22685.26 stddev:131096.95 (577.9%) stddev-sans-first:131423.46 06:30:55 INFO - PROCESS | 1640 | Values: 368.7 385.4 420.6 420.7 436.3 20757.4 21469.5 21784.7 21942.0 22169.6 15368.9 20852.7 21528.5 22174.8 22653.2 6706.1 7030.0 7051.6 7059.6 7071.5 6280.2 6920.6 6935.9 6943.9 6966.7 6583.7 6923.5 6927.7 6930.2 6936.1 6834.3 6986.0 6988.6 6996.8 6997.8 6445.7 6729.7 6768.9 6775.1 6833.1 27603.8 27690.9 27842.7 27989.6 28279.4 3010.5 3032.3 3053.6 3104.6 3131.6 16740.5 17571.1 17769.1 17837.9 17912.9 2155.1 2179.9 2183.8 2187.1 2215.3 132312.3 134354.0 135367.6 138184.0 138988.0 21944.9 22717.3 23167.8 23330.0 23350.8 38200.8 38333.2 38661.0 39147.8 39190.8 10490.6 11070.9 11138.7 11159.4 11310.3 9238.2 9333.3 9345.9 9522.4 9529.8 9759.0 9911.3 10009.2 10037.6 10101.0 4320.4 4417.8 4423.8 4429.8 4438.6 873.0 898.2 921.9 945.9 962.5 21293.8 22503.2 22508.0 22534.0 22643.7 948.2 950.2 971.7 973.8 976.3 7122.6 11231.2 11655.5 11662.9 11674.8 741336.5 798691.0 799621.0 802743.0 813439.0 122755.0 138790.3 154124.0 155160.0 157445.0 1310.5 1313.0 1333.5 1338.4 1345.2 18703.7 19689.3 19709.6 19760.7 19795.5 63646.0 64355.0 64548.7 65688.7 66353.0 89324.7 90088.5 91258.6 92187.6 92725.2 11630.8 11703.9 11981.8 12092.6 12165.1 13226.9 13275.0 13283.9 13293.7 13542.7 9574.4 11234.4 11353.4 11452.9 11525.5 10491.6 10524.4 10756.9 10808.9 10926.6 11757.8 11919.6 12015.4 12086.3 12143.8 11773.0 11819.9 11877.2 12090.3 12164.8 8804.2 10429.6 11472.9 11506.9 11660.4 06:30:55 INFO - PROCESS | 1640 | 06:30:55 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:6873.83 Median:3499.53 stddev:4316.09 (123.3%) stddev-sans-first:4308.42 06:30:55 INFO - PROCESS | 1640 | Values: 1374.9 1614.5 1620.2 1635.1 1643.9 9691.5 9983.7 10898.9 11621.8 11640.7 10868.9 11205.2 11237.8 11253.5 11302.0 2503.1 2530.2 2536.8 2540.7 2551.6 2490.4 2505.4 2525.1 2525.2 2528.9 2218.7 2481.3 2487.3 2492.9 2493.3 2216.0 2265.6 2381.0 2460.6 2464.4 2128.5 2303.3 2317.9 2324.5 2329.3 9774.5 9874.6 9917.6 9919.9 9920.8 6050.8 6134.5 6200.6 6211.9 6223.8 3732.1 3825.7 3848.2 3883.5 3890.9 1243.2 1248.9 1277.9 1301.4 1303.5 15797.1 15927.4 15941.2 15950.5 16019.0 4308.4 4937.2 5177.6 5193.6 5221.9 11549.1 13207.1 13243.7 13267.0 13315.3 5956.9 6094.1 6165.2 6170.8 6176.1 4670.6 5190.6 5253.5 5506.5 5551.4 3585.7 3642.9 4173.6 4285.1 4293.8 1122.4 1189.5 1190.2 1190.5 1204.8 929.9 931.7 933.1 937.3 989.7 6466.3 6547.5 6648.7 6904.0 6922.6 837.0 968.9 995.5 1008.4 1033.0 3413.4 4102.1 4102.3 4133.0 4210.3 13599.7 14041.4 14101.0 14106.7 14260.8 13820.6 13891.7 14065.6 14221.8 14243.4 10320.7 10465.4 10474.5 10478.4 10496.4 6424.5 6533.5 6583.0 6689.1 6764.8 12350.3 12404.5 12408.4 12449.1 12540.8 11280.3 12640.8 12816.9 12923.2 13109.5 8317.8 9136.6 9153.8 9262.9 9425.9 10533.3 10545.2 10627.3 10643.9 10711.5 9088.0 9127.1 9152.0 9261.5 9283.9 7286.7 8922.9 8995.4 8997.9 9014.6 7997.6 8050.9 8112.4 8167.3 8168.8 5828.0 5871.4 5914.4 6050.5 6110.3 5922.6 6019.1 6047.4 6161.6 6207.5 06:30:55 INFO - PROCESS | 1640 | 06:30:55 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4686.79 Median:5084.94 stddev:1318.84 (25.9%) stddev-sans-first:1281.58 06:30:55 INFO - PROCESS | 1640 | Values: 341.6 378.0 396.9 397.5 402.6 5192.4 5217.2 5318.8 5500.1 5525.1 4384.0 4664.8 4844.2 5275.6 5359.2 3703.9 4009.6 4406.3 4424.2 4470.0 3614.8 4253.8 4327.2 4440.4 4454.7 3860.8 4352.8 4368.0 4374.0 4427.4 3882.5 3925.0 4123.7 4393.5 4440.5 3748.3 3964.8 4084.7 4101.9 4125.9 5080.4 5138.3 5196.3 5239.8 5242.7 4897.3 5135.8 5165.8 5189.6 5206.0 4618.9 5170.4 5635.2 5685.2 5734.7 3769.3 4585.7 4585.8 4603.0 4609.2 5267.2 5364.5 5845.2 6035.8 6104.4 3924.6 4462.9 4640.6 4695.6 4745.8 4530.4 4719.0 4771.3 4775.7 4823.9 4263.5 4987.9 5073.9 5089.5 5138.5 4646.8 5014.6 5626.5 5666.5 5692.7 1360.5 1454.2 1627.0 1629.6 1634.1 1032.8 1106.9 1206.9 1223.8 1244.9 4466.5 5265.3 5301.3 5306.7 5327.8 5711.6 5860.6 5860.6 5915.8 5920.5 4430.9 4459.3 4855.4 5282.8 5321.1 2493.9 2547.0 2819.1 3012.9 3067.1 5650.0 5791.4 5825.9 6095.6 6168.6 5602.4 5648.5 5867.7 5991.5 5996.3 5528.6 5961.4 6067.2 6071.2 6102.5 4719.4 4762.0 4950.3 5223.7 5499.8 5148.4 5329.9 5391.6 5719.5 5744.4 4878.4 5542.3 6122.0 6143.6 6181.8 4137.4 4644.1 4655.0 4681.0 4698.0 4823.9 5534.1 5858.0 5876.5 5895.8 5212.6 5273.3 5673.4 5725.9 5728.5 4841.4 4883.5 5133.5 5210.0 5552.3 4679.7 4774.0 5155.0 5215.0 5596.5 5042.5 5096.6 5401.4 5720.9 5762.0 5024.9 5116.0 5424.6 5602.6 5626.4 06:30:55 INFO - PROCESS | 1640 | 06:30:55 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:20972.80 Median:17282.48 stddev:29745.36 (172.1%) stddev-sans-first:29789.76 06:30:55 INFO - PROCESS | 1640 | Values: 677.4 779.2 803.4 900.5 910.9 16407.6 17397.9 17448.2 17471.5 17498.7 13452.9 13469.4 13534.9 13550.5 15400.0 973.9 991.6 1012.8 1036.6 1036.7 1018.6 1019.9 1021.8 1024.0 1025.6 1070.4 1078.5 1080.9 1082.6 1083.5 952.8 1076.7 1077.1 1080.1 1080.6 795.8 796.2 797.0 797.1 798.2 16367.3 16910.4 17269.7 17295.2 17663.1 14819.3 14835.4 14846.6 14849.7 15005.6 12053.1 12108.6 12125.2 12194.7 12497.9 3684.6 3722.1 3745.1 3791.3 3842.9 87932.0 118452.0 119174.2 120090.0 120441.8 11508.5 11626.5 11694.9 11739.5 11761.4 24170.3 24524.8 24546.4 24600.9 24608.0 8126.4 8361.6 8405.7 8484.5 8609.7 4608.4 4613.9 4690.6 4700.4 4819.1 3432.8 3496.4 3519.0 3527.9 3537.4 664.6 673.5 673.6 682.0 683.0 693.0 694.6 700.4 725.7 761.5 10220.8 10256.1 10551.9 10659.3 10766.7 678.6 699.0 705.8 728.5 731.7 5146.8 5212.0 5222.2 5323.8 5329.3 105414.5 106313.0 107217.5 109487.0 109780.0 75642.0 76045.0 77065.6 80268.0 86299.0 72574.2 77355.8 77490.6 77698.1 79247.2 9710.4 10834.6 11408.2 11513.4 11650.0 46383.3 49894.0 50682.7 51563.7 51707.7 64181.0 64836.4 69531.6 71229.6 72992.6 13410.9 13510.3 13628.0 13909.4 14018.7 15157.1 15243.3 15367.0 15373.9 15646.6 14046.5 14403.4 14505.6 14536.4 14764.6 13989.5 14557.9 14612.9 14728.6 15189.5 13306.8 13340.3 14193.0 14209.7 14256.8 9999.9 10419.3 10564.3 10602.2 10968.4 8898.9 9475.9 11533.4 11781.2 11806.9 06:30:55 INFO - PROCESS | 1640 | -------- Summary: end -------- 06:30:55 INFO - PROCESS | 1640 | 06:30:55 INFO - TEST-INFO | 1640: exit 0 06:30:56 INFO - TEST-OK | dromaeo_css | took 1116477ms 06:30:56 INFO - TEST-START | kraken 06:30:56 INFO - operating with platform_type : mac_ 06:30:56 INFO - Initialising browser for kraken test... 06:30:56 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmp8kpD2j/profile http://localhost:49224/getInfo.html) 06:30:58 INFO - PROCESS | 1653 | 2017-05-26 06:30:58.589 plugin-container[1655:10152] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8537, name = 'com.apple.tsm.portname' 06:30:58 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:30:58 INFO - PROCESS | 1653 | 2017-05-26 06:30:58.589 plugin-container[1655:10152] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4507, name = 'com.apple.CFPasteboardClient' 06:30:58 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:30:58 INFO - PROCESS | 1653 | 2017-05-26 06:30:58.590 plugin-container[1655:10152] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:30:58 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 06:30:58 INFO - PROCESS | 1653 | colorDepth:24 06:30:58 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 06:30:58 INFO - PROCESS | 1653 | __metrics 06:30:58 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 06:30:59 INFO - TEST-INFO | 1653: exit 0 06:30:59 INFO - Browser initialized. 06:30:59 INFO - Running cycle 1/1 for kraken test... 06:30:59 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/T/tmp8kpD2j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:31:01 INFO - PROCESS | 1656 | 2017-05-26 06:31:01.422 plugin-container[1657:10278] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 06:31:01 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:31:01 INFO - PROCESS | 1656 | 2017-05-26 06:31:01.423 plugin-container[1657:10278] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8627, name = 'com.apple.CFPasteboardClient' 06:31:01 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 06:31:01 INFO - PROCESS | 1656 | 2017-05-26 06:31:01.423 plugin-container[1657:10278] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 06:31:11 INFO - PROCESS | 1656 | RSS: Main: 174329856 06:31:11 INFO - PROCESS | 1656 | 06:31:13 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection) 06:31:14 INFO - PROCESS | 1656 | RSS: Main: 187858944 06:31:14 INFO - PROCESS | 1656 | 06:31:17 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-dft) 06:31:18 INFO - PROCESS | 1656 | RSS: Main: 195813376 06:31:18 INFO - PROCESS | 1656 | 06:31:21 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-fft) 06:31:22 INFO - PROCESS | 1656 | RSS: Main: 199540736 06:31:22 INFO - PROCESS | 1656 | 06:31:24 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator) 06:31:24 INFO - PROCESS | 1656 | RSS: Main: 200945664 06:31:24 INFO - PROCESS | 1656 | 06:31:26 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:31:27 INFO - PROCESS | 1656 | RSS: Main: 201297920 06:31:27 INFO - PROCESS | 1656 | 06:31:30 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom) 06:31:31 INFO - PROCESS | 1656 | RSS: Main: 204980224 06:31:31 INFO - PROCESS | 1656 | 06:31:34 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate) 06:31:35 INFO - PROCESS | 1656 | RSS: Main: 204214272 06:31:35 INFO - PROCESS | 1656 | 06:31:38 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial) 06:31:39 INFO - PROCESS | 1656 | RSS: Main: 204353536 06:31:39 INFO - PROCESS | 1656 | 06:31:41 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:31:41 INFO - PROCESS | 1656 | RSS: Main: 204009472 06:31:41 INFO - PROCESS | 1656 | 06:31:43 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:31:44 INFO - PROCESS | 1656 | RSS: Main: 203960320 06:31:44 INFO - PROCESS | 1656 | 06:31:46 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:31:46 INFO - PROCESS | 1656 | RSS: Main: 204005376 06:31:46 INFO - PROCESS | 1656 | 06:31:49 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:31:50 INFO - PROCESS | 1656 | RSS: Main: 204472320 06:31:50 INFO - PROCESS | 1656 | 06:31:53 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:31:54 INFO - PROCESS | 1656 | RSS: Main: 204197888 06:31:54 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49224/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49224/tests/kraken/driver.html?testName=ai-astar) 06:31:55 INFO - PROCESS | 1656 | RSS: Main: 206065664 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | __start_tp_report 06:31:55 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 06:31:55 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 06:31:55 INFO - PROCESS | 1656 | |i|pagename|runs| 06:31:55 INFO - PROCESS | 1656 | |0;ai-astar;107;120;118;117;118;118;119;119;120;118 06:31:55 INFO - PROCESS | 1656 | |1;audio-beat-detection;141;133;101;178;137;140;95;138;152;141 06:31:55 INFO - PROCESS | 1656 | |2;audio-dft;194;201;197;202;195;227;277;239;192;192 06:31:55 INFO - PROCESS | 1656 | |3;audio-fft;86;69;82;88;73;60;62;74;96;84 06:31:55 INFO - PROCESS | 1656 | |4;audio-oscillator;90;71;83;71;73;87;112;103;101;112 06:31:55 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;136;88;137;136;117;95;136;136;90;88 06:31:55 INFO - PROCESS | 1656 | |6;imaging-darkroom;105;96;95;101;111;91;122;152;150;95 06:31:55 INFO - PROCESS | 1656 | |7;imaging-desaturate;114;140;106;136;140;144;91;92;89;86 06:31:55 INFO - PROCESS | 1656 | |8;json-parse-financial;67;65;64;97;68;62;92;105;103;97 06:31:55 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;57;68;66;61;60;61;58;62;62;62 06:31:55 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;83;82;81;87;83;85;81;81;84;88 06:31:55 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;138;137;137;135;135;140;184;152;185;133 06:31:55 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;175;215;164;163;161;197;168;162;159;162 06:31:55 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;64;64;65;65;65;66;63;62;65;65 06:31:55 INFO - PROCESS | 1656 | __end_tp_report 06:31:55 INFO - PROCESS | 1656 | __start_cc_report 06:31:55 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,994 06:31:55 INFO - PROCESS | 1656 | __end_cc_report 06:31:55 INFO - PROCESS | 1656 | __startTimestamp1495805515963__endTimestamp 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | ------- Summary: start ------- 06:31:55 INFO - PROCESS | 1656 | Number of tests: 14 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:117.40 Median:118.50 stddev:3.78 (3.2%) stddev-sans-first:1.01 06:31:55 INFO - PROCESS | 1656 | Values: 107.0 120.0 118.0 117.0 118.0 118.0 119.0 119.0 120.0 118.0 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:135.60 Median:141.00 stddev:23.58 (16.7%) stddev-sans-first:24.93 06:31:55 INFO - PROCESS | 1656 | Values: 141.0 133.0 101.0 178.0 137.0 140.0 95.0 138.0 152.0 141.0 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:211.60 Median:201.50 stddev:27.96 (13.9%) stddev-sans-first:28.92 06:31:55 INFO - PROCESS | 1656 | Values: 194.0 201.0 197.0 202.0 195.0 227.0 277.0 239.0 192.0 192.0 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:77.40 Median:83.00 stddev:11.73 (14.1%) stddev-sans-first:12.02 06:31:55 INFO - PROCESS | 1656 | Values: 86.0 69.0 82.0 88.0 73.0 60.0 62.0 74.0 96.0 84.0 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:90.30 Median:72.00 stddev:16.08 (22.3%) stddev-sans-first:17.05 06:31:55 INFO - PROCESS | 1656 | Values: 90.0 71.0 83.0 71.0 73.0 87.0 112.0 103.0 101.0 112.0 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:115.90 Median:112.50 stddev:22.92 (20.4%) stddev-sans-first:23.12 06:31:55 INFO - PROCESS | 1656 | Values: 136.0 88.0 137.0 136.0 117.0 95.0 136.0 136.0 90.0 88.0 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:111.80 Median:121.50 stddev:22.58 (18.6%) stddev-sans-first:23.82 06:31:55 INFO - PROCESS | 1656 | Values: 105.0 96.0 95.0 101.0 111.0 91.0 122.0 152.0 150.0 95.0 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:113.80 Median:115.00 stddev:24.09 (20.9%) stddev-sans-first:25.55 06:31:55 INFO - PROCESS | 1656 | Values: 114.0 140.0 106.0 136.0 140.0 144.0 91.0 92.0 89.0 86.0 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:82.00 Median:67.50 stddev:18.12 (26.8%) stddev-sans-first:18.38 06:31:55 INFO - PROCESS | 1656 | Values: 67.0 65.0 64.0 97.0 68.0 62.0 92.0 105.0 103.0 97.0 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:61.70 Median:62.00 stddev:3.30 (5.3%) stddev-sans-first:3.03 06:31:55 INFO - PROCESS | 1656 | Values: 57.0 68.0 66.0 61.0 60.0 61.0 58.0 62.0 62.0 62.0 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:83.50 stddev:2.51 (3.0%) stddev-sans-first:2.65 06:31:55 INFO - PROCESS | 1656 | Values: 83.0 82.0 81.0 87.0 83.0 85.0 81.0 81.0 84.0 88.0 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:139.00 stddev:20.13 (14.5%) stddev-sans-first:21.05 06:31:55 INFO - PROCESS | 1656 | Values: 138.0 137.0 137.0 135.0 135.0 140.0 184.0 152.0 185.0 133.0 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.60 Median:166.00 stddev:18.65 (11.2%) stddev-sans-first:19.76 06:31:55 INFO - PROCESS | 1656 | Values: 175.0 215.0 164.0 163.0 161.0 197.0 168.0 162.0 159.0 162.0 06:31:55 INFO - PROCESS | 1656 | 06:31:55 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.40 Median:65.00 stddev:1.17 (1.8%) stddev-sans-first:1.24 06:31:55 INFO - PROCESS | 1656 | Values: 64.0 64.0 65.0 65.0 65.0 66.0 63.0 62.0 65.0 65.0 06:31:55 INFO - PROCESS | 1656 | -------- Summary: end -------- 06:31:55 INFO - PROCESS | 1656 | 06:31:56 INFO - TEST-INFO | 1656: exit 0 06:31:56 INFO - TEST-OK | kraken | took 60801ms 06:31:56 INFO - SUITE-END | took 1177s 06:31:57 INFO - Completed test suite (00:19:37) 06:31:57 INFO - javascript benchmark 06:31:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7840.756578349209, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2779.596958441026, 3471.704905083068, 3652.634736526347, 3684.3184010957966, 3697.9630203697957, 5029.874253143668, 5955.196048533452, 6192.349803270659, 6206.503479721623, 6251.59364641298, 6218.657973811439, 6240.313565507802, 6260.311365749775, 6269.9373006269925, 6293.811185664437, 5596.552275817935, 5649.983003059448, 5682.062459694166, 5699.287589051369, 5751.539876809866, 5691.646228241765, 5721.370649228582, 5728.198052272685, 5750.942490575104, 5762.078067509199, 5463.9180412293845, 5491.0116179087745, 5579.191017302489, 5721.914171287434, 5747.827565173052, 5604.691741954191, 5617.0, 5628.409017053233, 5655.830325090254, 5658.830235092953, 5517.44825517449, 5642.379338272786, 5658.321001479808, 5663.688497132656, 5722.856928576777, 4987.351644286219, 5106.463821298785, 6070.514358851281, 6266.185395898549, 6311.179546658951, 6532.673366331712, 6561.048647946069, 6569.178852643419, 6684.298148694365, 6729.6971636276485, 6789.796306110825, 6807.829804254884, 6814.046033555257, 6820.113385259934, 6829.282925292872, 6253.374662533801, 6302.338254483305, 6321.1782468278825, 6346.30190679025, 6350.174477317919, 6577.408033277028, 6711.228208755958, 6794.286599907037, 6798.354156355237, 6845.041694162821, 6603.570767900071, 6603.966980165164, 6604.174478190226, 6605.174353205849, 6625.039369291522, 6656.134702488743, 6728.192616885907, 6748.460123190133, 6771.28901465339, 6776.83057923546, 6425.453836423956, 6489.1888513935755, 6514.3811337922825, 6549.9345006550275, 6664.366885145903, 5577.9163312550345, 6341.968290158519, 6652.201735791736, 6716.093327400738, 6731.158605174353, 5204.505571970657, 5252.343457067866, 5304.832936753908, 5318.25544423781, 5345.518903298722, 4726.1492931272705, 4778.976105119452, 4790.880227994258, 4805.687630304019, 4848.200046992207, 6125.296497837281, 6175.96912015437, 6318.210223722035, 6324.873502530016, 6325.9051114233325, 6593.109930159367, 6656.467482601381, 6716.597004179765, 6717.596944183365, 6736.696848641823, 6336.493080553545, 6340.619562826245, 6350.33321501245, 6358.205224346957, 6428.164338636041, 5681.488666020078, 5932.940670593325, 5944.494717948936, 5977.820665380045, 5996.760129594854, 6763.560368576125, 6872.072270243453, 6885.414739746977, 6929.237783843874, 6955.756548520778, 6720.865582688417, 6744.831379215559, 6785.796426107225, 6795.626240556619, 6812.352826481477, 5414.729263536886, 6998.860022799414, 7021.368076873106, 7031.507794454339, 7047.224805271314, 6340.0, 6396.200474940632, 6562.310957349409, 6590.176227971504, 6622.403983641495, 6768.0, 6770.119884414995, 6772.25505194438, 6773.254941956479, 6786.830329241809, 5371.865703357291, 5483.150111732688, 6477.8704425912165, 6485.962246040611, 6586.0, 6253.843653908507, 6300.338464461349, 6324.209473815773, 6449.838754031187, 6463.838404039937, 6354.745810167541, 6388.425041746265, 6454.806355809334, 6457.515686323639, 6466.256380516113, 6268.1851359323, 6539.378759017885, 6540.084388185753, 6550.967245163743, 6567.507436942344, 6218.844528886814, 6267.561270711189, 6322.146510221061, 6425.903611445926, 6438.484921206198], "value": 6106.21663668708, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.4876284126793, 776.5224387001992, 843.4433274039134, 873.8028900406445, 973.922086233101, 33025.33949321015, 40016.79991600042, 41254.58745412545, 41338.793306033425, 41396.58603413965, 39450.40824387629, 39715.0, 39718.0, 39740.006499837444, 39746.0, 8279.917200828006, 8425.031121421023, 11153.386563738992, 11375.658730238107, 11561.595344162955, 11293.887061129366, 11354.48904799286, 11369.829452558219, 11375.829362559569, 11379.089672826154, 11369.829452558219, 11409.37248451335, 11414.42927853604, 11419.48612312448, 11420.942895285554, 9665.758356041086, 11286.830697539543, 11322.773544529146, 11327.660170194866, 11332.490037948312, 11072.335659860353, 11125.998660120627, 11153.72115697106, 11160.386178760165, 11184.0, 35075.123121921904, 35365.64634353675, 35417.64582354195, 35527.82236088803, 35628.82185589081, 3887.32142057414, 3895.0651843557625, 3926.0, 4007.218592374459, 4159.168166366739, 20721.0, 25980.090696825708, 26181.869090654614, 26377.604335934975, 26400.735992640213, 3137.9372412551847, 3230.3054843208824, 3301.042697617688, 3370.0900756795527, 3393.474011528217, 307449.0, 310714.0, 354852.0, 363551.0, 466428.0, 44888.3266750999, 45011.54988450139, 45077.77461112739, 45082.549174508495, 45265.54734452679, 46445.0, 46732.0, 46780.06439871184, 46829.76585117121, 46873.82815429642, 14661.85338146605, 14727.705445891022, 14751.77872331916, 14752.409903603735, 14769.0, 12475.563355282522, 12526.436310366056, 12706.491740330468, 12729.872701272869, 12735.0, 12044.518219271305, 12049.457774400173, 12096.879031209575, 12104.910558049818, 12137.939310303393, 5828.59199856006, 5833.679147646835, 5845.853853653693, 5866.970665146647, 5902.114682797529, 1059.359087751899, 1064.4890452582804, 1088.689723428819, 1097.4512743628186, 1139.4701463819363, 16496.42262520806, 16776.0, 16866.746998795028, 17011.91494042522, 17047.40334088326, 985.0445068283753, 1159.756451145252, 1175.7589694112687, 1179.2865316483403, 1221.3648902570792, 8419.410641255176, 8512.233898949124, 8610.397272190887, 8710.47737135774, 8833.0, 411720.0, 415974.0, 416155.91922040196, 417409.0, 417654.0, 46185.76907115443, 52096.0, 56131.71934140303, 56229.43770562242, 56852.0, 1431.8926080544002, 1492.3359105198083, 1503.225838693074, 1505.6160679026777, 1518.567208345616, 15327.693446131238, 15580.68838623244, 16456.67086658236, 16773.496795096165, 16950.745738814174, 45734.313985289584, 45908.0, 46003.53996460127, 46008.30987535256, 46018.76990615026, 365480.1725991353, 365624.0, 365659.1717041398, 365956.1702191578, 366547.0, 27086.05198818032, 30747.9238226661, 31026.069217923497, 32106.83946580252, 32335.353292933538, 32972.50541241931, 39838.80483585497, 39914.403423863885, 40491.59508404996, 41653.958651033965, 22968.851557422397, 28872.855635721688, 28955.42089158163, 29072.41855162927, 30074.849625751733, 23284.883575582015, 29076.0, 29210.0, 29657.555136672534, 29868.0, 26676.7332326682, 34236.82881585576, 34479.827600861834, 34505.82747086249, 34513.48229776606, 27838.58242126329, 28299.434011319245, 29778.10665680033, 29834.850825745732, 30034.84982575073, 23339.649905251095, 23445.648315274942, 23606.0, 23743.643845341987, 24068.51862962766], "value": 17712.876524344178, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [368.70872011111226, 385.42763995466726, 420.5941266677655, 420.69499612780754, 436.299738919035, 20757.377278681644, 21469.4632634184, 21784.673229901564, 21942.012609432575, 22169.55660886783, 15368.923155384235, 20852.687209691867, 21528.461788455297, 22174.778252217475, 22653.20713775018, 6706.094677218574, 7030.0, 7051.647417629124, 7059.611721355323, 7071.504994650377, 6280.152179455784, 6920.584764914096, 6935.930640693579, 6943.930560694404, 6966.651667416634, 6583.736650533973, 6923.549969252008, 6927.722891084376, 6930.237673855871, 6936.0636314097455, 6834.316568343175, 6985.9999999999745, 6988.61562614056, 6996.790096297119, 6997.82505437363, 6445.709943052527, 6729.663516824188, 6768.864622707568, 6775.085363475966, 6833.111695479605, 27603.750997605704, 27690.892364305404, 27842.747076381613, 27989.580156297467, 28279.434411311864, 3010.5333673280675, 3032.3328867649084, 3053.603031605877, 3104.580881581003, 3131.62420509538, 16740.497785066345, 17571.12144392788, 17769.11154442273, 17837.910810445996, 17912.91043544774, 2155.1164022750754, 2179.9128034878745, 2183.825293976478, 2187.0923566719794, 2215.2578886073184, 132312.33843830816, 134354.0, 135367.64632353748, 138184.0, 138988.0, 21944.902754862193, 22717.318480445614, 23167.768322316897, 23330.0, 23350.7664923352, 38200.8089959554, 38333.233335333134, 38661.03347416337, 39147.80426097908, 39190.80404597959, 10490.632827850988, 11070.944645276722, 11138.665840024812, 11159.442027898735, 11310.264832785842, 9238.214751746042, 9333.346665733468, 9345.859812102824, 9522.381045232038, 9529.809403811885, 9759.0, 9911.315076437024, 10009.199264059003, 10037.598496060222, 10101.0, 4320.395144679747, 4417.8232870684815, 4423.8230470781455, 4429.7785110744335, 4438.60052595268, 872.9519876406858, 898.1961144775389, 921.8985911549725, 945.9479728614855, 962.4802606592501, 21293.78706212918, 22503.212387566356, 22508.0, 22534.0, 22643.660345094835, 948.2414068744977, 950.2255661635745, 971.7230589282162, 973.8490533967247, 976.345848281653, 7122.643867806589, 11231.21381503287, 11655.53377864876, 11662.94168529152, 11674.76650467003, 741336.4665883397, 798691.0, 799621.0, 802743.0, 813439.0, 122755.0, 138790.30604846912, 154124.0, 155160.0, 157445.0, 1310.5020092364841, 1313.0414797197907, 1333.48660765604, 1338.3910320804011, 1345.2466618693368, 18703.71944420862, 19689.310874119335, 19709.605807883476, 19760.703589446457, 19795.505112372306, 63646.0, 64355.0, 64548.677256615294, 65688.67155664192, 66353.0, 89324.6601300968, 90088.5495572518, 91258.63112053192, 92187.61718574361, 92725.21937276499, 11630.767384652429, 11703.941480292544, 11981.76036479283, 12092.576759813715, 12165.148439609497, 13226.8757155639, 13275.0, 13283.86716132865, 13293.734125317245, 13542.729145417234, 9574.425534467675, 11234.438278086227, 11353.432328383382, 11452.885471145182, 11525.538978440769, 10491.580336786443, 10524.421156836448, 10756.946215268874, 10808.945955270488, 10926.562937482411, 11757.764844702888, 11919.642410727693, 12015.399230038289, 12086.335251560897, 12143.757124857277, 11773.0, 11819.940900295443, 11877.168598198043, 12090.335031573348, 12164.817527736914, 8804.207621314112, 10429.634962776267, 11472.94263528677, 11506.884931150582, 11660.358680272666], "value": 11906.933223286735, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1374.924379159146, 1614.5237155039263, 1620.2222932992163, 1635.1252080137122, 1643.9095849728265, 9691.515424228777, 9983.700488985342, 10898.891011089896, 11621.825672614907, 11640.650780476579, 10868.945655271713, 11205.159613029015, 11237.83143252852, 11253.493592788323, 11302.0, 2503.123906632673, 2530.177692250017, 2536.797056235506, 2540.720520742717, 2551.578989466745, 2490.3898544856534, 2505.4237525369085, 2525.1288305534563, 2525.229804909502, 2528.9494210115786, 2218.7226596675414, 2481.280428675682, 2487.266256454341, 2492.8628925409093, 2493.3143385568933, 2215.966760498594, 2265.558216147852, 2380.9523809523885, 2460.6432067350224, 2464.383904023994, 2128.4891626009803, 2303.2744685424036, 2317.9420514487106, 2324.4886125052462, 2329.33613920032, 9774.462404567746, 9874.55564499592, 9917.603295868157, 9919.900800992042, 9920.751981200456, 6050.788222412194, 6134.478569321613, 6200.627962322275, 6211.937880621181, 6223.813285601439, 3732.122951106488, 3825.7321987460887, 3848.2303539291975, 3883.4951456310782, 3890.9416358754643, 1243.1919252485957, 1248.8813562711528, 1277.910546261762, 1301.44688507384, 1303.5176984515695, 15797.131157786202, 15927.362905483767, 15941.202939852961, 15950.521484355488, 16019.038857668576, 4308.3968244445805, 4937.1606826839525, 5177.637565370464, 5193.610479214074, 5221.869453263699, 11549.133814963743, 13207.141535800141, 13243.735125297633, 13267.004974626941, 13315.334233288297, 5956.940430595725, 6094.055421409688, 6165.167702360213, 6170.81487555374, 6176.073588961602, 4670.602998745145, 5190.584753219733, 5253.50091741284, 5506.476884696028, 5551.361593416728, 3585.713142948558, 3642.8889188797143, 4173.603507666767, 4285.121550082225, 4293.82824687009, 1122.3714719756963, 1189.4885199364182, 1190.1966172833304, 1190.4999900042003, 1204.7771162334996, 929.9398685498537, 931.725141083382, 933.1415098109755, 937.2923442800642, 989.7129832348538, 6466.321036291216, 6547.500099916093, 6648.700808463534, 6903.99892015665, 6922.619255940972, 836.9790755231046, 968.9467079310706, 995.5022488755623, 1008.436922769704, 1033.03927347566, 3413.35146322198, 4102.118044620421, 4102.3026085565525, 4133.0, 4210.263203939298, 13599.728005439836, 14041.368138433794, 14101.0, 14106.71786564263, 14260.786088208684, 13820.585382438543, 13891.652708682364, 14065.648358791112, 14221.786673199911, 14243.359048842827, 10320.690379288633, 10465.42440165783, 10474.52864621109, 10478.37129772216, 10496.422696751752, 6424.518161138027, 6533.542652014314, 6583.0454584086, 6689.063531105552, 6764.830879227862, 12350.320732359807, 12404.503819847105, 12408.379581020732, 12449.066320025853, 12540.811887821872, 11280.2667826591, 12640.810387844374, 12816.871831281567, 12923.15999460032, 13109.475620975054, 8317.835503029703, 9136.588853501475, 9153.77115572116, 9262.861057084014, 9425.858612120686, 10533.315334503233, 10545.156387488829, 10627.256092073481, 10643.94678026605, 10711.517981690688, 9088.0, 9127.08729127082, 9151.993280739247, 9261.490618016074, 9283.860742088738, 7286.744963926237, 8922.86615700778, 8995.41529800587, 8997.865032024394, 9014.594343254701, 7997.560134192442, 8050.879236811335, 8112.351011918914, 8167.3466122711225, 8168.754937351889, 5828.038373668297, 5871.354151043467, 5914.408559144051, 6050.546209034428, 6110.297315808739, 5922.585419020627, 6019.097135429721, 6047.425494578008, 6161.599496032923, 6207.503399727921], "value": 5250.255178199376, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.5713279833808, 378.0360081791431, 396.87101691950124, 397.4912112496005, 402.579304626665, 5192.376914770228, 5217.191335343018, 5318.787248510064, 5500.147477141039, 5525.0883604205255, 4384.0135969406865, 4664.833791552112, 4844.2457095750815, 5275.551578115856, 5359.222912677659, 3703.8703645372484, 4009.5565596385277, 4406.339049142623, 4424.225760491901, 4469.994251293466, 3614.8373323200517, 4253.787310634471, 4327.221100201962, 4440.42274504314, 4454.688171827974, 3860.7607587599127, 4352.804123814436, 4367.956320436802, 4373.956260437387, 4427.358033085216, 3882.515190278224, 3925.0, 4123.731957422788, 4393.538678438766, 4440.467143942731, 3748.269087527933, 3964.8414063437426, 4084.6936479764136, 4101.91796164075, 4125.91748165038, 5080.364954380702, 5138.332016837825, 5196.324477817897, 5239.834331706303, 5242.68543887368, 4897.2898929655, 5135.79308862417, 5165.819196328111, 5189.558887494568, 5205.973970130163, 4618.884527886796, 5170.431252562215, 5635.21107045014, 5685.232493613351, 5734.741936612821, 3769.321522126016, 4585.72485650862, 4585.770711464447, 4602.964333025043, 4609.216433206363, 5267.183586544092, 5364.463553644604, 5845.181674565607, 6035.818925432244, 6104.4200800923845, 3924.60753924611, 4462.906587885924, 4640.559146881041, 4695.577398034194, 4745.762711864393, 4530.433695788027, 4718.95281047192, 4771.260454629538, 4775.6657034007985, 4823.879403014883, 4263.531011588722, 4987.875303117451, 5073.8731531712, 5089.51649593288, 5138.486151384832, 4646.83736069236, 5014.623903207274, 5626.465485778926, 5666.518345940559, 5692.686902220335, 1360.5034162530803, 1454.2001898955532, 1626.983730162707, 1629.6229685915382, 1634.0849124490323, 1032.8347464405658, 1106.9373401534456, 1206.9215500992407, 1223.7980733178927, 1244.8858271846827, 4466.508684044752, 5265.314280003936, 5301.3373328333955, 5306.6550674206055, 5327.7910450743475, 5711.572581652781, 5860.589758716934, 5860.619059761102, 5915.83502825986, 5920.4773159969745, 4430.867073987785, 4459.308807134899, 4855.417349918032, 5282.7622756976025, 5321.057813897977, 2493.862837543916, 2547.0193975319635, 2819.126070918022, 3012.9246768830953, 3067.0798760372245, 5650.039493286151, 5791.420857914259, 5825.854353641193, 6095.6037857540005, 6168.599041062316, 5602.4033150551895, 5648.4633959773755, 5867.735951882176, 5991.472174595538, 5996.2504686914135, 5528.585356098309, 5961.4038596140035, 6067.206906543869, 6071.150038994632, 6102.511799056154, 4719.38647975761, 4761.952380476151, 4950.282209079735, 5223.686578805284, 5499.84503254321, 5148.43367229597, 5329.893402131858, 5391.62258641907, 5719.48524632785, 5744.391570360306, 4878.438979517259, 5542.337298810421, 6121.979746683494, 6143.61768602061, 6181.84545386369, 4137.358709400048, 4644.140833945731, 4654.976725116353, 4681.010570507588, 4697.976510117564, 4823.938733478629, 5534.122256426716, 5857.970710146422, 5876.529877609695, 5895.823125306248, 5212.609054321017, 5273.34083239595, 5673.432656734459, 5725.854829034293, 5728.484436400744, 4841.443234027991, 4883.487233840396, 5133.512316329943, 5209.95800839826, 5552.278203833475, 4679.695819771704, 4774.0, 5154.989554073839, 5214.9570085982195, 5596.496315331639, 5042.512458824729, 5096.617753668415, 5401.378841433286, 5720.882119245105, 5762.0, 5024.924626130684, 5115.979188220706, 5424.647397919122, 5602.635828671123, 5626.35296940857], "value": 4301.218588478793, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.4140368581179, 779.2285637219147, 803.4456225204608, 900.5362238447199, 910.9134632209938, 16407.589810254736, 17397.91301043496, 17448.214830332632, 17471.475855724333, 17498.65002699947, 13452.932735336333, 13469.393877275521, 13534.864651353484, 13550.457981680745, 15399.999000065016, 973.9220862330997, 991.6380521109797, 1012.817692815293, 1036.6112707734599, 1036.7304500829764, 1018.6485662446444, 1019.9031092046281, 1021.8313978193589, 1023.9744006399825, 1025.5538840604324, 1070.4380200394778, 1078.4607696151925, 1080.9081228095622, 1082.5994382078643, 1083.49617427896, 952.766572189811, 1076.7361996310876, 1077.1113831089344, 1080.076534562949, 1080.6271836216572, 795.8408318336297, 796.195842199377, 796.9880451793227, 797.11520212564, 798.2336956521746, 16367.345306187739, 16910.408135715315, 17269.740953885703, 17295.22171502273, 17663.11684415774, 14819.333130009176, 14835.406583736638, 14846.554603361916, 14849.70300593982, 15005.624859378493, 12053.096017798613, 12108.576199832964, 12125.211861228992, 12194.695132621666, 12497.93751031239, 3684.5762737285286, 3722.143906901401, 3745.0637340664834, 3791.2607041626893, 3842.9231415371814, 87932.0, 118452.0, 119174.21238681427, 120090.0, 120441.79558204394, 11508.482118304697, 11626.476808543635, 11694.941525292319, 11739.530418783324, 11761.411929403495, 24170.274891753277, 24524.75475245225, 24546.386340341276, 24600.876995614908, 24608.0, 8126.39052071097, 8361.581920903931, 8405.663773449116, 8484.533350665773, 8609.655613775503, 4608.377868987711, 4613.884652883705, 4690.64820138491, 4700.412448443944, 4819.108464933998, 3432.828358582061, 3496.423090190141, 3518.964810351915, 3527.894163175109, 3537.4340105582983, 664.5647101148755, 673.4982438083659, 673.619405036153, 681.9965900170467, 683.0, 692.9826754331182, 694.6040756768695, 700.432649553863, 725.6661935509619, 761.5050217358651, 10220.795584088426, 10256.12822910046, 10551.947240263904, 10659.253852230424, 10766.676999690022, 678.5962352400314, 698.9580625162506, 705.8200158959432, 728.49005696013, 731.7256028989129, 5146.794128234904, 5211.960013747136, 5222.164453687392, 5323.787048518016, 5329.307190065267, 105414.47292763487, 106313.0, 107217.46391267993, 109487.0, 109780.0, 75642.0, 76045.0, 77065.61467192628, 80268.0, 86299.0, 72574.18564535823, 77355.83966240511, 77490.61254693803, 77698.05754856174, 79247.20752792398, 9710.368826026286, 10834.620788272372, 11408.201425900108, 11513.366764828015, 11650.009749171077, 46383.3042504356, 49894.0, 50682.74658626683, 51563.74218128886, 51707.741461292455, 64181.0, 64836.379090523114, 69531.60936781338, 71229.6438517804, 72992.6350368266, 13410.865891341353, 13510.324483775968, 13628.0, 13909.443622254996, 14018.719625607635, 15157.090574565562, 15243.314050867959, 15367.0, 15373.923130384277, 15646.608834779221, 14046.508372206923, 14403.351849166604, 14505.637359066108, 14536.418543258575, 14764.557063288119, 13989.510367137102, 14557.92721036388, 14612.926935365254, 14728.55814325572, 15189.544313670607, 13306.800397994231, 13340.332983350987, 14193.0, 14209.703285887537, 14256.786148207577, 9999.900001000187, 10419.322744021614, 10564.313319634199, 10602.20483463728, 10968.396738179477, 8898.86651700238, 9475.857862131936, 11533.423328833356, 11781.234219775688, 11806.881931180578], "value": 7988.71369591747, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 120.0, 118.0, 117.0, 118.0, 118.0, 119.0, 119.0, 120.0, 118.0], "value": 117.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 133.0, 101.0, 178.0, 137.0, 140.0, 95.0, 138.0, 152.0, 141.0], "value": 135.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 201.0, 197.0, 202.0, 195.0, 227.0, 277.0, 239.0, 192.0, 192.0], "value": 211.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 82.0, 88.0, 73.0, 60.0, 62.0, 74.0, 96.0, 84.0], "value": 77.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 83.0, 71.0, 73.0, 87.0, 112.0, 103.0, 101.0, 112.0], "value": 90.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 88.0, 137.0, 136.0, 117.0, 95.0, 136.0, 136.0, 90.0, 88.0], "value": 115.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 96.0, 95.0, 101.0, 111.0, 91.0, 122.0, 152.0, 150.0, 95.0], "value": 111.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 140.0, 106.0, 136.0, 140.0, 144.0, 91.0, 92.0, 89.0, 86.0], "value": 113.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 64.0, 97.0, 68.0, 62.0, 92.0, 105.0, 103.0, 97.0], "value": 82.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 68.0, 66.0, 61.0, 60.0, 61.0, 58.0, 62.0, 62.0, 62.0], "value": 61.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 81.0, 87.0, 83.0, 85.0, 81.0, 81.0, 84.0, 88.0], "value": 83.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 137.0, 135.0, 135.0, 140.0, 184.0, 152.0, 185.0, 133.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 215.0, 164.0, 163.0, 161.0, 197.0, 168.0, 162.0, 159.0, 162.0], "value": 172.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 65.0, 65.0, 65.0, 66.0, 63.0, 62.0, 65.0, 65.0], "value": 64.4, "unit": "score"}]}]} 06:31:57 INFO - Return code: 0 06:31:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:31:57 ERROR - # TBPL SUCCESS # 06:31:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:31:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:31:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:31:57 INFO - 2017-05-26 06:31:57.283 screenresolution[1661:10667] starting screenresolution argv=screenresolution get 06:31:57 INFO - 2017-05-26 06:31:57.286 screenresolution[1661:10667] Display 0: 1600x1200x32@60 06:31:57 INFO - 2017-05-26 06:31:57.292 screenresolution[1662:10671] starting screenresolution argv=screenresolution list 06:31:57 INFO - Available Modes on Display 0 06:31:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:31:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:31:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:31:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:31:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:31:57 INFO - Intel Iris: 06:31:57 INFO - Chipset Model: Intel Iris 06:31:57 INFO - Type: GPU 06:31:57 INFO - Bus: Built-In 06:31:57 INFO - VRAM (Dynamic, Max): 1536 MB 06:31:57 INFO - Vendor: Intel (0x8086) 06:31:57 INFO - Device ID: 0x0a2e 06:31:57 INFO - Revision ID: 0x0009 06:31:57 INFO - Displays: 06:31:57 INFO - Display: 06:31:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:31:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:31:57 INFO - Display Serial Number: 1600x1200 60 06:31:57 INFO - Main Display: Yes 06:31:57 INFO - Mirror: Off 06:31:57 INFO - Online: Yes 06:31:57 INFO - Rotation: Supported 06:31:57 INFO - Return code: 0 06:31:57 INFO - Running post-action listener: _resource_record_post_action 06:31:57 INFO - [mozharness: 2017-05-26 13:31:57.529280Z] Finished run-tests step (success) 06:31:57 INFO - Running post-run listener: _resource_record_post_run 06:31:57 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 39663616; Write bytes: 426456064; Read time: 250; Write time: 1414 06:31:57 INFO - TinderboxPrint: CPU usage
54.4% 06:31:57 INFO - TinderboxPrint: I/O read bytes / time
39,663,616 / 250 06:31:57 INFO - TinderboxPrint: I/O write bytes / time
426,456,064 / 1,414 06:31:57 INFO - TinderboxPrint: CPU idle
2,176.3 (45.6%) 06:31:57 INFO - TinderboxPrint: CPU system
956.7 (20.0%) 06:31:57 INFO - TinderboxPrint: CPU user
1,640.8 (34.4%) 06:31:57 INFO - TinderboxPrint: Swap in / out
162,676,736 / 0 06:31:57 INFO - install - Wall time: 15s; CPU: 53.0%; Read bytes: 108866048; Write bytes: 104125952; Read time: 9746; Write time: 220 06:31:57 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 38701056; Write bytes: 301489664; Read time: 237; Write time: 1135 06:31:58 INFO - Running post-run listener: _upload_blobber_files 06:31:58 INFO - Blob upload gear active. 06:31:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:31:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 06:31:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:31:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:31:58 INFO - (blobuploader) - INFO - Open directory for files ... 06:31:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:31:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:31:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:31:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:31:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:31:59 INFO - (blobuploader) - INFO - Done attempting. 06:31:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:31:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:31:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:32:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:32:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:32:00 INFO - (blobuploader) - INFO - Done attempting. 06:32:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 06:32:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:32:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:32:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 06:32:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:32:01 INFO - (blobuploader) - INFO - Done attempting. 06:32:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:32:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:32:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:32:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:32:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:32:03 INFO - (blobuploader) - INFO - Done attempting. 06:32:03 INFO - (blobuploader) - INFO - Iteration through files over. 06:32:03 INFO - Return code: 0 06:32:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:32:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:32:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e89781d6a9de7dde9a960fff7b3f6b2621e607f8d4dd01d8097ac2747886b40c3cd5516042f5bee719dd982f7eba3d26fab890add4ba549bb06a6251931171da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca6c83f7e2a9710dd270ebf529623369a9a060597f95fe7792866bc38d9a4595c4b22911d2073c905b719e57538eaff64e0b95c4d02778153583927f8528ef39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b9209fa639668b721f340db234ec8faead40fc1a0ec8ecca73510822c5192e1c260818cc7836b849f053a3c309a4e8bcc02886eb55bc7f54de3aff335898ac0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/93fb80960bc4458421ab15e918bfa8858cc9960d07c03349ce6c7b7b4b767ebf3429d2bf3a1af168e1761eeaec8a6226ba213ce5a328a3b11bb2d089a4347774"} 06:32:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:32:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:32:03 INFO - Contents: 06:32:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e89781d6a9de7dde9a960fff7b3f6b2621e607f8d4dd01d8097ac2747886b40c3cd5516042f5bee719dd982f7eba3d26fab890add4ba549bb06a6251931171da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca6c83f7e2a9710dd270ebf529623369a9a060597f95fe7792866bc38d9a4595c4b22911d2073c905b719e57538eaff64e0b95c4d02778153583927f8528ef39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b9209fa639668b721f340db234ec8faead40fc1a0ec8ecca73510822c5192e1c260818cc7836b849f053a3c309a4e8bcc02886eb55bc7f54de3aff335898ac0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/93fb80960bc4458421ab15e918bfa8858cc9960d07c03349ce6c7b7b4b767ebf3429d2bf3a1af168e1761eeaec8a6226ba213ce5a328a3b11bb2d089a4347774"} 06:32:03 INFO - Running post-run listener: copy_logs_to_upload_dir 06:32:03 INFO - Copying logs to upload dir... 06:32:03 INFO - mkdir: /builds/slave/test/build/upload/logs 06:32:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.950177 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2017-05-26 06:32:03.174340) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 06:32:03.177724) ========= 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.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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/e89781d6a9de7dde9a960fff7b3f6b2621e607f8d4dd01d8097ac2747886b40c3cd5516042f5bee719dd982f7eba3d26fab890add4ba549bb06a6251931171da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca6c83f7e2a9710dd270ebf529623369a9a060597f95fe7792866bc38d9a4595c4b22911d2073c905b719e57538eaff64e0b95c4d02778153583927f8528ef39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b9209fa639668b721f340db234ec8faead40fc1a0ec8ecca73510822c5192e1c260818cc7836b849f053a3c309a4e8bcc02886eb55bc7f54de3aff335898ac0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/93fb80960bc4458421ab15e918bfa8858cc9960d07c03349ce6c7b7b4b767ebf3429d2bf3a1af168e1761eeaec8a6226ba213ce5a328a3b11bb2d089a4347774"} build_url:https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011035 build_url: 'https://queue.taskcluster.net/v1/task/Mrx4U7xcR_KyJXEnMRpwZg/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/e89781d6a9de7dde9a960fff7b3f6b2621e607f8d4dd01d8097ac2747886b40c3cd5516042f5bee719dd982f7eba3d26fab890add4ba549bb06a6251931171da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ca6c83f7e2a9710dd270ebf529623369a9a060597f95fe7792866bc38d9a4595c4b22911d2073c905b719e57538eaff64e0b95c4d02778153583927f8528ef39", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b9209fa639668b721f340db234ec8faead40fc1a0ec8ecca73510822c5192e1c260818cc7836b849f053a3c309a4e8bcc02886eb55bc7f54de3aff335898ac0d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/93fb80960bc4458421ab15e918bfa8858cc9960d07c03349ce6c7b7b4b767ebf3429d2bf3a1af168e1761eeaec8a6226ba213ce5a328a3b11bb2d089a4347774"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-26 06:32:03.210348) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 06:32:03.210667) ========= 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.cd1ueBuj8Q/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.qFXvqXJyUK/Listeners TMPDIR=/var/folders/s7/5fc64b65217gh236xtt4c1sr00000w/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.005356 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-26 06:32:03.259014) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 06:32:03.259330) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-26 06:32:03.259612) ========= ========= Total master_lag: 0.41 =========