builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0232 starttime: 1495646628.77 results: success (0) buildid: 20170524084321 builduid: ed8b0d08f6ab4f52b37a494f8e18d0d1 revision: b3b1b2125fc0b242026a926b1db8c7f23330cc99 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 10:23:48.766348) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-24 10:23:48.771543) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 10:23:48.771861) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-24 10:23:48.795465) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 10:23:48.795795) ========= 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.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners TMPDIR=/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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-24 10:23:48-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.6M=0.001s 2017-05-24 10:23:48 (19.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.091533 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 10:23:48.909602) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 10:23:48.909927) ========= 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.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners TMPDIR=/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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.022152 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 10:23:48.958527) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 10:23:48.958879) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b3b1b2125fc0b242026a926b1db8c7f23330cc99 --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 b3b1b2125fc0b242026a926b1db8c7f23330cc99 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners TMPDIR=/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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-24 10:23:49,041 truncating revision to first 12 chars 2017-05-24 10:23:49,042 Setting DEBUG logging. 2017-05-24 10:23:49,042 attempt 1/10 2017-05-24 10:23:49,042 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b3b1b2125fc0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-24 10:23:49,822 unpacking tar archive at: mozilla-beta-b3b1b2125fc0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.049374 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-24 10:23:50.032537) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 10:23:50.033068) ========= script_repo_revision: b3b1b2125fc0b242026a926b1db8c7f23330cc99 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-24 10:23:50.033655) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 10:23:50.034120) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-24 10:23:50.053757) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-24 10:23:50.054050) ========= /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.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners TMPDIR=/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:23:50 INFO - MultiFileLogger online at 20170524 10:23:50 in /builds/slave/test 10:23:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 10:23:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:23:50 INFO - {'append_to_log': False, 10:23:50 INFO - 'base_work_dir': '/builds/slave/test', 10:23:50 INFO - 'blob_upload_branch': 'Mozilla-Beta', 10:23:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:23:50 INFO - 'branch': 'Mozilla-Beta', 10:23:50 INFO - 'buildbot_json_path': 'buildprops.json', 10:23:50 INFO - 'config_files': ('talos/mac_config.py',), 10:23:50 INFO - 'default_actions': ('clobber', 10:23:50 INFO - 'read-buildbot-config', 10:23:50 INFO - 'download-and-extract', 10:23:50 INFO - 'populate-webroot', 10:23:50 INFO - 'create-virtualenv', 10:23:50 INFO - 'install', 10:23:50 INFO - 'run-tests'), 10:23:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:23:50 INFO - 'download_minidump_stackwalk': True, 10:23:50 INFO - 'download_symbols': 'ondemand', 10:23:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:23:50 INFO - 'tooltool.py': '/tools/tooltool.py', 10:23:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:23:50 INFO - '/tools/misc-python/virtualenv.py')}, 10:23:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:23:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:23:50 INFO - 'gecko_profile': False, 10:23:50 INFO - 'gecko_profile_interval': 0, 10:23:50 INFO - 'installer_path': 'installer.exe', 10:23:50 INFO - 'log_level': 'info', 10:23:50 INFO - 'log_name': 'talos', 10:23:50 INFO - 'log_to_console': True, 10:23:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:23:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:23:50 INFO - 'opt_config_files': (), 10:23:50 INFO - 'pip_index': False, 10:23:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:23:50 INFO - 'cmd': ('bash', 10:23:50 INFO - '-c', 10:23:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:23:50 INFO - 'enabled': True, 10:23:50 INFO - 'halt_on_failure': False, 10:23:50 INFO - 'name': 'check_screen_resolution'},), 10:23:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:23:50 INFO - 'cmd': ('bash', 10:23:50 INFO - '-c', 10:23:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:23:50 INFO - 'enabled': True, 10:23:50 INFO - 'halt_on_failure': False, 10:23:50 INFO - 'name': 'check_screen_resolution'},), 10:23:50 INFO - 'run_cmd_checks_enabled': True, 10:23:50 INFO - 'suite': 'dromaeojs-e10s', 10:23:50 INFO - 'system_bits': '32', 10:23:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:23:50 INFO - 'title': 't-yosemite-r7-0232', 10:23:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:23:50 INFO - 'use_talos_json': True, 10:23:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:23:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:23:50 INFO - 'work_dir': 'build'} 10:23:50 INFO - [mozharness: 2017-05-24 17:23:50.258672Z] Running clobber step. 10:23:50 INFO - Running pre-action listener: _resource_record_pre_action 10:23:50 INFO - Running main action method: clobber 10:23:50 INFO - rmtree: /builds/slave/test/build 10:23:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:23:53 INFO - Running post-action listener: _resource_record_post_action 10:23:53 INFO - [mozharness: 2017-05-24 17:23:53.768247Z] Finished clobber step (success) 10:23:53 INFO - [mozharness: 2017-05-24 17:23:53.768436Z] Running read-buildbot-config step. 10:23:53 INFO - Running pre-action listener: _resource_record_pre_action 10:23:53 INFO - Running main action method: read_buildbot_config 10:23:53 INFO - Using buildbot properties: 10:23:53 INFO - { 10:23:53 INFO - "project": "", 10:23:53 INFO - "product": "firefox", 10:23:53 INFO - "script_repo_revision": "production", 10:23:53 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 10:23:53 INFO - "repository": "", 10:23:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 10:23:53 INFO - "buildid": "20170524084321", 10:23:53 INFO - "pgo_build": "False", 10:23:53 INFO - "basedir": "/builds/slave/test", 10:23:53 INFO - "buildnumber": 0, 10:23:53 INFO - "slavename": "t-yosemite-r7-0232", 10:23:53 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:23:53 INFO - "platform": "yosemite_r7_devedition", 10:23:53 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 10:23:53 INFO - "branch": "mozilla-beta", 10:23:53 INFO - "repo_path": "releases/mozilla-beta", 10:23:53 INFO - "revision": "b3b1b2125fc0b242026a926b1db8c7f23330cc99", 10:23:53 INFO - "stage_platform": "macosx64-devedition", 10:23:53 INFO - "builduid": "ed8b0d08f6ab4f52b37a494f8e18d0d1", 10:23:53 INFO - "slavebuilddir": "test" 10:23:53 INFO - } 10:23:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 10:23:53 INFO - Running post-action listener: _resource_record_post_action 10:23:53 INFO - [mozharness: 2017-05-24 17:23:53.772895Z] Finished read-buildbot-config step (success) 10:23:53 INFO - [mozharness: 2017-05-24 17:23:53.773065Z] Running download-and-extract step. 10:23:53 INFO - Running pre-action listener: _resource_record_pre_action 10:23:53 INFO - Running main action method: download_and_extract 10:23:53 INFO - mkdir: /builds/slave/test/build/tests 10:23:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:23:53 INFO - trying https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 10:23:53 INFO - Downloading https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:23:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 10:23:55 INFO - Downloaded 1287 bytes. 10:23:55 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 10:23:55 INFO - Using the following test package requirements: 10:23:55 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:23:55 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 10:23:55 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:23:55 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:23:55 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 10:23:55 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:23:55 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 10:23:55 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 10:23:55 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:23:55 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 10:23:55 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 10:23:55 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:23:55 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 10:23:55 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:23:55 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 10:23:55 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:23:55 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 10:23:55 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 10:23:55 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 10:23:55 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 10:23:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:23:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:23:55 INFO - Fetch https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:23:56 INFO - Expected file size: 51964010 10:23:56 INFO - Obtained file size: 51964010 10:24:12 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 10:24:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 10:24:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 10:24:12 INFO - Fetch https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 10:24:14 INFO - Expected file size: 51964010 10:24:14 INFO - Obtained file size: 51964010 10:24:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 10:24:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 10:24:30 INFO - Fetch https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 10:24:31 INFO - Expected file size: 14404449 10:24:31 INFO - Obtained file size: 14404449 10:24:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:31 INFO - trying https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:24:31 INFO - Downloading https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 10:24:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 10:24:34 INFO - Downloaded 74891409 bytes. 10:24:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:24:34 INFO - mkdir: /builds/slave/test/properties 10:24:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:24:34 INFO - Writing to file /builds/slave/test/properties/build_url 10:24:34 INFO - Contents: 10:24:34 INFO - build_url:https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 10:24:34 INFO - Running post-action listener: _resource_record_post_action 10:24:34 INFO - Running post-action listener: set_extra_try_arguments 10:24:34 INFO - [mozharness: 2017-05-24 17:24:34.667298Z] Finished download-and-extract step (success) 10:24:34 INFO - [mozharness: 2017-05-24 17:24:34.667474Z] Running populate-webroot step. 10:24:34 INFO - Running pre-action listener: _resource_record_pre_action 10:24:34 INFO - Running main action method: populate_webroot 10:24:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:24:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:24:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:24:34 INFO - '--tppagecycles', 10:24:34 INFO - '7'], 10:24:34 INFO - 'tests': ['tsvgm']}}, 10:24:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:24:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:24:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:24:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:24:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:24:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:24:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 10:24:34 INFO - 'talos_options': ['--disable-e10s'], 10:24:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:24:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 10:24:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:24:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 10:24:34 INFO - 'talos_options': ['--disable-e10s'], 10:24:34 INFO - 'tests': ['damp', 'tps']}, 10:24:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 10:24:34 INFO - 'tests': ['damp', 'tps']}, 10:24:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:24:34 INFO - 'tests': ['dromaeo_dom']}, 10:24:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:24:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:24:34 INFO - 'tests': ['basic_compositor_video']}, 10:24:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:24:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:24:34 INFO - 'tests': ['a11yr', 10:24:34 INFO - 'ts_paint', 10:24:34 INFO - 'tpaint', 10:24:34 INFO - 'sessionrestore', 10:24:34 INFO - 'sessionrestore_no_auto_restore', 10:24:34 INFO - 'tabpaint']}, 10:24:34 INFO - 'other-e10s': {'tests': ['a11yr', 10:24:34 INFO - 'ts_paint', 10:24:34 INFO - 'tpaint', 10:24:34 INFO - 'sessionrestore', 10:24:34 INFO - 'sessionrestore_no_auto_restore', 10:24:34 INFO - 'tabpaint']}, 10:24:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:24:34 INFO - 'ts_paint', 10:24:34 INFO - 'tpaint', 10:24:34 INFO - 'sessionrestore', 10:24:34 INFO - 'sessionrestore_no_auto_restore', 10:24:34 INFO - 'tabpaint']}, 10:24:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:24:34 INFO - 'ts_paint', 10:24:34 INFO - 'tpaint', 10:24:34 INFO - 'sessionrestore', 10:24:34 INFO - 'sessionrestore_no_auto_restore', 10:24:34 INFO - 'tabpaint']}, 10:24:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:24:34 INFO - 'tests': ['a11yr', 10:24:34 INFO - 'ts_paint', 10:24:34 INFO - 'tpaint', 10:24:34 INFO - 'sessionrestore', 10:24:34 INFO - 'sessionrestore_no_auto_restore', 10:24:34 INFO - 'tabpaint']}, 10:24:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:24:34 INFO - 'tests': ['a11yr', 10:24:34 INFO - 'ts_paint', 10:24:34 INFO - 'tpaint', 10:24:34 INFO - 'sessionrestore', 10:24:34 INFO - 'sessionrestore_no_auto_restore', 10:24:34 INFO - 'tabpaint']}, 10:24:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:24:34 INFO - 'tests': ['tsvgx', 10:24:34 INFO - 'tsvgr_opacity', 10:24:34 INFO - 'tart', 10:24:34 INFO - 'tscrollx', 10:24:34 INFO - 'cart', 10:24:34 INFO - 'tsvg_static']}, 10:24:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:24:34 INFO - 'tsvgr_opacity', 10:24:34 INFO - 'tart', 10:24:34 INFO - 'tscrollx', 10:24:34 INFO - 'cart', 10:24:34 INFO - 'tsvg_static']}, 10:24:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 10:24:34 INFO - 'talos_options': ['--disable-e10s'], 10:24:34 INFO - 'tests': ['tp5o']}, 10:24:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 10:24:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 10:24:34 INFO - 'talos_options': ['--disable-e10s', 10:24:34 INFO - '--xperf_path', 10:24:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:24:34 INFO - 'tests': ['tp5n']}, 10:24:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 10:24:34 INFO - 'talos_options': ['--xperf_path', 10:24:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:24:34 INFO - 'tests': ['tp5n']}}, 10:24:34 INFO - 'talos.zip': {'path': '', 10:24:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:24:34 INFO - Running post-action listener: _resource_record_post_action 10:24:34 INFO - [mozharness: 2017-05-24 17:24:34.686514Z] Finished populate-webroot step (success) 10:24:34 INFO - [mozharness: 2017-05-24 17:24:34.686618Z] Running create-virtualenv step. 10:24:34 INFO - Running pre-action listener: _resource_record_pre_action 10:24:34 INFO - Running main action method: create_virtualenv 10:24:34 INFO - Creating virtualenv /builds/slave/test/build/venv 10:24:34 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:24:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:24:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:24:34 INFO - Using real prefix '/tools/python27' 10:24:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:24:35 INFO - Installing distribute.............................................................................................................................................................................................done. 10:24:38 INFO - Installing pip.................done. 10:24:38 INFO - Return code: 0 10:24:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:24:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:24:38 INFO - Reading from file tmpfile_stdout 10:24:38 INFO - Output received: 10:24:38 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 10:24:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:38 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 0x10bd16b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd19768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8519c7cbf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baf4d30>, '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 0x10ba1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bae9be8>, '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.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:24:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:24:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:24:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZjTlcR2Z3n/Render', 10:24:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:24:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:24:38 INFO - 'HOME': '/Users/cltbld', 10:24:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:24:38 INFO - 'LOGNAME': 'cltbld', 10:24:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:38 INFO - 'MOZ_NO_REMOTE': '1', 10:24:38 INFO - 'NO_EM_RESTART': '1', 10:24:38 INFO - 'PAGER': '/bin/cat', 10:24:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:24:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:24:38 INFO - 'PWD': '/builds/slave/test', 10:24:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:24:38 INFO - 'SHELL': '/bin/bash', 10:24:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L43hBsd6I1/Listeners', 10:24:38 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 10:24:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:24:38 INFO - 'USER': 'cltbld', 10:24:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:24:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:24:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:38 INFO - 'XPC_FLAGS': '0x0', 10:24:38 INFO - 'XPC_SERVICE_NAME': '0', 10:24:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:24:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:39 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:24:39 INFO - Cleaning up... 10:24:39 INFO - Return code: 0 10:24:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bd16b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd19768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8519c7cbf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baf4d30>, '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 0x10ba1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bae9be8>, '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.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:24:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:24:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:24:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZjTlcR2Z3n/Render', 10:24:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:24:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:24:39 INFO - 'HOME': '/Users/cltbld', 10:24:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:24:39 INFO - 'LOGNAME': 'cltbld', 10:24:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:39 INFO - 'MOZ_NO_REMOTE': '1', 10:24:39 INFO - 'NO_EM_RESTART': '1', 10:24:39 INFO - 'PAGER': '/bin/cat', 10:24:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:24:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:24:39 INFO - 'PWD': '/builds/slave/test', 10:24:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:24:39 INFO - 'SHELL': '/bin/bash', 10:24:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L43hBsd6I1/Listeners', 10:24:39 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 10:24:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:24:39 INFO - 'USER': 'cltbld', 10:24:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:24:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:24:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:39 INFO - 'XPC_FLAGS': '0x0', 10:24:39 INFO - 'XPC_SERVICE_NAME': '0', 10:24:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:24:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:39 INFO - Downloading/unpacking psutil>=3.1.1 10:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:24:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:24:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:24:41 INFO - Installing collected packages: psutil 10:24:41 INFO - Running setup.py install for psutil 10:24:41 INFO - building 'psutil._psutil_osx' extension 10:24:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:24:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:24:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:24:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:24:41 INFO - building 'psutil._psutil_posix' extension 10:24:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:24:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:24:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:24:41 INFO - ^ 10:24:41 INFO - 1 warning generated. 10:24:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:24:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:24:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:24:41 INFO - Successfully installed psutil 10:24:41 INFO - Cleaning up... 10:24:42 INFO - Return code: 0 10:24:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bd16b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd19768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8519c7cbf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baf4d30>, '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 0x10ba1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bae9be8>, '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.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:24:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:24:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:24:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZjTlcR2Z3n/Render', 10:24:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:24:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:24:42 INFO - 'HOME': '/Users/cltbld', 10:24:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:24:42 INFO - 'LOGNAME': 'cltbld', 10:24:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:42 INFO - 'MOZ_NO_REMOTE': '1', 10:24:42 INFO - 'NO_EM_RESTART': '1', 10:24:42 INFO - 'PAGER': '/bin/cat', 10:24:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:24:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:24:42 INFO - 'PWD': '/builds/slave/test', 10:24:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:24:42 INFO - 'SHELL': '/bin/bash', 10:24:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L43hBsd6I1/Listeners', 10:24:42 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 10:24:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:24:42 INFO - 'USER': 'cltbld', 10:24:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:24:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:24:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:42 INFO - 'XPC_FLAGS': '0x0', 10:24:42 INFO - 'XPC_SERVICE_NAME': '0', 10:24:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:24:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:24:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:24:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:24:43 INFO - Installing collected packages: mozsystemmonitor 10:24:43 INFO - Running setup.py install for mozsystemmonitor 10:24:43 INFO - Successfully installed mozsystemmonitor 10:24:43 INFO - Cleaning up... 10:24:43 INFO - Return code: 0 10:24:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:43 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 0x10bd16b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd19768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8519c7cbf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baf4d30>, '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 0x10ba1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bae9be8>, '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.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:24:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:24:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:24:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZjTlcR2Z3n/Render', 10:24:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:24:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:24:43 INFO - 'HOME': '/Users/cltbld', 10:24:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:24:43 INFO - 'LOGNAME': 'cltbld', 10:24:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:43 INFO - 'MOZ_NO_REMOTE': '1', 10:24:43 INFO - 'NO_EM_RESTART': '1', 10:24:43 INFO - 'PAGER': '/bin/cat', 10:24:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:24:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:24:43 INFO - 'PWD': '/builds/slave/test', 10:24:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:24:43 INFO - 'SHELL': '/bin/bash', 10:24:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L43hBsd6I1/Listeners', 10:24:43 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 10:24:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:24:43 INFO - 'USER': 'cltbld', 10:24:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:24:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:24:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:43 INFO - 'XPC_FLAGS': '0x0', 10:24:43 INFO - 'XPC_SERVICE_NAME': '0', 10:24:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:44 INFO - Downloading/unpacking jsonschema==2.5.1 10:24: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 10:24: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 10:24: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 10:24: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 10:24:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:24:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:45 INFO - Downloading functools32-3.2.3-2.tar.gz 10:24:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:24:45 INFO - warning: no files found matching '*.txt' 10:24:45 INFO - no previously-included directories found matching 'build' 10:24:45 INFO - no previously-included directories found matching 'dist' 10:24:45 INFO - no previously-included directories found matching '.git*' 10:24:45 INFO - Installing collected packages: jsonschema, functools32 10:24:46 INFO - Running setup.py install for functools32 10:24:46 INFO - warning: no files found matching '*.txt' 10:24:46 INFO - no previously-included directories found matching 'build' 10:24:46 INFO - no previously-included directories found matching 'dist' 10:24:46 INFO - no previously-included directories found matching '.git*' 10:24:46 INFO - Successfully installed jsonschema functools32 10:24:46 INFO - Cleaning up... 10:24:46 INFO - Return code: 0 10:24:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:24: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')]} 10:24:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24: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')]} 10:24:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:47 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 0x10bd16b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd19768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8519c7cbf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baf4d30>, '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 0x10ba1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bae9be8>, '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.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:24:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:24:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZjTlcR2Z3n/Render', 10:24:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:24:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:24:47 INFO - 'HOME': '/Users/cltbld', 10:24:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:24:47 INFO - 'LOGNAME': 'cltbld', 10:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:47 INFO - 'MOZ_NO_REMOTE': '1', 10:24:47 INFO - 'NO_EM_RESTART': '1', 10:24:47 INFO - 'PAGER': '/bin/cat', 10:24:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:24:47 INFO - 'PWD': '/builds/slave/test', 10:24:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:24:47 INFO - 'SHELL': '/bin/bash', 10:24:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L43hBsd6I1/Listeners', 10:24:47 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 10:24:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:24:47 INFO - 'USER': 'cltbld', 10:24:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:24:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:47 INFO - 'XPC_FLAGS': '0x0', 10:24:47 INFO - 'XPC_SERVICE_NAME': '0', 10:24:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:24:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:24:47 INFO - Cleaning up... 10:24:47 INFO - Return code: 0 10:24:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:47 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 0x10bd16b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd19768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8519c7cbf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baf4d30>, '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 0x10ba1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bae9be8>, '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.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:24:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:24:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:24:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZjTlcR2Z3n/Render', 10:24:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:24:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:24:47 INFO - 'HOME': '/Users/cltbld', 10:24:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:24:47 INFO - 'LOGNAME': 'cltbld', 10:24:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:47 INFO - 'MOZ_NO_REMOTE': '1', 10:24:47 INFO - 'NO_EM_RESTART': '1', 10:24:47 INFO - 'PAGER': '/bin/cat', 10:24:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:24:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:24:47 INFO - 'PWD': '/builds/slave/test', 10:24:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:24:47 INFO - 'SHELL': '/bin/bash', 10:24:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L43hBsd6I1/Listeners', 10:24:47 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 10:24:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:24:47 INFO - 'USER': 'cltbld', 10:24:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:24:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:24:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:47 INFO - 'XPC_FLAGS': '0x0', 10:24:47 INFO - 'XPC_SERVICE_NAME': '0', 10:24:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:24:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:48 INFO - Downloading/unpacking blobuploader==1.2.4 10:24: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 10:24: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 10:24: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 10:24: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 10:24:49 INFO - Downloading blobuploader-1.2.4.tar.gz 10:24:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:24:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:24:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:50 INFO - Downloading docopt-0.6.1.tar.gz 10:24:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:24:50 INFO - Installing collected packages: blobuploader, requests, docopt 10:24:50 INFO - Running setup.py install for blobuploader 10:24:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:24:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:24:50 INFO - Running setup.py install for requests 10:24:51 INFO - Running setup.py install for docopt 10:24:51 INFO - Successfully installed blobuploader requests docopt 10:24:51 INFO - Cleaning up... 10:24:51 INFO - Return code: 0 10:24:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:24:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:51 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 0x10bd16b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd19768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8519c7cbf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baf4d30>, '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 0x10ba1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bae9be8>, '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.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:24:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:24:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:24:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZjTlcR2Z3n/Render', 10:24:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:24:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:24:51 INFO - 'HOME': '/Users/cltbld', 10:24:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:24:51 INFO - 'LOGNAME': 'cltbld', 10:24:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:51 INFO - 'MOZ_NO_REMOTE': '1', 10:24:51 INFO - 'NO_EM_RESTART': '1', 10:24:51 INFO - 'PAGER': '/bin/cat', 10:24:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:24:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:24:51 INFO - 'PWD': '/builds/slave/test', 10:24:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:24:51 INFO - 'SHELL': '/bin/bash', 10:24:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L43hBsd6I1/Listeners', 10:24:51 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 10:24:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:24:51 INFO - 'USER': 'cltbld', 10:24:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:24:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:24:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:51 INFO - 'XPC_FLAGS': '0x0', 10:24:51 INFO - 'XPC_SERVICE_NAME': '0', 10:24:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:24:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:24:51 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-gi7lX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:24:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:24:51 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-MsYd9U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:24:52 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-8D3LyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:24:52 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-L2ZinK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:24:52 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-pHMVlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:24:52 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-kdPlJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:24:52 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-PwONO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:24:52 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-jhhxgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:24:52 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-iwqG2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:24:52 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-eFXvkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:24:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:24:52 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-yI9WlG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:24:53 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-LN5HJZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:24:53 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-iMIHJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:24:53 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-NkvQ5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:24:53 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-8qu7tm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:24:53 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-8i1GXN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:24:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:24:53 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-mKUbYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:24:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:24:53 INFO - Running setup.py install for manifestparser 10:24:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:24:53 INFO - Running setup.py install for mozcrash 10:24:53 INFO - Running setup.py install for mozdebug 10:24:54 INFO - Running setup.py install for mozdevice 10:24:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:24:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:24:54 INFO - Running setup.py install for mozfile 10:24:54 INFO - Running setup.py install for mozhttpd 10:24:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:24:54 INFO - Running setup.py install for mozinfo 10:24:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:24:54 INFO - Running setup.py install for mozInstall 10:24:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:24:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:24:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:24:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:24:54 INFO - Running setup.py install for mozleak 10:24:54 INFO - Running setup.py install for mozlog 10:24:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:24:55 INFO - Running setup.py install for moznetwork 10:24:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:24:55 INFO - Running setup.py install for mozprocess 10:24:55 INFO - Running setup.py install for mozprofile 10:24:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:24:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:24:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:24:55 INFO - Running setup.py install for mozrunner 10:24:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:24:55 INFO - Running setup.py install for mozscreenshot 10:24:55 INFO - Running setup.py install for moztest 10:24:56 INFO - Running setup.py install for mozversion 10:24:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:24:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:24:56 INFO - Cleaning up... 10:24:56 INFO - Return code: 0 10:24:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:24: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')]} 10:24:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:24: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')]} 10:24:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:24:56 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 0x10bd16b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd19768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8519c7cbf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baf4d30>, '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 0x10ba1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bae9be8>, '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.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:24:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:24:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:24:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZjTlcR2Z3n/Render', 10:24:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:24:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:24:56 INFO - 'HOME': '/Users/cltbld', 10:24:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:24:56 INFO - 'LOGNAME': 'cltbld', 10:24:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:24:56 INFO - 'MOZ_NO_REMOTE': '1', 10:24:56 INFO - 'NO_EM_RESTART': '1', 10:24:56 INFO - 'PAGER': '/bin/cat', 10:24:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:24:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:24:56 INFO - 'PWD': '/builds/slave/test', 10:24:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:24:56 INFO - 'SHELL': '/bin/bash', 10:24:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L43hBsd6I1/Listeners', 10:24:56 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 10:24:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:24:56 INFO - 'USER': 'cltbld', 10:24:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:24:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:24:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:24:56 INFO - 'XPC_FLAGS': '0x0', 10:24:56 INFO - 'XPC_SERVICE_NAME': '0', 10:24:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:24:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:24:56 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-y614S7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:24:56 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:24:56 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-Qw3u7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:24:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:24:56 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-ZnaZ_1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:24:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:24:56 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-lIdWdQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:24:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:24:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:24:56 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-VYR1Ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:24:57 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-nSfqFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:24:57 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-74fRqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:24:57 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-82sWhL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:24:57 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-MUvimh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:24:57 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-eHpBFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:24:57 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-qJ4kEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:24:57 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-1TNesf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:24:57 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-fjogwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:24:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:24:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:24:57 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-L7SaD4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:24:58 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-qVvBMU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:24:58 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-0qO5WM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:24:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:24:58 INFO - Running setup.py (path:/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/pip-oaB2Un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:24:58 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:24:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:24:59 INFO - Downloading blessings-1.6.tar.gz 10:24:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:24:59 INFO - Installing collected packages: blessings 10:24:59 INFO - Running setup.py install for blessings 10:24:59 INFO - Successfully installed blessings 10:24:59 INFO - Cleaning up... 10:25:00 INFO - Return code: 0 10:25:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:25:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:25:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:25:00 INFO - Reading from file tmpfile_stdout 10:25:00 INFO - Current package versions: 10:25:00 INFO - blessings == 1.6 10:25:00 INFO - blobuploader == 1.2.4 10:25:00 INFO - docopt == 0.6.1 10:25:00 INFO - functools32 == 3.2.3-2 10:25:00 INFO - jsonschema == 2.5.1 10:25:00 INFO - manifestparser == 1.1 10:25:00 INFO - mozInstall == 1.12 10:25:00 INFO - mozcrash == 1.0 10:25:00 INFO - mozdebug == 0.1 10:25:00 INFO - mozdevice == 0.49 10:25:00 INFO - mozfile == 1.2 10:25:00 INFO - mozhttpd == 0.7 10:25:00 INFO - mozinfo == 0.9 10:25:00 INFO - mozleak == 0.1 10:25:00 INFO - mozlog == 3.4 10:25:00 INFO - moznetwork == 0.27 10:25:00 INFO - mozprocess == 0.25 10:25:00 INFO - mozprofile == 0.28 10:25:00 INFO - mozrunner == 6.13 10:25:00 INFO - mozscreenshot == 0.1 10:25:00 INFO - mozsystemmonitor == 0.3 10:25:00 INFO - moztest == 0.8 10:25:00 INFO - mozversion == 1.4 10:25:00 INFO - psutil == 3.1.1 10:25:00 INFO - requests == 1.2.3 10:25:00 INFO - wsgiref == 0.1.2 10:25:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:00 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 0x10bd16b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd19768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8519c7cbf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baf4d30>, '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 0x10ba1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bae9be8>, '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.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:25:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:25:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:25:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZjTlcR2Z3n/Render', 10:25:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:25:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:25:00 INFO - 'HOME': '/Users/cltbld', 10:25:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:25:00 INFO - 'LOGNAME': 'cltbld', 10:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:00 INFO - 'MOZ_NO_REMOTE': '1', 10:25:00 INFO - 'NO_EM_RESTART': '1', 10:25:00 INFO - 'PAGER': '/bin/cat', 10:25:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:00 INFO - 'PWD': '/builds/slave/test', 10:25:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:25:00 INFO - 'SHELL': '/bin/bash', 10:25:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L43hBsd6I1/Listeners', 10:25:00 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 10:25:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:25:00 INFO - 'USER': 'cltbld', 10:25:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:25:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:00 INFO - 'XPC_FLAGS': '0x0', 10:25:00 INFO - 'XPC_SERVICE_NAME': '0', 10:25:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:25:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:25:00 INFO - Cleaning up... 10:25:00 INFO - Return code: 0 10:25:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:25:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bd16b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd19768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8519c7cbf0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baf4d30>, '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 0x10ba1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bae9be8>, '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.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:25:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:25:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:25:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZjTlcR2Z3n/Render', 10:25:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:25:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:25:00 INFO - 'HOME': '/Users/cltbld', 10:25:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:25:00 INFO - 'LOGNAME': 'cltbld', 10:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:00 INFO - 'MOZ_NO_REMOTE': '1', 10:25:00 INFO - 'NO_EM_RESTART': '1', 10:25:00 INFO - 'PAGER': '/bin/cat', 10:25:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:00 INFO - 'PWD': '/builds/slave/test', 10:25:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:25:00 INFO - 'SHELL': '/bin/bash', 10:25:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L43hBsd6I1/Listeners', 10:25:00 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 10:25:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:25:00 INFO - 'USER': 'cltbld', 10:25:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:25:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:00 INFO - 'XPC_FLAGS': '0x0', 10:25:00 INFO - 'XPC_SERVICE_NAME': '0', 10:25:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:25:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:25:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:25:00 INFO - Cleaning up... 10:25:00 INFO - Return code: 0 10:25:00 INFO - Running post-action listener: _resource_record_post_action 10:25:00 INFO - Running post-action listener: _start_resource_monitoring 10:25:00 INFO - Starting resource monitoring. 10:25:00 INFO - [mozharness: 2017-05-24 17:25:00.883188Z] Finished create-virtualenv step (success) 10:25:00 INFO - [mozharness: 2017-05-24 17:25:00.884172Z] Running install step. 10:25:00 INFO - Running pre-action listener: _resource_record_pre_action 10:25:00 INFO - Running main action method: install 10:25:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:25:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:25:01 INFO - Reading from file tmpfile_stdout 10:25:01 INFO - Detecting whether we're running mozinstall >=1.0... 10:25:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:25:01 INFO - Reading from file tmpfile_stdout 10:25:01 INFO - Output received: 10:25:01 INFO - Usage: mozinstall [options] installer 10:25:01 INFO - Options: 10:25:01 INFO - -h, --help show this help message and exit 10:25:01 INFO - -d DEST, --destination=DEST 10:25:01 INFO - Directory to install application into. [default: 10:25:01 INFO - "/builds/slave/test"] 10:25:01 INFO - --app=APP Application being installed. [default: firefox] 10:25:01 INFO - mkdir: /builds/slave/test/build/application 10:25:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 10:25:21 INFO - Reading from file tmpfile_stdout 10:25:21 INFO - Output received: 10:25:21 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:25:21 INFO - Running post-action listener: _resource_record_post_action 10:25:21 INFO - [mozharness: 2017-05-24 17:25:21.281645Z] Finished install step (success) 10:25:21 INFO - [mozharness: 2017-05-24 17:25:21.281782Z] Running run-tests step. 10:25:21 INFO - Running pre-action listener: _resource_record_pre_action 10:25:21 INFO - Running main action method: run_tests 10:25:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:25:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:25:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:25:21 INFO - 2017-05-24 10:25:21.296 screenresolution[1956:11083] starting screenresolution argv=screenresolution get 10:25:21 INFO - 2017-05-24 10:25:21.298 screenresolution[1956:11083] Display 0: 1600x1200x32@60 10:25:21 INFO - 2017-05-24 10:25:21.304 screenresolution[1957:11086] starting screenresolution argv=screenresolution list 10:25:21 INFO - Available Modes on Display 0 10:25:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:25:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:25:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:25:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:25:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:25:21 INFO - Intel Iris: 10:25:21 INFO - Chipset Model: Intel Iris 10:25:21 INFO - Type: GPU 10:25:21 INFO - Bus: Built-In 10:25:21 INFO - VRAM (Dynamic, Max): 1536 MB 10:25:21 INFO - Vendor: Intel (0x8086) 10:25:21 INFO - Device ID: 0x0a2e 10:25:21 INFO - Revision ID: 0x0009 10:25:21 INFO - Displays: 10:25:21 INFO - Display: 10:25:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:25:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:25:21 INFO - Display Serial Number: 1600x1200 60 10:25:21 INFO - Main Display: Yes 10:25:21 INFO - Mirror: Off 10:25:21 INFO - Online: Yes 10:25:21 INFO - Rotation: Supported 10:25:21 INFO - Return code: 0 10:25:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:25:21 INFO - Python 2.7.3 10:25:21 INFO - Return code: 0 10:25:21 INFO - grabbing minidump binary from tooltool 10:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:21 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 0x10baf4d30>, '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 0x10ba1bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bae9be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:25:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:25:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:25:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:25:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:25:21 INFO - Return code: 0 10:25:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:25:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:25:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:25:21 INFO - ENV: RUST_BACKTRACE is now 1 10:25:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:25:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:25:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0232', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 10:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0232 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 10:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZjTlcR2Z3n/Render', 10:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:25:21 INFO - 'HOME': '/Users/cltbld', 10:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:25:21 INFO - 'LOGNAME': 'cltbld', 10:25:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:25:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:25:21 INFO - 'MOZ_NO_REMOTE': '1', 10:25:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:25:21 INFO - 'NO_EM_RESTART': '1', 10:25:21 INFO - 'PAGER': '/bin/cat', 10:25:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:25:21 INFO - 'PWD': '/builds/slave/test', 10:25:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:25:21 INFO - 'RUST_BACKTRACE': '1', 10:25:21 INFO - 'SHELL': '/bin/bash', 10:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.L43hBsd6I1/Listeners', 10:25:21 INFO - 'TMPDIR': '/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/', 10:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:25:21 INFO - 'USER': 'cltbld', 10:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:25:21 INFO - 'XPC_FLAGS': '0x0', 10:25:21 INFO - 'XPC_SERVICE_NAME': '0', 10:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:25:21 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0232', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 10:25:21 INFO - mozversion application_buildid: 20170524084321 10:25:21 INFO - mozversion application_changeset: b3b1b2125fc0b242026a926b1db8c7f23330cc99 10:25:21 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:25:21 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:25:21 INFO - mozversion application_name: Firefox 10:25:21 INFO - mozversion application_remotingname: firefox-dev 10:25:21 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 10:25:21 INFO - mozversion application_vendor: Mozilla 10:25:21 INFO - mozversion application_version: 54.0 10:25:21 INFO - mozversion platform_buildid: 20170524084321 10:25:21 INFO - mozversion platform_changeset: b3b1b2125fc0b242026a926b1db8c7f23330cc99 10:25:21 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 10:25:21 INFO - mozversion platform_version: 54.0 10:25:21 INFO - using testdate: 1495646721 10:25:21 INFO - actual date: 1495646721 10:25:21 INFO - starting webserver on 'localhost:49361' 10:25:21 INFO - SUITE-START | Running 2 tests 10:25:21 INFO - TEST-START | dromaeo_css 10:25:21 INFO - operating with platform_type : mac_ 10:25:21 INFO - Initialising browser for dromaeo_css test... 10:25:21 INFO - TEST-INFO | started process 1967 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/tmpH56RA5/profile http://localhost:49361/getInfo.html) 10:25:24 INFO - PROCESS | 1967 | 2017-05-24 10:25:24.772 plugin-container[1970:11250] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 10:25:24 INFO - PROCESS | 1967 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:25:24 INFO - PROCESS | 1967 | 2017-05-24 10:25:24.773 plugin-container[1970:11250] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d3b, name = 'com.apple.CFPasteboardClient' 10:25:24 INFO - PROCESS | 1967 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:25:24 INFO - PROCESS | 1967 | 2017-05-24 10:25:24.773 plugin-container[1970:11250] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:25:24 INFO - PROCESS | 1967 | __metrics Screen width/height:1600/1200 10:25:24 INFO - PROCESS | 1967 | colorDepth:24 10:25:24 INFO - PROCESS | 1967 | Browser inner width/height: 1024/689 10:25:24 INFO - PROCESS | 1967 | __metrics 10:25:24 ERROR - PROCESS | 1967 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 10:25:25 INFO - TEST-INFO | 1967: exit 0 10:25:25 INFO - Browser initialized. 10:25:25 INFO - Running cycle 1/1 for dromaeo_css test... 10:25:25 INFO - TEST-INFO | started process 1971 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/tmpH56RA5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:25:27 INFO - PROCESS | 1971 | 2017-05-24 10:25:27.949 plugin-container[1972:11376] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x874f, name = 'com.apple.tsm.portname' 10:25:27 INFO - PROCESS | 1971 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:25:27 INFO - PROCESS | 1971 | 2017-05-24 10:25:27.950 plugin-container[1972:11376] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 10:25:27 INFO - PROCESS | 1971 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:25:27 INFO - PROCESS | 1971 | 2017-05-24 10:25:27.950 plugin-container[1972:11376] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:25:37 INFO - PROCESS | 1971 | RSS: Main: 202919936 10:25:37 INFO - PROCESS | 1971 | 10:28:41 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49361/tests/dromaeo/cssquery-ext.html) 10:28:43 INFO - PROCESS | 1971 | RSS: Main: 215941120 10:28:43 INFO - PROCESS | 1971 | 10:31:45 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-ext.html (next: http://localhost:49361/tests/dromaeo/cssquery-jquery.html) 10:31:46 INFO - PROCESS | 1971 | RSS: Main: 230887424 10:31:46 INFO - PROCESS | 1971 | 10:34:48 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49361/tests/dromaeo/cssquery-mootools.html) 10:34:48 INFO - PROCESS | 1971 | RSS: Main: 232202240 10:34:48 INFO - PROCESS | 1971 | 10:37:50 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49361/tests/dromaeo/cssquery-prototype.html) 10:37:51 INFO - PROCESS | 1971 | RSS: Main: 236326912 10:37:51 INFO - PROCESS | 1971 | 10:40:54 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49361/tests/dromaeo/cssquery-yui.html) 10:40:54 INFO - PROCESS | 1971 | RSS: Main: 238309376 10:40:54 INFO - PROCESS | 1971 | 10:43:57 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49361/tests/dromaeo/cssquery-yui.html (next: http://localhost:49361/tests/dromaeo/cssquery-dojo.html) 10:43:57 INFO - PROCESS | 1971 | RSS: Main: 239529984 10:43:57 INFO - PROCESS | 1971 | 10:43:57 INFO - PROCESS | 1971 | __start_tp_report 10:43:57 INFO - PROCESS | 1971 | _x_x_mozilla_page_load 10:43:57 INFO - PROCESS | 1971 | _x_x_mozilla_page_load_details 10:43:57 INFO - PROCESS | 1971 | |i|pagename|runs| 10:43:57 INFO - PROCESS | 1971 | |0;dojo.html;3529.894103176905;3533.6289689582595;3551.1654761131144;3562.9287414251717;3568.018794831421;6066.787662431816;6088.117223002662;6110.755569777215;6134.631922084678;6172.4444799968;4733.692309999848;6082.787102451415;6106.633601983884;6174.382561743823;6189.195404597396;5480.3971563128025;5499.202615620733;5542.196381524676;5568.136938774476;5607.43925607438;4871.853844384674;5590.18942253375;5615.410381909902;5625.746841392147;5626.971865140668;4555.2256116460285;5378.865528361803;5404.891902161954;5541.889162216753;5581.720913954286;5489.533389661883;5503.532199763025;5510.201020851954;5515.200295957064;5549.361823390322;5475.0966090595;5525.254090697745;5542.307211598551;5559.054960656698;5573.41479144692;6197.473214776749;6210.782622608187;6211.534134939898;6224.035274532454;6229.25248970122;5104.157813960692;6300.401461861115;6317;6470.09418681385;6486.448651864597;6358.237011558643;6448.967755161194;6454.548181627288;6490.967545162244;6495.220573531159;6229.7508099676415;6274.737136688942;6278.968605156991;6293.433590976834;6312.589681670676;6690.2306234782645;6734.326567343324;6735.932640673628;6748.763793267212;6749.730010799513;6271.749130034747;6273.7176827042895;6283.08895210192;6334.5249106317215;6336.1762970814425;6524.184476940382;6535.542512024208;6539.705713242915;6550.967245163743;6553.180852393451;6304.464120549713;6441.355864413521;6442;6454.419102280817;6456.063870738717;5100.744962751847;6249.375062493805;6286.18279623646;6309.716062777186;6435.839104022437;4841.515848415131;4879.878003049881;4931.211006238985;4986.102501549756;5071.746412679351;4481.686281960231;4487.035287413157;4498.797554110073;4513.729176249435;4513.729176249435;6042.395760423922;6095.786647467317;6116.785912493041;6119.938800611937;6134.263888333428;5405.837824865261;6238.157848690369;6314.463270622048;6412.422881940554;6478.481721462272;6083.878322433615;6119.593952096689;6126.846828829226;6127.34224287047;6151.784687535915;5685.374608793029;5691.943080569225;5704.34400043992;5706.600537962386;5709.8287051388525;6328.715207815567;6387.872242555216;6701.798946031627;6706.530542862148;6715.865682686319;6364.427201551883;6388.584741991755;6438.806835794933;6504.284528701934;6505.447037001908;6648.434883034996;6713.731450741916;6717.1267735194115;6723.5293529452465;6737.831554210988;6261.405166509175;6451.935480645134;6454.99947508137;6457.934440817213;6470.35296470368;6233.625982441068;6278.874422511615;6281.371862813865;6319;6325.873482530232;5937.673427961504;6301.684915754286;6304.369563043842;6306.4639505642535;6316.178896743394;6227.0970709246;6264.561480696308;6302.369763023661;6324.683765811599;6332.715027823851;6104.095385180614;6115.088229002258;6269.655168965751;6283.3402492737605;6303.527235457267;6349.968250158904;6355.428011478989;6382.521310901608;6387.744490220339;6409.9359006411205;6138.877222455615;6162.691865406622;6291.874162516816;6300.59046161998;6315.368463153648 10:43:57 INFO - PROCESS | 1971 | |1;ext.html;675.9188897332319;769.011819811542;837.5812093953022;955.8279509688258;966.7873067925054;37644.43533347002;38090.42864357033;39490.80254598723;39585.60414395855;39623;38263.80868095662;38587.42118868212;38597.61402385975;38606;38611.841644750704;7977.20227977202;8093.4334596578265;10934.179936504754;11239.83140252897;11367;9207.585658645377;11016.339019658806;11026.393548354838;11122;11127.276727012719;11168.944155279212;11179.608713695023;11187.160962927772;11196.608118715847;11202.943985280062;9122;11057.944710276477;11088.722781930435;11096.778064438748;11115.666530004113;9018.729438116867;10872.61945831892;10949;10949.905009499065;10952.780944381147;33530.83234583836;33748.32503349944;33819;33856;33909.6609033909;3760.492333534965;3764.7552909060823;3773.2264885698364;3815.9809200954096;3825.158465137665;24270.757292427028;24599.754002459926;25809.74190258111;26896.731032689815;26958.191254262405;2965.8072225305286;3052.709992550704;3070.570120183176;3071.43178511976;3083.9845800771077;373629;374081;375102.24897750944;375835;377359;35285.11787205289;44598.77700611476;44665.55334446614;44853.327200092026;44869.878253043935;37341.81329093392;46219.76890115528;46702.532974670496;46716.532834671896;46759.06481870344;13594;13952.720945581032;13978.790318145237;13996.790048149285;14037.806786423269;11946.522139114339;12083.395830208454;12202.511899523919;12204.084693647834;12220.572279970158;10367.429791361397;11536.884631153582;12077.516899324104;12118.454669539891;12169.269843809223;5474.698891561003;5611.494965453047;5731.455511726379;5740.827775166752;5749.3963133871175;1137.8691450483134;1149.9827502587468;1152.2395219155317;1156.4044517073714;1212.4725744301081;26776.464470710478;26839.329016774347;27367.45265094687;27490.587641185397;27536.724632753816;974.4202199691173;1121.1333130337896;1246.1712960880914;1310.2138716770053;1311.658968668134;12250.326232057323;13490.52783152585;13510.86489135096;13621.523246686516;13770.96717746173;416980;419897;419929;420052;420830;55089.44910550924;55312.17031744527;55324.17013744797;55355.16967245495;55379.44620553743;1258.1256027061288;1433.720424517209;1465.8241011078526;1482.2218335374014;1489.8733607643471;26377.868110659325;27117.322066948484;28277.57583636288;28960.40464869021;29044;46534.53465465302;46716.299255510516;46851.06297874091;46899;46954.76522617365;492332;494944;495504;496549;497174;29990.55014174833;30769.230769230948;32787.83606081954;32863;32951.8466853659;39292.51673473959;39864.40203396893;40125.996850078984;40336.79831600823;40503;27424.17727468179;28203.576946345413;28208.29479263035;28872.422551549273;29366.38484883352;26427.867860660575;27913.860430697718;28175.295617609725;28460.715392845806;28717.856410717814;32162.19594510156;32265.838670806497;32413.837930810194;32677.34645307033;33257.66742332643;27834.721652784025;28218.71781282243;28670.713292866803;29234.707652924048;29302.26744331409;22497.5500489986;23265.651015235122;23499.41251468727;23664.645030324904;23781.167659131846 10:43:57 INFO - PROCESS | 1971 | |2;jquery.html;351.73971261266655;394.73355485047585;414.22953306849274;424.66027178257394;456.34742318484575;20313.289034883783;21471.892640536815;21507.56984860302;21642;21887;19697.818130912154;21452.57094858102;22262.88868555659;22487.887560562216;23006.769932300715;6629.303923088079;6858.485613578977;6885.69014394351;6949.992251123584;6959.582425054487;5109.054824857395;6089.512838972893;6731.629760363178;6837.418819400332;6907.620080895549;6532.379423954717;6728.899066514006;6880.4495640348905;6892.172939247297;6924.446044316468;6633.701483433233;6662.600243985328;6676.26561078281;6708.7651932182625;6723.630200338955;6195.876082478325;6777.559458635173;6818.352256535622;6820.488463365267;6855.931440685579;26673;26800.463990720076;26847;26931.461370772475;27296.317592060157;3360.327934413127;3398.269372084989;3411.249525104473;3480.2343484433386;3576.445650924085;16189.838101619069;16282.43011494592;16310.918445407815;16423.753643695236;16497.010179389275;2021.1107112870436;2082.7188329575465;2103.4741314671332;2113.19698514565;2153.9784602153936;110450;114288;128461.35769321094;130534;136185;21649.67525487119;21733.782662173493;22132.225372111898;22774.886125569265;22848.74331911761;34099.82950085284;34499.65500344965;35047.64952350495;35067.82466087653;35217.47173792395;10827.350358978487;10912.017918387224;10942.945285273523;11053.778924421627;11187.328760274248;8914.465132092095;9027.36808423404;9073.77315567116;9273.860892086625;9276.211522020703;8305.875411868827;10060.74848128788;10348.379097254188;10458.26792124544;10463.476826158661;3835.827387767557;4194.30793919006;4456.532064133284;4478.39541661878;4616.238320677118;1015.2081376526321;1056.4400867540216;1056.747753911617;1106.4246591772394;1137.5449820071872;22680.319590412313;22878;22931.770682293296;22966.311010669706;23040;1138.7267055906689;1150.7871043856912;1161.3148242536943;1180.8937195652431;1182.3319824299244;11641.47613357384;11754.88245117538;11881.287122772661;12009.399530023464;12043.494563179602;716269;737527;755367;757968;775318;122890;123193;127363;129958.35020824835;131809.34095329655;1267.4233223883111;1269.7270086931353;1314.4216544720293;1319.2678063674753;1320.683036071336;19144.138513767193;19557.60884782325;19581.608367832847;19689.507762305482;19819.60360792805;43186;56791.432085680266;56800.715996419756;58466.123008155766;58553.414465854796;63019;74094;77971.6101419512;78934;87095.1290487087;10878.075363593867;11611.303321800719;11681.415929203677;11731.941340293244;11738.295702257892;12713.8092928608;12743.681407964876;13059.542915997894;13104.606861794162;13288.734225315246;11028.439400970921;11386.146039047271;11431.828522572334;11550.653480395602;11622.418879055844;9419.952900235456;10235.641752538626;10631.574737010742;10665.520051597541;10695.86082783462;11543.249688770538;11695.181337306307;11709.238899471507;11843.28940263587;11892.464839082435;11577.305361678325;11591;11718.824217636913;11924.880751192377;11944.343061131722;9706.223502119954;9770.902290977283;10019.54912028975;10048.89951100509;10212.387256764321 10:43:57 INFO - PROCESS | 1971 | |3;mootools.html;1495.7681559358296;1585.8017747781528;1587.3015873015877;1643.7780899578554;1658.9004659720417;9097.909020909789;9832.950835245832;10486.318389304699;10861.67414977551;11563.826542601857;10818.350898946068;11223.943880280587;11417.372044537547;11421.428928553581;11451.370174640413;2404.4589967257402;2468.0868078810777;2472.431340791619;2473.2951108933958;2478.1821998740375;2424.745401732819;2455.2143314139485;2460.2988148377717;2464.4824586836785;2469.8271121021535;2420.9636855447184;2439.609662454008;2440.0849681369486;2448.3879030242438;2454.2759885833634;2404.1945948107405;2412.0834083048417;2414.6619473273754;2418.4800267942387;2423.854568725882;2362.161432691404;2377.9048838046456;2379.4646204604;2381.7022872140983;2387.8447900886385;11416.42917854104;11421.200515963887;11470.598529051527;11533.308001519852;11556.248843825124;5805.593608447411;5962.821115366546;5974;5977.820665380045;5984.251968503937;2932.765378769693;3076.415481058592;3470.357983772985;3626.746127771057;3701.6668499835146;1163.03468121459;1171.9862319094007;1188.9464974076188;1240.2558464921065;1241.7640648276888;15515.612109697235;15520.689586208326;15598.9220053899;15618.609534761608;15623.687526249525;4683.929741053887;4931.742405686527;4962.875928101754;5030.3963524377305;5072.315237442972;13421.865781342256;13426.731465370833;13453.394597243148;13471.057026008086;13498.122622029538;6021.578489505692;6093.664848433379;6103.298120716086;6104.145419641254;6113.938860611426;4352.325389564622;5373.946260537423;5375.838724838261;5385.246065550826;5424.105022671294;4104.581673306773;4173.582641735802;4237.724547904377;4268.59448352406;4281.2293787118;1155.5493357590547;1158.1487606609135;1159.675290918544;1173.0556901694047;1173.5892437646755;877.8332116897769;908.6955869783565;957.4877440569262;976.0625159059261;997.5311603546321;6613.933860661428;6622.9006564901565;6743.258241593421;6940.514164008572;7037.507374483839;999.5901680311038;1021.7241344836852;1033.503918119307;1074.1782536359692;1075.3601607043797;3586.1214002569536;3622.710183185339;3785.886423407302;4447.132809102259;4551.044280701024;11266.493007814668;12757.660445653073;12964.611061668165;14008.229547374789;14060;12721.618351449471;13077.95376369888;13908.304584770722;14018.579442616534;14043.876489880786;10139.797204055878;10444.895551044392;10467.476626168662;10514.474276286155;10527.842082368923;5673.716314184357;5946;6354.792589407998;6661.666916654245;6785.558938668971;12206.291119243135;12391.690207744878;12788.552400665933;12978.740425191632;13038.282894440898;9966.950165249127;12990.870091298966;13072.673183170496;13274;13295.468181272641;9488.288378371788;9489.051094890456;9567.521623918916;9588.808223835345;9652.131308182297;10483.108935740242;10516.68449946503;10570.154387649123;10587.788244235226;10623.787524249317;6690.565113267622;9079.68221112258;9106.27149827999;9311.161995420445;9341.719748407559;7561.7731468056045;8786.297096232414;8929.553522323988;8969.686060987835;9038.909610903807;7343.1555371133;7886.72396466121;8090.676372945251;8127.1466496017065;8205.95897020511;5879.588428809942;5932.93207222704;5979.790707325223;6018.3680713524445;6040.637561746309;5967.731452084579;6102.084687296941;6248.156498872594;6275;6306.073838824894 10:43:57 INFO - PROCESS | 1971 | |4;prototype.html;376.5801131738113;382.92532956073575;389.11088163546293;394.11717752234983;397.7017237072195;4423.77881105945;4897.779599918003;5361.900810333882;5377.381601115868;5395.271638328824;4633.374494443249;4950.653454258203;5310.415854256029;5352.411234764182;5353.5984801139875;4189;4348.630181492819;4367.694261401703;4443.4445694288215;4670.416197975253;3915.4729655434403;4346.630536404409;4576;4664.941500894227;4739.85780426586;3987.8006099695044;4405.229084910144;4502.347159661847;4609.608183304423;4674.415698037746;4005.8998525036964;4263.744175349489;4461;4615.246296314801;4750.263709125091;3864.478295430109;4030.891504836164;4391.429114215131;4398.34024896265;4407.537208593084;4738.905221895543;5115.695497648076;5215.496561650391;5288.391834938992;5293.988284451883;4542.909141817178;4854.737768180298;5208.322918020633;5381.085215513333;5462.180672899097;5400.756965936543;5456.963176996398;5492.725363731837;5582.888342233173;5861.6189947653265;4296.57034296571;4986.95273992462;4999;5052.317937078521;5055;4924.359833221694;5158.92261616076;5608.635438696471;5884.117382392633;6036;4778.474367819537;4791.209450440639;4801.855944321676;4807.663463557587;4923.975380123076;4578.633709303248;4767.427908650915;4884.145274577006;4921.901561968741;5006.224035274538;4559.452865656076;4828.1031381171015;4954.885150841032;5184.755658641899;5232.1628539433505;4969.652124351261;5516.7517461714315;5649.830505084854;5735.812856614694;5739.942600573941;1534.9309281082378;1700.6173610937687;1779.973300400495;1797.6764182447023;1817.4002579148848;1354.9160671462791;1398.5105213175307;1418.7020725647524;1426.522115091456;1460.5253292679922;4569.702969306984;5092.185250359925;5122.871928201825;5131.358580177478;5192.584593232532;4930.580900623416;5001.299298378907;5104.69371837691;5294.444083371268;5798.565107616945;4798.688085274446;4810.546621223131;5049.394072711299;5054.974725126424;5144.922826157611;2776.5141100307364;2899.9840166226886;3044.5737596736476;3068.5243787213017;3078.322768990863;5059.256150448641;5376.650517716336;5707.204708868556;5769.711514424346;6180.8763824723255;5179.922301165486;5584.274044374205;5760.36635970043;6037.698115094316;6064.727087281083;6048.879022419527;6164.938350616437;6166.81499555014;6173.438023220403;6210.354256122041;5465.344158700981;5508.14623733322;5671.546276297969;5943.524518038633;5991.88016239664;5222.738863056908;5674.992254105166;5838.474537291664;5896.262967129109;5918.9704051481185;5363.383210930637;5770.682612456355;6158.938410615837;6210.043836191585;6215.488249139222;3920.4511368409017;4493.595576398009;4646.3727396801;4658.787076138807;4770.546798054179;4991.10515900784;5492.673773306159;5755.963926493104;5775.718591156554;6147.938520614736;5181.5595674368105;5250.871062721533;5544.168374743821;5651.987089166573;5857.26784151981;4983.177775666974;5271.523973287485;5293.391260005149;5302.8713059295205;5744.712764361681;4879.975205206804;5234.502722241533;5630.408807075198;5635.887282254414;5734.799282025109;5133.511281728257;5278.129108697022;5286.841394758163;5346.251524786455;5924.9703751480965;5198.3105490716425;5585.497305242548;5609.719514024201;5642.322921249476;5647.830565083054 10:43:57 INFO - PROCESS | 1971 | |5;yui.html;732.2421293960748;752.9736459223928;779.5907148746911;821.7575815134535;848.1306660672809;12957.352132393378;13742.862571374308;15991.76012359814;16618.50144495664;16902.661946761058;15109.84890151098;15447.922760386182;15497.922510387432;15593.84406155941;15597.688046239069;950.6387572722373;973.4937832327221;978.6330126202674;978.7161723100293;982.3958265666635;765.3571000359697;964.4840010594334;971.8930917599093;974.1378879691489;980.5587485631459;1015.6445244164557;1016.5425558498669;1017.760826205841;1018.9796204075914;1020.9081182693556;995.8058178655165;1005.4972513743129;1007.1036777268237;1011.0041609015115;1011.281989787252;744.7058411927259;751.0874287740421;752.6236881559221;753.6307209467321;753.6608526163249;13867.514636987657;16275.267612957445;16405.91797041007;17161.65676686472;17392.826071739248;14566.981971458554;14958.775618365733;15077.623059423602;15097.773533397009;15426.074435533905;11253.268537545033;12027.458764355624;12060.577879774253;12568.30874301922;12869.935650321688;4572.679912406133;4605.930911036337;4821.662483626147;4855.5387238212315;4862.07620552094;113216;114131;114591;116615.4169229157;120071.1989320161;10055.195584353234;11216.775664486664;11467.942660286812;11784.705382365339;11870.109741769402;24070;24440.633390499155;24639.87680061624;24681.259562213163;25008.374790630016;7743.303102720783;7957.3634109271115;8117.715879944174;8205.384596155312;8220.671173153127;4248.936265956013;4297.39836422901;4338.717983331074;4414.249577571821;4455.732656040583;3611.8916432507067;3706.6664000240107;3706.814659267026;3708.35103856824;3718.9814050930113;647.1328419917334;650.7527139686889;654.3456543456543;656.4387448731266;673.5487223560128;571.6198727846019;674.7132468700783;692.6155983429145;698.1587187439123;713.1121753416926;10089.596416143419;10430.895691043144;10520.26358154937;10641.787164256672;10647.361158330525;674.942629876466;689.3588962265042;712.3410844968383;726.418864908082;741.7922981565063;4460.50934397216;4828.396450443694;5048.29323894655;5085.313482679865;5090.363704536933;85219;101027;105808;109436.45281773541;112231.43884280526;73071.17322066991;74533.627331863;80229.79655305059;83467.16532834715;91903;65469.672651636436;72973.27026729664;74260.25739742641;75621.62189188908;75666;8424.4945303282;10888.67333979982;11068.612598559012;11143.387113708824;11164.553417863195;46884.29673554831;49365.76585585389;49558;50736.74631626818;50857.98284034224;60253.69873150606;61806.38193618006;63497.36502634914;63563;64309.35690643221;10657.946710266398;12459.501619935463;12671.113022088366;12722.300273485047;12986.285754283605;9784.951075244579;12128.393580321124;14293;14641.48754793577;14798.926005369904;14029.368678409292;14047.367868446148;14101.788473172706;14170.787438188641;14543;13608.319584020957;13934.442622294995;13936.442542298195;14587.635309117357;14738.26308684583;13286.402111905181;13436.798448023483;13844.584662460142;14052.78920816209;14110.647233819236;9249.398789078688;9488.762780930532;9866.950665246628;10060.396376217157;10155.543000564845;10970.286931349438;11521.2104297903;11529.942350288195;11821.704457388634;11909.285442873455 10:43:57 INFO - PROCESS | 1971 | __end_tp_report 10:43:57 INFO - PROCESS | 1971 | __start_cc_report 10:43:57 INFO - PROCESS | 1971 | _x_x_mozilla_cycle_collect,375 10:43:57 INFO - PROCESS | 1971 | __end_cc_report 10:43:57 INFO - PROCESS | 1971 | __startTimestamp1495647837972__endTimestamp 10:43:57 INFO - PROCESS | 1971 | 10:43:57 INFO - PROCESS | 1971 | ------- Summary: start ------- 10:43:57 INFO - PROCESS | 1971 | Number of tests: 6 10:43:57 INFO - PROCESS | 1971 | 10:43:57 INFO - PROCESS | 1971 | [#0] dojo.html Cycles:165 Average:5979.68 Median:6249.38 stddev:685.52 (11.0%) stddev-sans-first:660.13 10:43:57 INFO - PROCESS | 1971 | Values: 3529.9 3533.6 3551.2 3562.9 3568.0 6066.8 6088.1 6110.8 6134.6 6172.4 4733.7 6082.8 6106.6 6174.4 6189.2 5480.4 5499.2 5542.2 5568.1 5607.4 4871.9 5590.2 5615.4 5625.7 5627.0 4555.2 5378.9 5404.9 5541.9 5581.7 5489.5 5503.5 5510.2 5515.2 5549.4 5475.1 5525.3 5542.3 5559.1 5573.4 6197.5 6210.8 6211.5 6224.0 6229.3 5104.2 6300.4 6317.0 6470.1 6486.4 6358.2 6449.0 6454.5 6491.0 6495.2 6229.8 6274.7 6279.0 6293.4 6312.6 6690.2 6734.3 6735.9 6748.8 6749.7 6271.7 6273.7 6283.1 6334.5 6336.2 6524.2 6535.5 6539.7 6551.0 6553.2 6304.5 6441.4 6442.0 6454.4 6456.1 5100.7 6249.4 6286.2 6309.7 6435.8 4841.5 4879.9 4931.2 4986.1 5071.7 4481.7 4487.0 4498.8 4513.7 4513.7 6042.4 6095.8 6116.8 6119.9 6134.3 5405.8 6238.2 6314.5 6412.4 6478.5 6083.9 6119.6 6126.8 6127.3 6151.8 5685.4 5691.9 5704.3 5706.6 5709.8 6328.7 6387.9 6701.8 6706.5 6715.9 6364.4 6388.6 6438.8 6504.3 6505.4 6648.4 6713.7 6717.1 6723.5 6737.8 6261.4 6451.9 6455.0 6457.9 6470.4 6233.6 6278.9 6281.4 6319.0 6325.9 5937.7 6301.7 6304.4 6306.5 6316.2 6227.1 6264.6 6302.4 6324.7 6332.7 6104.1 6115.1 6269.7 6283.3 6303.5 6350.0 6355.4 6382.5 6387.7 6409.9 6138.9 6162.7 6291.9 6300.6 6315.4 10:43:57 INFO - PROCESS | 1971 | 10:43:57 INFO - PROCESS | 1971 | [#1] ext.html Cycles:180 Average:55716.20 Median:16521.63 stddev:115031.94 (696.3%) stddev-sans-first:115280.41 10:43:57 INFO - PROCESS | 1971 | Values: 675.9 769.0 837.6 955.8 966.8 37644.4 38090.4 39490.8 39585.6 39623.0 38263.8 38587.4 38597.6 38606.0 38611.8 7977.2 8093.4 10934.2 11239.8 11367.0 9207.6 11016.3 11026.4 11122.0 11127.3 11168.9 11179.6 11187.2 11196.6 11202.9 9122.0 11057.9 11088.7 11096.8 11115.7 9018.7 10872.6 10949.0 10949.9 10952.8 33530.8 33748.3 33819.0 33856.0 33909.7 3760.5 3764.8 3773.2 3816.0 3825.2 24270.8 24599.8 25809.7 26896.7 26958.2 2965.8 3052.7 3070.6 3071.4 3084.0 373629.0 374081.0 375102.2 375835.0 377359.0 35285.1 44598.8 44665.6 44853.3 44869.9 37341.8 46219.8 46702.5 46716.5 46759.1 13594.0 13952.7 13978.8 13996.8 14037.8 11946.5 12083.4 12202.5 12204.1 12220.6 10367.4 11536.9 12077.5 12118.5 12169.3 5474.7 5611.5 5731.5 5740.8 5749.4 1137.9 1150.0 1152.2 1156.4 1212.5 26776.5 26839.3 27367.5 27490.6 27536.7 974.4 1121.1 1246.2 1310.2 1311.7 12250.3 13490.5 13510.9 13621.5 13771.0 416980.0 419897.0 419929.0 420052.0 420830.0 55089.4 55312.2 55324.2 55355.2 55379.4 1258.1 1433.7 1465.8 1482.2 1489.9 26377.9 27117.3 28277.6 28960.4 29044.0 46534.5 46716.3 46851.1 46899.0 46954.8 492332.0 494944.0 495504.0 496549.0 497174.0 29990.6 30769.2 32787.8 32863.0 32951.8 39292.5 39864.4 40126.0 40336.8 40503.0 27424.2 28203.6 28208.3 28872.4 29366.4 26427.9 27913.9 28175.3 28460.7 28717.9 32162.2 32265.8 32413.8 32677.3 33257.7 27834.7 28218.7 28670.7 29234.7 29302.3 22497.6 23265.7 23499.4 23664.6 23781.2 10:43:57 INFO - PROCESS | 1971 | 10:43:57 INFO - PROCESS | 1971 | [#2] jquery.html Cycles:180 Average:41252.78 Median:11897.99 stddev:123503.01 (1038.0%) stddev-sans-first:123811.28 10:43:57 INFO - PROCESS | 1971 | Values: 351.7 394.7 414.2 424.7 456.3 20313.3 21471.9 21507.6 21642.0 21887.0 19697.8 21452.6 22262.9 22487.9 23006.8 6629.3 6858.5 6885.7 6950.0 6959.6 5109.1 6089.5 6731.6 6837.4 6907.6 6532.4 6728.9 6880.4 6892.2 6924.4 6633.7 6662.6 6676.3 6708.8 6723.6 6195.9 6777.6 6818.4 6820.5 6855.9 26673.0 26800.5 26847.0 26931.5 27296.3 3360.3 3398.3 3411.2 3480.2 3576.4 16189.8 16282.4 16310.9 16423.8 16497.0 2021.1 2082.7 2103.5 2113.2 2154.0 110450.0 114288.0 128461.4 130534.0 136185.0 21649.7 21733.8 22132.2 22774.9 22848.7 34099.8 34499.7 35047.6 35067.8 35217.5 10827.4 10912.0 10942.9 11053.8 11187.3 8914.5 9027.4 9073.8 9273.9 9276.2 8305.9 10060.7 10348.4 10458.3 10463.5 3835.8 4194.3 4456.5 4478.4 4616.2 1015.2 1056.4 1056.7 1106.4 1137.5 22680.3 22878.0 22931.8 22966.3 23040.0 1138.7 1150.8 1161.3 1180.9 1182.3 11641.5 11754.9 11881.3 12009.4 12043.5 716269.0 737527.0 755367.0 757968.0 775318.0 122890.0 123193.0 127363.0 129958.4 131809.3 1267.4 1269.7 1314.4 1319.3 1320.7 19144.1 19557.6 19581.6 19689.5 19819.6 43186.0 56791.4 56800.7 58466.1 58553.4 63019.0 74094.0 77971.6 78934.0 87095.1 10878.1 11611.3 11681.4 11731.9 11738.3 12713.8 12743.7 13059.5 13104.6 13288.7 11028.4 11386.1 11431.8 11550.7 11622.4 9420.0 10235.6 10631.6 10665.5 10695.9 11543.2 11695.2 11709.2 11843.3 11892.5 11577.3 11591.0 11718.8 11924.9 11944.3 9706.2 9770.9 10019.5 10048.9 10212.4 10:43:57 INFO - PROCESS | 1971 | 10:43:57 INFO - PROCESS | 1971 | [#3] mootools.html Cycles:180 Average:6854.84 Median:2466.28 stddev:4308.91 (174.7%) stddev-sans-first:4302.18 10:43:57 INFO - PROCESS | 1971 | Values: 1495.8 1585.8 1587.3 1643.8 1658.9 9097.9 9833.0 10486.3 10861.7 11563.8 10818.4 11223.9 11417.4 11421.4 11451.4 2404.5 2468.1 2472.4 2473.3 2478.2 2424.7 2455.2 2460.3 2464.5 2469.8 2421.0 2439.6 2440.1 2448.4 2454.3 2404.2 2412.1 2414.7 2418.5 2423.9 2362.2 2377.9 2379.5 2381.7 2387.8 11416.4 11421.2 11470.6 11533.3 11556.2 5805.6 5962.8 5974.0 5977.8 5984.3 2932.8 3076.4 3470.4 3626.7 3701.7 1163.0 1172.0 1188.9 1240.3 1241.8 15515.6 15520.7 15598.9 15618.6 15623.7 4683.9 4931.7 4962.9 5030.4 5072.3 13421.9 13426.7 13453.4 13471.1 13498.1 6021.6 6093.7 6103.3 6104.1 6113.9 4352.3 5373.9 5375.8 5385.2 5424.1 4104.6 4173.6 4237.7 4268.6 4281.2 1155.5 1158.1 1159.7 1173.1 1173.6 877.8 908.7 957.5 976.1 997.5 6613.9 6622.9 6743.3 6940.5 7037.5 999.6 1021.7 1033.5 1074.2 1075.4 3586.1 3622.7 3785.9 4447.1 4551.0 11266.5 12757.7 12964.6 14008.2 14060.0 12721.6 13078.0 13908.3 14018.6 14043.9 10139.8 10444.9 10467.5 10514.5 10527.8 5673.7 5946.0 6354.8 6661.7 6785.6 12206.3 12391.7 12788.6 12978.7 13038.3 9967.0 12990.9 13072.7 13274.0 13295.5 9488.3 9489.1 9567.5 9588.8 9652.1 10483.1 10516.7 10570.2 10587.8 10623.8 6690.6 9079.7 9106.3 9311.2 9341.7 7561.8 8786.3 8929.6 8969.7 9038.9 7343.2 7886.7 8090.7 8127.1 8206.0 5879.6 5932.9 5979.8 6018.4 6040.6 5967.7 6102.1 6248.2 6275.0 6306.1 10:43:57 INFO - PROCESS | 1971 | 10:43:57 INFO - PROCESS | 1971 | [#4] prototype.html Cycles:180 Average:4786.96 Median:5119.28 stddev:1277.74 (25.0%) stddev-sans-first:1237.70 10:43:57 INFO - PROCESS | 1971 | Values: 376.6 382.9 389.1 394.1 397.7 4423.8 4897.8 5361.9 5377.4 5395.3 4633.4 4950.7 5310.4 5352.4 5353.6 4189.0 4348.6 4367.7 4443.4 4670.4 3915.5 4346.6 4576.0 4664.9 4739.9 3987.8 4405.2 4502.3 4609.6 4674.4 4005.9 4263.7 4461.0 4615.2 4750.3 3864.5 4030.9 4391.4 4398.3 4407.5 4738.9 5115.7 5215.5 5288.4 5294.0 4542.9 4854.7 5208.3 5381.1 5462.2 5400.8 5457.0 5492.7 5582.9 5861.6 4296.6 4987.0 4999.0 5052.3 5055.0 4924.4 5158.9 5608.6 5884.1 6036.0 4778.5 4791.2 4801.9 4807.7 4924.0 4578.6 4767.4 4884.1 4921.9 5006.2 4559.5 4828.1 4954.9 5184.8 5232.2 4969.7 5516.8 5649.8 5735.8 5739.9 1534.9 1700.6 1780.0 1797.7 1817.4 1354.9 1398.5 1418.7 1426.5 1460.5 4569.7 5092.2 5122.9 5131.4 5192.6 4930.6 5001.3 5104.7 5294.4 5798.6 4798.7 4810.5 5049.4 5055.0 5144.9 2776.5 2900.0 3044.6 3068.5 3078.3 5059.3 5376.7 5707.2 5769.7 6180.9 5179.9 5584.3 5760.4 6037.7 6064.7 6048.9 6164.9 6166.8 6173.4 6210.4 5465.3 5508.1 5671.5 5943.5 5991.9 5222.7 5675.0 5838.5 5896.3 5919.0 5363.4 5770.7 6158.9 6210.0 6215.5 3920.5 4493.6 4646.4 4658.8 4770.5 4991.1 5492.7 5756.0 5775.7 6147.9 5181.6 5250.9 5544.2 5652.0 5857.3 4983.2 5271.5 5293.4 5302.9 5744.7 4880.0 5234.5 5630.4 5635.9 5734.8 5133.5 5278.1 5286.8 5346.3 5925.0 5198.3 5585.5 5609.7 5642.3 5647.8 10:43:57 INFO - PROCESS | 1971 | 10:43:57 INFO - PROCESS | 1971 | [#5] yui.html Cycles:180 Average:20474.65 Median:24660.57 stddev:29218.11 (118.5%) stddev-sans-first:29262.47 10:43:57 INFO - PROCESS | 1971 | Values: 732.2 753.0 779.6 821.8 848.1 12957.4 13742.9 15991.8 16618.5 16902.7 15109.8 15447.9 15497.9 15593.8 15597.7 950.6 973.5 978.6 978.7 982.4 765.4 964.5 971.9 974.1 980.6 1015.6 1016.5 1017.8 1019.0 1020.9 995.8 1005.5 1007.1 1011.0 1011.3 744.7 751.1 752.6 753.6 753.7 13867.5 16275.3 16405.9 17161.7 17392.8 14567.0 14958.8 15077.6 15097.8 15426.1 11253.3 12027.5 12060.6 12568.3 12869.9 4572.7 4605.9 4821.7 4855.5 4862.1 113216.0 114131.0 114591.0 116615.4 120071.2 10055.2 11216.8 11467.9 11784.7 11870.1 24070.0 24440.6 24639.9 24681.3 25008.4 7743.3 7957.4 8117.7 8205.4 8220.7 4248.9 4297.4 4338.7 4414.2 4455.7 3611.9 3706.7 3706.8 3708.4 3719.0 647.1 650.8 654.3 656.4 673.5 571.6 674.7 692.6 698.2 713.1 10089.6 10430.9 10520.3 10641.8 10647.4 674.9 689.4 712.3 726.4 741.8 4460.5 4828.4 5048.3 5085.3 5090.4 85219.0 101027.0 105808.0 109436.5 112231.4 73071.2 74533.6 80229.8 83467.2 91903.0 65469.7 72973.3 74260.3 75621.6 75666.0 8424.5 10888.7 11068.6 11143.4 11164.6 46884.3 49365.8 49558.0 50736.7 50858.0 60253.7 61806.4 63497.4 63563.0 64309.4 10657.9 12459.5 12671.1 12722.3 12986.3 9785.0 12128.4 14293.0 14641.5 14798.9 14029.4 14047.4 14101.8 14170.8 14543.0 13608.3 13934.4 13936.4 14587.6 14738.3 13286.4 13436.8 13844.6 14052.8 14110.6 9249.4 9488.8 9867.0 10060.4 10155.5 10970.3 11521.2 11529.9 11821.7 11909.3 10:43:57 INFO - PROCESS | 1971 | -------- Summary: end -------- 10:43:57 INFO - PROCESS | 1971 | 10:43:58 INFO - TEST-INFO | 1971: exit 0 10:43:58 INFO - TEST-OK | dromaeo_css | took 1116557ms 10:43:58 INFO - TEST-START | kraken 10:43:58 INFO - operating with platform_type : mac_ 10:43:58 INFO - Initialising browser for kraken test... 10:43:58 INFO - TEST-INFO | started process 1978 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/tmpfCm2en/profile http://localhost:49361/getInfo.html) 10:44:01 INFO - PROCESS | 1978 | 2017-05-24 10:44:01.164 plugin-container[1980:13934] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d47, name = 'com.apple.tsm.portname' 10:44:01 INFO - PROCESS | 1978 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:44:01 INFO - PROCESS | 1978 | 2017-05-24 10:44:01.165 plugin-container[1980:13934] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870f, name = 'com.apple.CFPasteboardClient' 10:44:01 INFO - PROCESS | 1978 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:44:01 INFO - PROCESS | 1978 | 2017-05-24 10:44:01.165 plugin-container[1980:13934] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:44:01 INFO - PROCESS | 1978 | __metrics Screen width/height:1600/1200 10:44:01 INFO - PROCESS | 1978 | colorDepth:24 10:44:01 INFO - PROCESS | 1978 | Browser inner width/height: 1024/689 10:44:01 INFO - PROCESS | 1978 | __metrics 10:44:01 ERROR - PROCESS | 1978 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 10:44:01 INFO - TEST-INFO | 1978: exit 0 10:44:01 INFO - Browser initialized. 10:44:01 INFO - Running cycle 1/1 for kraken test... 10:44:01 INFO - TEST-INFO | started process 1981 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/T/tmpfCm2en/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:44:04 INFO - PROCESS | 1981 | 2017-05-24 10:44:04.394 plugin-container[1982:14062] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 10:44:04 INFO - PROCESS | 1981 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:44:04 INFO - PROCESS | 1981 | 2017-05-24 10:44:04.395 plugin-container[1982:14062] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4507, name = 'com.apple.CFPasteboardClient' 10:44:04 INFO - PROCESS | 1981 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:44:04 INFO - PROCESS | 1981 | 2017-05-24 10:44:04.395 plugin-container[1982:14062] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:44:14 INFO - PROCESS | 1981 | RSS: Main: 202969088 10:44:14 INFO - PROCESS | 1981 | 10:44:16 INFO - PROCESS | 1981 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49361/tests/kraken/driver.html?testName=audio-beat-detection) 10:44:17 INFO - PROCESS | 1981 | RSS: Main: 216231936 10:44:17 INFO - PROCESS | 1981 | 10:44:20 INFO - PROCESS | 1981 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49361/tests/kraken/driver.html?testName=audio-dft) 10:44:21 INFO - PROCESS | 1981 | RSS: Main: 224272384 10:44:21 INFO - PROCESS | 1981 | 10:44:24 INFO - PROCESS | 1981 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49361/tests/kraken/driver.html?testName=audio-fft) 10:44:25 INFO - PROCESS | 1981 | RSS: Main: 227926016 10:44:25 INFO - PROCESS | 1981 | 10:44:27 INFO - PROCESS | 1981 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49361/tests/kraken/driver.html?testName=audio-oscillator) 10:44:27 INFO - PROCESS | 1981 | RSS: Main: 229470208 10:44:27 INFO - PROCESS | 1981 | 10:44:29 INFO - PROCESS | 1981 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49361/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:44:30 INFO - PROCESS | 1981 | RSS: Main: 229457920 10:44:30 INFO - PROCESS | 1981 | 10:44:33 INFO - PROCESS | 1981 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49361/tests/kraken/driver.html?testName=imaging-darkroom) 10:44:33 INFO - PROCESS | 1981 | RSS: Main: 233500672 10:44:33 INFO - PROCESS | 1981 | 10:44:37 INFO - PROCESS | 1981 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49361/tests/kraken/driver.html?testName=imaging-desaturate) 10:44:37 INFO - PROCESS | 1981 | RSS: Main: 233103360 10:44:37 INFO - PROCESS | 1981 | 10:44:40 INFO - PROCESS | 1981 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49361/tests/kraken/driver.html?testName=json-parse-financial) 10:44:41 INFO - PROCESS | 1981 | RSS: Main: 233316352 10:44:41 INFO - PROCESS | 1981 | 10:44:43 INFO - PROCESS | 1981 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49361/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:44:43 INFO - PROCESS | 1981 | RSS: Main: 233005056 10:44:43 INFO - PROCESS | 1981 | 10:44:45 INFO - PROCESS | 1981 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:44:46 INFO - PROCESS | 1981 | RSS: Main: 233095168 10:44:46 INFO - PROCESS | 1981 | 10:44:48 INFO - PROCESS | 1981 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:44:49 INFO - PROCESS | 1981 | RSS: Main: 233005056 10:44:49 INFO - PROCESS | 1981 | 10:44:51 INFO - PROCESS | 1981 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:44:52 INFO - PROCESS | 1981 | RSS: Main: 233353216 10:44:52 INFO - PROCESS | 1981 | 10:44:55 INFO - PROCESS | 1981 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:44:56 INFO - PROCESS | 1981 | RSS: Main: 241127424 10:44:56 INFO - PROCESS | 1981 | 10:44:57 INFO - PROCESS | 1981 | Cycle 1(1): loaded http://localhost:49361/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49361/tests/kraken/driver.html?testName=ai-astar) 10:44:58 INFO - PROCESS | 1981 | RSS: Main: 242769920 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | __start_tp_report 10:44:58 INFO - PROCESS | 1981 | _x_x_mozilla_page_load 10:44:58 INFO - PROCESS | 1981 | _x_x_mozilla_page_load_details 10:44:58 INFO - PROCESS | 1981 | |i|pagename|runs| 10:44:58 INFO - PROCESS | 1981 | |0;ai-astar;108;119;119;119;119;118;119;118;120;118 10:44:58 INFO - PROCESS | 1981 | |1;audio-beat-detection;137;111;111;148;118;105;141;98;161;143 10:44:58 INFO - PROCESS | 1981 | |2;audio-dft;195;201;198;229;184;179;259;276;241;194 10:44:58 INFO - PROCESS | 1981 | |3;audio-fft;83;63;59;94;65;64;62;64;77;62 10:44:58 INFO - PROCESS | 1981 | |4;audio-oscillator;93;71;87;73;72;71;83;73;58;112 10:44:58 INFO - PROCESS | 1981 | |5;imaging-gaussian-blur;90;85;138;88;90;105;90;90;108;91 10:44:58 INFO - PROCESS | 1981 | |6;imaging-darkroom;156;101;104;157;116;153;136;98;96;97 10:44:58 INFO - PROCESS | 1981 | |7;imaging-desaturate;95;92;96;92;91;88;90;89;91;93 10:44:58 INFO - PROCESS | 1981 | |8;json-parse-financial;66;66;65;66;69;67;68;70;68;68 10:44:58 INFO - PROCESS | 1981 | |9;json-stringify-tinderbox;57;64;62;63;61;56;57;59;60;59 10:44:58 INFO - PROCESS | 1981 | |10;stanford-crypto-aes;83;89;95;100;82;86;84;87;86;91 10:44:58 INFO - PROCESS | 1981 | |11;stanford-crypto-ccm;140;152;145;148;157;147;150;146;191;149 10:44:58 INFO - PROCESS | 1981 | |12;stanford-crypto-pbkdf2;165;169;166;170;168;166;166;167;166;175 10:44:58 INFO - PROCESS | 1981 | |13;stanford-crypto-sha256-iterative;66;67;67;68;67;73;90;69;65;67 10:44:58 INFO - PROCESS | 1981 | __end_tp_report 10:44:58 INFO - PROCESS | 1981 | __start_cc_report 10:44:58 INFO - PROCESS | 1981 | _x_x_mozilla_cycle_collect,1006 10:44:58 INFO - PROCESS | 1981 | __end_cc_report 10:44:58 INFO - PROCESS | 1981 | __startTimestamp1495647898245__endTimestamp 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | ------- Summary: start ------- 10:44:58 INFO - PROCESS | 1981 | Number of tests: 14 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | [#0] ai-astar Cycles:10 Average:117.70 Median:119.00 stddev:3.47 (2.9%) stddev-sans-first:0.67 10:44:58 INFO - PROCESS | 1981 | Values: 108.0 119.0 119.0 119.0 119.0 118.0 119.0 118.0 120.0 118.0 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | [#1] audio-beat-detection Cycles:10 Average:127.30 Median:142.00 stddev:21.26 (15.0%) stddev-sans-first:22.25 10:44:58 INFO - PROCESS | 1981 | Values: 137.0 111.0 111.0 148.0 118.0 105.0 141.0 98.0 161.0 143.0 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | [#2] audio-dft Cycles:10 Average:215.60 Median:215.00 stddev:33.51 (15.6%) stddev-sans-first:34.71 10:44:58 INFO - PROCESS | 1981 | Values: 195.0 201.0 198.0 229.0 184.0 179.0 259.0 276.0 241.0 194.0 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | [#3] audio-fft Cycles:10 Average:69.30 Median:64.50 stddev:11.47 (17.8%) stddev-sans-first:11.04 10:44:58 INFO - PROCESS | 1981 | Values: 83.0 63.0 59.0 94.0 65.0 64.0 62.0 64.0 77.0 62.0 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | [#4] audio-oscillator Cycles:10 Average:79.30 Median:73.00 stddev:15.11 (20.7%) stddev-sans-first:15.19 10:44:58 INFO - PROCESS | 1981 | Values: 93.0 71.0 87.0 73.0 72.0 71.0 83.0 73.0 58.0 112.0 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | [#5] imaging-gaussian-blur Cycles:10 Average:97.50 Median:90.00 stddev:16.06 (17.8%) stddev-sans-first:16.80 10:44:58 INFO - PROCESS | 1981 | Values: 90.0 85.0 138.0 88.0 90.0 105.0 90.0 90.0 108.0 91.0 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | [#6] imaging-darkroom Cycles:10 Average:121.40 Median:156.50 stddev:26.27 (16.8%) stddev-sans-first:24.70 10:44:58 INFO - PROCESS | 1981 | Values: 156.0 101.0 104.0 157.0 116.0 153.0 136.0 98.0 96.0 97.0 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | [#7] imaging-desaturate Cycles:10 Average:91.70 Median:92.00 stddev:2.50 (2.7%) stddev-sans-first:2.35 10:44:58 INFO - PROCESS | 1981 | Values: 95.0 92.0 96.0 92.0 91.0 88.0 90.0 89.0 91.0 93.0 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | [#8] json-parse-financial Cycles:10 Average:67.30 Median:68.00 stddev:1.57 (2.3%) stddev-sans-first:1.59 10:44:58 INFO - PROCESS | 1981 | Values: 66.0 66.0 65.0 66.0 69.0 67.0 68.0 70.0 68.0 68.0 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | [#9] json-stringify-tinderbox Cycles:10 Average:59.80 Median:60.50 stddev:2.70 (4.5%) stddev-sans-first:2.67 10:44:58 INFO - PROCESS | 1981 | Values: 57.0 64.0 62.0 63.0 61.0 56.0 57.0 59.0 60.0 59.0 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:86.50 stddev:5.66 (6.5%) stddev-sans-first:5.67 10:44:58 INFO - PROCESS | 1981 | Values: 83.0 89.0 95.0 100.0 82.0 86.0 84.0 87.0 86.0 91.0 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | [#11] stanford-crypto-ccm Cycles:10 Average:152.50 Median:149.50 stddev:14.25 (9.5%) stddev-sans-first:14.37 10:44:58 INFO - PROCESS | 1981 | Values: 140.0 152.0 145.0 148.0 157.0 147.0 150.0 146.0 191.0 149.0 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:167.50 stddev:2.97 (1.8%) stddev-sans-first:2.98 10:44:58 INFO - PROCESS | 1981 | Values: 165.0 169.0 166.0 170.0 168.0 166.0 166.0 167.0 166.0 175.0 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - PROCESS | 1981 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.90 Median:67.50 stddev:7.39 (10.9%) stddev-sans-first:7.70 10:44:58 INFO - PROCESS | 1981 | Values: 66.0 67.0 67.0 68.0 67.0 73.0 90.0 69.0 65.0 67.0 10:44:58 INFO - PROCESS | 1981 | -------- Summary: end -------- 10:44:58 INFO - PROCESS | 1981 | 10:44:58 INFO - TEST-INFO | 1981: exit 0 10:44:58 INFO - TEST-OK | kraken | took 60430ms 10:44:58 INFO - SUITE-END | took 1176s 10:44:58 INFO - Completed test suite (00:19:37) 10:44:58 INFO - javascript benchmark 10:44:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7826.051383498119, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3529.894103176905, 3533.6289689582595, 3551.1654761131144, 3562.9287414251717, 3568.018794831421, 6066.787662431816, 6088.117223002662, 6110.755569777215, 6134.631922084678, 6172.4444799968, 4733.692309999848, 6082.787102451415, 6106.633601983884, 6174.382561743823, 6189.195404597396, 5480.3971563128025, 5499.202615620733, 5542.196381524676, 5568.136938774476, 5607.43925607438, 4871.853844384674, 5590.18942253375, 5615.410381909902, 5625.746841392147, 5626.971865140668, 4555.2256116460285, 5378.865528361803, 5404.891902161954, 5541.889162216753, 5581.720913954286, 5489.533389661883, 5503.532199763025, 5510.201020851954, 5515.200295957064, 5549.361823390322, 5475.0966090595, 5525.254090697745, 5542.307211598551, 5559.054960656698, 5573.41479144692, 6197.473214776749, 6210.782622608187, 6211.534134939898, 6224.035274532454, 6229.25248970122, 5104.157813960692, 6300.401461861115, 6317.0, 6470.09418681385, 6486.448651864597, 6358.237011558643, 6448.967755161194, 6454.548181627288, 6490.967545162244, 6495.220573531159, 6229.7508099676415, 6274.737136688942, 6278.968605156991, 6293.433590976834, 6312.589681670676, 6690.2306234782645, 6734.326567343324, 6735.932640673628, 6748.763793267212, 6749.730010799513, 6271.749130034747, 6273.7176827042895, 6283.08895210192, 6334.5249106317215, 6336.1762970814425, 6524.184476940382, 6535.542512024208, 6539.705713242915, 6550.967245163743, 6553.180852393451, 6304.464120549713, 6441.355864413521, 6442.0, 6454.419102280817, 6456.063870738717, 5100.744962751847, 6249.375062493805, 6286.18279623646, 6309.716062777186, 6435.839104022437, 4841.515848415131, 4879.878003049881, 4931.211006238985, 4986.102501549756, 5071.746412679351, 4481.686281960231, 4487.035287413157, 4498.797554110073, 4513.729176249435, 4513.729176249435, 6042.395760423922, 6095.786647467317, 6116.785912493041, 6119.938800611937, 6134.263888333428, 5405.837824865261, 6238.157848690369, 6314.463270622048, 6412.422881940554, 6478.481721462272, 6083.878322433615, 6119.593952096689, 6126.846828829226, 6127.34224287047, 6151.784687535915, 5685.374608793029, 5691.943080569225, 5704.34400043992, 5706.600537962386, 5709.8287051388525, 6328.715207815567, 6387.872242555216, 6701.798946031627, 6706.530542862148, 6715.865682686319, 6364.427201551883, 6388.584741991755, 6438.806835794933, 6504.284528701934, 6505.447037001908, 6648.434883034996, 6713.731450741916, 6717.1267735194115, 6723.5293529452465, 6737.831554210988, 6261.405166509175, 6451.935480645134, 6454.99947508137, 6457.934440817213, 6470.35296470368, 6233.625982441068, 6278.874422511615, 6281.371862813865, 6319.0, 6325.873482530232, 5937.673427961504, 6301.684915754286, 6304.369563043842, 6306.4639505642535, 6316.178896743394, 6227.0970709246, 6264.561480696308, 6302.369763023661, 6324.683765811599, 6332.715027823851, 6104.095385180614, 6115.088229002258, 6269.655168965751, 6283.3402492737605, 6303.527235457267, 6349.968250158904, 6355.428011478989, 6382.521310901608, 6387.744490220339, 6409.9359006411205, 6138.877222455615, 6162.691865406622, 6291.874162516816, 6300.59046161998, 6315.368463153648], "value": 5936.9669740640165, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [675.9188897332319, 769.011819811542, 837.5812093953022, 955.8279509688258, 966.7873067925054, 37644.43533347002, 38090.42864357033, 39490.80254598723, 39585.60414395855, 39623.0, 38263.80868095662, 38587.42118868212, 38597.61402385975, 38606.0, 38611.841644750704, 7977.20227977202, 8093.4334596578265, 10934.179936504754, 11239.83140252897, 11367.0, 9207.585658645377, 11016.339019658806, 11026.393548354838, 11122.0, 11127.276727012719, 11168.944155279212, 11179.608713695023, 11187.160962927772, 11196.608118715847, 11202.943985280062, 9122.0, 11057.944710276477, 11088.722781930435, 11096.778064438748, 11115.666530004113, 9018.729438116867, 10872.61945831892, 10949.0, 10949.905009499065, 10952.780944381147, 33530.83234583836, 33748.32503349944, 33819.0, 33856.0, 33909.6609033909, 3760.492333534965, 3764.7552909060823, 3773.2264885698364, 3815.9809200954096, 3825.158465137665, 24270.757292427028, 24599.754002459926, 25809.74190258111, 26896.731032689815, 26958.191254262405, 2965.8072225305286, 3052.709992550704, 3070.570120183176, 3071.43178511976, 3083.9845800771077, 373629.0, 374081.0, 375102.24897750944, 375835.0, 377359.0, 35285.11787205289, 44598.77700611476, 44665.55334446614, 44853.327200092026, 44869.878253043935, 37341.81329093392, 46219.76890115528, 46702.532974670496, 46716.532834671896, 46759.06481870344, 13594.0, 13952.720945581032, 13978.790318145237, 13996.790048149285, 14037.806786423269, 11946.522139114339, 12083.395830208454, 12202.511899523919, 12204.084693647834, 12220.572279970158, 10367.429791361397, 11536.884631153582, 12077.516899324104, 12118.454669539891, 12169.269843809223, 5474.698891561003, 5611.494965453047, 5731.455511726379, 5740.827775166752, 5749.3963133871175, 1137.8691450483134, 1149.9827502587468, 1152.2395219155317, 1156.4044517073714, 1212.4725744301081, 26776.464470710478, 26839.329016774347, 27367.45265094687, 27490.587641185397, 27536.724632753816, 974.4202199691173, 1121.1333130337896, 1246.1712960880914, 1310.2138716770053, 1311.658968668134, 12250.326232057323, 13490.52783152585, 13510.86489135096, 13621.523246686516, 13770.96717746173, 416980.0, 419897.0, 419929.0, 420052.0, 420830.0, 55089.44910550924, 55312.17031744527, 55324.17013744797, 55355.16967245495, 55379.44620553743, 1258.1256027061288, 1433.720424517209, 1465.8241011078526, 1482.2218335374014, 1489.8733607643471, 26377.868110659325, 27117.322066948484, 28277.57583636288, 28960.40464869021, 29044.0, 46534.53465465302, 46716.299255510516, 46851.06297874091, 46899.0, 46954.76522617365, 492332.0, 494944.0, 495504.0, 496549.0, 497174.0, 29990.55014174833, 30769.230769230948, 32787.83606081954, 32863.0, 32951.8466853659, 39292.51673473959, 39864.40203396893, 40125.996850078984, 40336.79831600823, 40503.0, 27424.17727468179, 28203.576946345413, 28208.29479263035, 28872.422551549273, 29366.38484883352, 26427.867860660575, 27913.860430697718, 28175.295617609725, 28460.715392845806, 28717.856410717814, 32162.19594510156, 32265.838670806497, 32413.837930810194, 32677.34645307033, 33257.66742332643, 27834.721652784025, 28218.71781282243, 28670.713292866803, 29234.707652924048, 29302.26744331409, 22497.5500489986, 23265.651015235122, 23499.41251468727, 23664.645030324904, 23781.167659131846], "value": 18371.981947998545, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.73971261266655, 394.73355485047585, 414.22953306849274, 424.66027178257394, 456.34742318484575, 20313.289034883783, 21471.892640536815, 21507.56984860302, 21642.0, 21887.0, 19697.818130912154, 21452.57094858102, 22262.88868555659, 22487.887560562216, 23006.769932300715, 6629.303923088079, 6858.485613578977, 6885.69014394351, 6949.992251123584, 6959.582425054487, 5109.054824857395, 6089.512838972893, 6731.629760363178, 6837.418819400332, 6907.620080895549, 6532.379423954717, 6728.899066514006, 6880.4495640348905, 6892.172939247297, 6924.446044316468, 6633.701483433233, 6662.600243985328, 6676.26561078281, 6708.7651932182625, 6723.630200338955, 6195.876082478325, 6777.559458635173, 6818.352256535622, 6820.488463365267, 6855.931440685579, 26673.0, 26800.463990720076, 26847.0, 26931.461370772475, 27296.317592060157, 3360.327934413127, 3398.269372084989, 3411.249525104473, 3480.2343484433386, 3576.445650924085, 16189.838101619069, 16282.43011494592, 16310.918445407815, 16423.753643695236, 16497.010179389275, 2021.1107112870436, 2082.7188329575465, 2103.4741314671332, 2113.19698514565, 2153.9784602153936, 110450.0, 114288.0, 128461.35769321094, 130534.0, 136185.0, 21649.67525487119, 21733.782662173493, 22132.225372111898, 22774.886125569265, 22848.74331911761, 34099.82950085284, 34499.65500344965, 35047.64952350495, 35067.82466087653, 35217.47173792395, 10827.350358978487, 10912.017918387224, 10942.945285273523, 11053.778924421627, 11187.328760274248, 8914.465132092095, 9027.36808423404, 9073.77315567116, 9273.860892086625, 9276.211522020703, 8305.875411868827, 10060.74848128788, 10348.379097254188, 10458.26792124544, 10463.476826158661, 3835.827387767557, 4194.30793919006, 4456.532064133284, 4478.39541661878, 4616.238320677118, 1015.2081376526321, 1056.4400867540216, 1056.747753911617, 1106.4246591772394, 1137.5449820071872, 22680.319590412313, 22878.0, 22931.770682293296, 22966.311010669706, 23040.0, 1138.7267055906689, 1150.7871043856912, 1161.3148242536943, 1180.8937195652431, 1182.3319824299244, 11641.47613357384, 11754.88245117538, 11881.287122772661, 12009.399530023464, 12043.494563179602, 716269.0, 737527.0, 755367.0, 757968.0, 775318.0, 122890.0, 123193.0, 127363.0, 129958.35020824835, 131809.34095329655, 1267.4233223883111, 1269.7270086931353, 1314.4216544720293, 1319.2678063674753, 1320.683036071336, 19144.138513767193, 19557.60884782325, 19581.608367832847, 19689.507762305482, 19819.60360792805, 43186.0, 56791.432085680266, 56800.715996419756, 58466.123008155766, 58553.414465854796, 63019.0, 74094.0, 77971.6101419512, 78934.0, 87095.1290487087, 10878.075363593867, 11611.303321800719, 11681.415929203677, 11731.941340293244, 11738.295702257892, 12713.8092928608, 12743.681407964876, 13059.542915997894, 13104.606861794162, 13288.734225315246, 11028.439400970921, 11386.146039047271, 11431.828522572334, 11550.653480395602, 11622.418879055844, 9419.952900235456, 10235.641752538626, 10631.574737010742, 10665.520051597541, 10695.86082783462, 11543.249688770538, 11695.181337306307, 11709.238899471507, 11843.28940263587, 11892.464839082435, 11577.305361678325, 11591.0, 11718.824217636913, 11924.880751192377, 11944.343061131722, 9706.223502119954, 9770.902290977283, 10019.54912028975, 10048.89951100509, 10212.387256764321], "value": 11685.26573393375, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.7681559358296, 1585.8017747781528, 1587.3015873015877, 1643.7780899578554, 1658.9004659720417, 9097.909020909789, 9832.950835245832, 10486.318389304699, 10861.67414977551, 11563.826542601857, 10818.350898946068, 11223.943880280587, 11417.372044537547, 11421.428928553581, 11451.370174640413, 2404.4589967257402, 2468.0868078810777, 2472.431340791619, 2473.2951108933958, 2478.1821998740375, 2424.745401732819, 2455.2143314139485, 2460.2988148377717, 2464.4824586836785, 2469.8271121021535, 2420.9636855447184, 2439.609662454008, 2440.0849681369486, 2448.3879030242438, 2454.2759885833634, 2404.1945948107405, 2412.0834083048417, 2414.6619473273754, 2418.4800267942387, 2423.854568725882, 2362.161432691404, 2377.9048838046456, 2379.4646204604, 2381.7022872140983, 2387.8447900886385, 11416.42917854104, 11421.200515963887, 11470.598529051527, 11533.308001519852, 11556.248843825124, 5805.593608447411, 5962.821115366546, 5974.0, 5977.820665380045, 5984.251968503937, 2932.765378769693, 3076.415481058592, 3470.357983772985, 3626.746127771057, 3701.6668499835146, 1163.03468121459, 1171.9862319094007, 1188.9464974076188, 1240.2558464921065, 1241.7640648276888, 15515.612109697235, 15520.689586208326, 15598.9220053899, 15618.609534761608, 15623.687526249525, 4683.929741053887, 4931.742405686527, 4962.875928101754, 5030.3963524377305, 5072.315237442972, 13421.865781342256, 13426.731465370833, 13453.394597243148, 13471.057026008086, 13498.122622029538, 6021.578489505692, 6093.664848433379, 6103.298120716086, 6104.145419641254, 6113.938860611426, 4352.325389564622, 5373.946260537423, 5375.838724838261, 5385.246065550826, 5424.105022671294, 4104.581673306773, 4173.582641735802, 4237.724547904377, 4268.59448352406, 4281.2293787118, 1155.5493357590547, 1158.1487606609135, 1159.675290918544, 1173.0556901694047, 1173.5892437646755, 877.8332116897769, 908.6955869783565, 957.4877440569262, 976.0625159059261, 997.5311603546321, 6613.933860661428, 6622.9006564901565, 6743.258241593421, 6940.514164008572, 7037.507374483839, 999.5901680311038, 1021.7241344836852, 1033.503918119307, 1074.1782536359692, 1075.3601607043797, 3586.1214002569536, 3622.710183185339, 3785.886423407302, 4447.132809102259, 4551.044280701024, 11266.493007814668, 12757.660445653073, 12964.611061668165, 14008.229547374789, 14060.0, 12721.618351449471, 13077.95376369888, 13908.304584770722, 14018.579442616534, 14043.876489880786, 10139.797204055878, 10444.895551044392, 10467.476626168662, 10514.474276286155, 10527.842082368923, 5673.716314184357, 5946.0, 6354.792589407998, 6661.666916654245, 6785.558938668971, 12206.291119243135, 12391.690207744878, 12788.552400665933, 12978.740425191632, 13038.282894440898, 9966.950165249127, 12990.870091298966, 13072.673183170496, 13274.0, 13295.468181272641, 9488.288378371788, 9489.051094890456, 9567.521623918916, 9588.808223835345, 9652.131308182297, 10483.108935740242, 10516.68449946503, 10570.154387649123, 10587.788244235226, 10623.787524249317, 6690.565113267622, 9079.68221112258, 9106.27149827999, 9311.161995420445, 9341.719748407559, 7561.7731468056045, 8786.297096232414, 8929.553522323988, 8969.686060987835, 9038.909610903807, 7343.1555371133, 7886.72396466121, 8090.676372945251, 8127.1466496017065, 8205.95897020511, 5879.588428809942, 5932.93207222704, 5979.790707325223, 6018.3680713524445, 6040.637561746309, 5967.731452084579, 6102.084687296941, 6248.156498872594, 6275.0, 6306.073838824894], "value": 5234.496362451235, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.5801131738113, 382.92532956073575, 389.11088163546293, 394.11717752234983, 397.7017237072195, 4423.77881105945, 4897.779599918003, 5361.900810333882, 5377.381601115868, 5395.271638328824, 4633.374494443249, 4950.653454258203, 5310.415854256029, 5352.411234764182, 5353.5984801139875, 4189.0, 4348.630181492819, 4367.694261401703, 4443.4445694288215, 4670.416197975253, 3915.4729655434403, 4346.630536404409, 4576.0, 4664.941500894227, 4739.85780426586, 3987.8006099695044, 4405.229084910144, 4502.347159661847, 4609.608183304423, 4674.415698037746, 4005.8998525036964, 4263.744175349489, 4461.0, 4615.246296314801, 4750.263709125091, 3864.478295430109, 4030.891504836164, 4391.429114215131, 4398.34024896265, 4407.537208593084, 4738.905221895543, 5115.695497648076, 5215.496561650391, 5288.391834938992, 5293.988284451883, 4542.909141817178, 4854.737768180298, 5208.322918020633, 5381.085215513333, 5462.180672899097, 5400.756965936543, 5456.963176996398, 5492.725363731837, 5582.888342233173, 5861.6189947653265, 4296.57034296571, 4986.95273992462, 4999.0, 5052.317937078521, 5055.0, 4924.359833221694, 5158.92261616076, 5608.635438696471, 5884.117382392633, 6036.0, 4778.474367819537, 4791.209450440639, 4801.855944321676, 4807.663463557587, 4923.975380123076, 4578.633709303248, 4767.427908650915, 4884.145274577006, 4921.901561968741, 5006.224035274538, 4559.452865656076, 4828.1031381171015, 4954.885150841032, 5184.755658641899, 5232.1628539433505, 4969.652124351261, 5516.7517461714315, 5649.830505084854, 5735.812856614694, 5739.942600573941, 1534.9309281082378, 1700.6173610937687, 1779.973300400495, 1797.6764182447023, 1817.4002579148848, 1354.9160671462791, 1398.5105213175307, 1418.7020725647524, 1426.522115091456, 1460.5253292679922, 4569.702969306984, 5092.185250359925, 5122.871928201825, 5131.358580177478, 5192.584593232532, 4930.580900623416, 5001.299298378907, 5104.69371837691, 5294.444083371268, 5798.565107616945, 4798.688085274446, 4810.546621223131, 5049.394072711299, 5054.974725126424, 5144.922826157611, 2776.5141100307364, 2899.9840166226886, 3044.5737596736476, 3068.5243787213017, 3078.322768990863, 5059.256150448641, 5376.650517716336, 5707.204708868556, 5769.711514424346, 6180.8763824723255, 5179.922301165486, 5584.274044374205, 5760.36635970043, 6037.698115094316, 6064.727087281083, 6048.879022419527, 6164.938350616437, 6166.81499555014, 6173.438023220403, 6210.354256122041, 5465.344158700981, 5508.14623733322, 5671.546276297969, 5943.524518038633, 5991.88016239664, 5222.738863056908, 5674.992254105166, 5838.474537291664, 5896.262967129109, 5918.9704051481185, 5363.383210930637, 5770.682612456355, 6158.938410615837, 6210.043836191585, 6215.488249139222, 3920.4511368409017, 4493.595576398009, 4646.3727396801, 4658.787076138807, 4770.546798054179, 4991.10515900784, 5492.673773306159, 5755.963926493104, 5775.718591156554, 6147.938520614736, 5181.5595674368105, 5250.871062721533, 5544.168374743821, 5651.987089166573, 5857.26784151981, 4983.177775666974, 5271.523973287485, 5293.391260005149, 5302.8713059295205, 5744.712764361681, 4879.975205206804, 5234.502722241533, 5630.408807075198, 5635.887282254414, 5734.799282025109, 5133.511281728257, 5278.129108697022, 5286.841394758163, 5346.251524786455, 5924.9703751480965, 5198.3105490716425, 5585.497305242548, 5609.719514024201, 5642.322921249476, 5647.830565083054], "value": 4424.469979585024, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.2421293960748, 752.9736459223928, 779.5907148746911, 821.7575815134535, 848.1306660672809, 12957.352132393378, 13742.862571374308, 15991.76012359814, 16618.50144495664, 16902.661946761058, 15109.84890151098, 15447.922760386182, 15497.922510387432, 15593.84406155941, 15597.688046239069, 950.6387572722373, 973.4937832327221, 978.6330126202674, 978.7161723100293, 982.3958265666635, 765.3571000359697, 964.4840010594334, 971.8930917599093, 974.1378879691489, 980.5587485631459, 1015.6445244164557, 1016.5425558498669, 1017.760826205841, 1018.9796204075914, 1020.9081182693556, 995.8058178655165, 1005.4972513743129, 1007.1036777268237, 1011.0041609015115, 1011.281989787252, 744.7058411927259, 751.0874287740421, 752.6236881559221, 753.6307209467321, 753.6608526163249, 13867.514636987657, 16275.267612957445, 16405.91797041007, 17161.65676686472, 17392.826071739248, 14566.981971458554, 14958.775618365733, 15077.623059423602, 15097.773533397009, 15426.074435533905, 11253.268537545033, 12027.458764355624, 12060.577879774253, 12568.30874301922, 12869.935650321688, 4572.679912406133, 4605.930911036337, 4821.662483626147, 4855.5387238212315, 4862.07620552094, 113216.0, 114131.0, 114591.0, 116615.4169229157, 120071.1989320161, 10055.195584353234, 11216.775664486664, 11467.942660286812, 11784.705382365339, 11870.109741769402, 24070.0, 24440.633390499155, 24639.87680061624, 24681.259562213163, 25008.374790630016, 7743.303102720783, 7957.3634109271115, 8117.715879944174, 8205.384596155312, 8220.671173153127, 4248.936265956013, 4297.39836422901, 4338.717983331074, 4414.249577571821, 4455.732656040583, 3611.8916432507067, 3706.6664000240107, 3706.814659267026, 3708.35103856824, 3718.9814050930113, 647.1328419917334, 650.7527139686889, 654.3456543456543, 656.4387448731266, 673.5487223560128, 571.6198727846019, 674.7132468700783, 692.6155983429145, 698.1587187439123, 713.1121753416926, 10089.596416143419, 10430.895691043144, 10520.26358154937, 10641.787164256672, 10647.361158330525, 674.942629876466, 689.3588962265042, 712.3410844968383, 726.418864908082, 741.7922981565063, 4460.50934397216, 4828.396450443694, 5048.29323894655, 5085.313482679865, 5090.363704536933, 85219.0, 101027.0, 105808.0, 109436.45281773541, 112231.43884280526, 73071.17322066991, 74533.627331863, 80229.79655305059, 83467.16532834715, 91903.0, 65469.672651636436, 72973.27026729664, 74260.25739742641, 75621.62189188908, 75666.0, 8424.4945303282, 10888.67333979982, 11068.612598559012, 11143.387113708824, 11164.553417863195, 46884.29673554831, 49365.76585585389, 49558.0, 50736.74631626818, 50857.98284034224, 60253.69873150606, 61806.38193618006, 63497.36502634914, 63563.0, 64309.35690643221, 10657.946710266398, 12459.501619935463, 12671.113022088366, 12722.300273485047, 12986.285754283605, 9784.951075244579, 12128.393580321124, 14293.0, 14641.48754793577, 14798.926005369904, 14029.368678409292, 14047.367868446148, 14101.788473172706, 14170.787438188641, 14543.0, 13608.319584020957, 13934.442622294995, 13936.442542298195, 14587.635309117357, 14738.26308684583, 13286.402111905181, 13436.798448023483, 13844.584662460142, 14052.78920816209, 14110.647233819236, 9249.398789078688, 9488.762780930532, 9866.950665246628, 10060.396376217157, 10155.543000564845, 10970.286931349438, 11521.2104297903, 11529.942350288195, 11821.704457388634, 11909.285442873455], "value": 7782.5669372371, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1525.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 119.0, 119.0, 119.0, 119.0, 118.0, 119.0, 118.0, 120.0, 118.0], "value": 117.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 111.0, 111.0, 148.0, 118.0, 105.0, 141.0, 98.0, 161.0, 143.0], "value": 127.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 201.0, 198.0, 229.0, 184.0, 179.0, 259.0, 276.0, 241.0, 194.0], "value": 215.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 63.0, 59.0, 94.0, 65.0, 64.0, 62.0, 64.0, 77.0, 62.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 71.0, 87.0, 73.0, 72.0, 71.0, 83.0, 73.0, 58.0, 112.0], "value": 79.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 85.0, 138.0, 88.0, 90.0, 105.0, 90.0, 90.0, 108.0, 91.0], "value": 97.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 101.0, 104.0, 157.0, 116.0, 153.0, 136.0, 98.0, 96.0, 97.0], "value": 121.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 92.0, 96.0, 92.0, 91.0, 88.0, 90.0, 89.0, 91.0, 93.0], "value": 91.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 65.0, 66.0, 69.0, 67.0, 68.0, 70.0, 68.0, 68.0], "value": 67.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 64.0, 62.0, 63.0, 61.0, 56.0, 57.0, 59.0, 60.0, 59.0], "value": 59.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 89.0, 95.0, 100.0, 82.0, 86.0, 84.0, 87.0, 86.0, 91.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 152.0, 145.0, 148.0, 157.0, 147.0, 150.0, 146.0, 191.0, 149.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 169.0, 166.0, 170.0, 168.0, 166.0, 166.0, 167.0, 166.0, 175.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 68.0, 67.0, 73.0, 90.0, 69.0, 65.0, 67.0], "value": 69.9, "unit": "score"}]}]} 10:44:58 INFO - Return code: 0 10:44:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:44:59 ERROR - # TBPL SUCCESS # 10:44:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:44:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:44:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:44:59 INFO - 2017-05-24 10:44:59.060 screenresolution[1986:14460] starting screenresolution argv=screenresolution get 10:44:59 INFO - 2017-05-24 10:44:59.063 screenresolution[1986:14460] Display 0: 1600x1200x32@60 10:44:59 INFO - 2017-05-24 10:44:59.069 screenresolution[1987:14463] starting screenresolution argv=screenresolution list 10:44:59 INFO - Available Modes on Display 0 10:44:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:44:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:44:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:44:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:44:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:44:59 INFO - Intel Iris: 10:44:59 INFO - Chipset Model: Intel Iris 10:44:59 INFO - Type: GPU 10:44:59 INFO - Bus: Built-In 10:44:59 INFO - VRAM (Dynamic, Max): 1536 MB 10:44:59 INFO - Vendor: Intel (0x8086) 10:44:59 INFO - Device ID: 0x0a2e 10:44:59 INFO - Revision ID: 0x0009 10:44:59 INFO - Displays: 10:44:59 INFO - Display: 10:44:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:44:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:44:59 INFO - Display Serial Number: 1600x1200 60 10:44:59 INFO - Main Display: Yes 10:44:59 INFO - Mirror: Off 10:44:59 INFO - Online: Yes 10:44:59 INFO - Rotation: Supported 10:44:59 INFO - Return code: 0 10:44:59 INFO - Running post-action listener: _resource_record_post_action 10:44:59 INFO - [mozharness: 2017-05-24 17:44:59.323202Z] Finished run-tests step (success) 10:44:59 INFO - Running post-run listener: _resource_record_post_run 10:44:59 INFO - Total resource usage - Wall time: 1197s; CPU: 54.0%; Read bytes: 1069056; Write bytes: 511258112; Read time: 14; Write time: 1611 10:44:59 INFO - TinderboxPrint: CPU usage
54.5% 10:44:59 INFO - TinderboxPrint: I/O read bytes / time
1,069,056 / 14 10:44:59 INFO - TinderboxPrint: I/O write bytes / time
511,258,112 / 1,611 10:44:59 INFO - TinderboxPrint: CPU idle
2,181.3 (45.5%) 10:44:59 INFO - TinderboxPrint: CPU system
961.5 (20.1%) 10:44:59 INFO - TinderboxPrint: CPU user
1,647.0 (34.4%) 10:44:59 INFO - TinderboxPrint: Swap in / out
210,124,800 / 0 10:44:59 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 206053888; Write bytes: 202843648; Read time: 15834; Write time: 436 10:44:59 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 1032192; Write bytes: 297396224; Read time: 14; Write time: 1135 10:44:59 INFO - Running post-run listener: _upload_blobber_files 10:44:59 INFO - Blob upload gear active. 10:44:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:44:59 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:44:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:44:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:45:00 INFO - (blobuploader) - INFO - Open directory for files ... 10:45:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:45:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:45:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:45:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:45:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:45:01 INFO - (blobuploader) - INFO - Done attempting. 10:45:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:45:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:45:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:45:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:45:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:45:02 INFO - (blobuploader) - INFO - Done attempting. 10:45:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:45:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:45:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:45:03 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:45:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:45:03 INFO - (blobuploader) - INFO - Done attempting. 10:45:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:45:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:45:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:45:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:45:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:45:05 INFO - (blobuploader) - INFO - Done attempting. 10:45:05 INFO - (blobuploader) - INFO - Iteration through files over. 10:45:05 INFO - Return code: 0 10:45:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:45:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:45:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bd2725e456951dfbd45b7a05b01d564098861810fc526348adc3e3b07ae6dbe2d9e81c7973fa87b0d82be88af604b1d2fefb7cf664a3dc7c7e408e05e5c37ca6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58726c6df3fa3d649d8a4cdb0a339f23144ba0941363911b5e429ba0f51eeade5ec7da936aa43d81a192914f538326769ea12b995c9425c1800499a9db05df7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e336a6d69795b085e74299c1a13eecc5221884056e39c2087a11484b909d1da393bcea3f585650713d8f670008e26a65cf362eb3b71412dc095d6dbac529c6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7feb722678a92f01c0892034530af6875bb8f8c3233371f40b6b5b8213369bd71fba993987da00d2a6b5bebf0355b20770f13a77ff2784d94f7893de8e041f64"} 10:45:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:45:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:45:05 INFO - Contents: 10:45:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bd2725e456951dfbd45b7a05b01d564098861810fc526348adc3e3b07ae6dbe2d9e81c7973fa87b0d82be88af604b1d2fefb7cf664a3dc7c7e408e05e5c37ca6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58726c6df3fa3d649d8a4cdb0a339f23144ba0941363911b5e429ba0f51eeade5ec7da936aa43d81a192914f538326769ea12b995c9425c1800499a9db05df7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e336a6d69795b085e74299c1a13eecc5221884056e39c2087a11484b909d1da393bcea3f585650713d8f670008e26a65cf362eb3b71412dc095d6dbac529c6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7feb722678a92f01c0892034530af6875bb8f8c3233371f40b6b5b8213369bd71fba993987da00d2a6b5bebf0355b20770f13a77ff2784d94f7893de8e041f64"} 10:45:05 INFO - Running post-run listener: copy_logs_to_upload_dir 10:45:05 INFO - Copying logs to upload dir... 10:45:05 INFO - mkdir: /builds/slave/test/build/upload/logs 10:45:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.258419 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-05-24 10:45:05.350958) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 10:45:05.354233) ========= 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.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners TMPDIR=/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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/bd2725e456951dfbd45b7a05b01d564098861810fc526348adc3e3b07ae6dbe2d9e81c7973fa87b0d82be88af604b1d2fefb7cf664a3dc7c7e408e05e5c37ca6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58726c6df3fa3d649d8a4cdb0a339f23144ba0941363911b5e429ba0f51eeade5ec7da936aa43d81a192914f538326769ea12b995c9425c1800499a9db05df7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e336a6d69795b085e74299c1a13eecc5221884056e39c2087a11484b909d1da393bcea3f585650713d8f670008e26a65cf362eb3b71412dc095d6dbac529c6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7feb722678a92f01c0892034530af6875bb8f8c3233371f40b6b5b8213369bd71fba993987da00d2a6b5bebf0355b20770f13a77ff2784d94f7893de8e041f64"} build_url:https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011323 build_url: 'https://queue.taskcluster.net/v1/task/C4ZifP7NQp2ae4_Ec084kQ/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/bd2725e456951dfbd45b7a05b01d564098861810fc526348adc3e3b07ae6dbe2d9e81c7973fa87b0d82be88af604b1d2fefb7cf664a3dc7c7e408e05e5c37ca6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/58726c6df3fa3d649d8a4cdb0a339f23144ba0941363911b5e429ba0f51eeade5ec7da936aa43d81a192914f538326769ea12b995c9425c1800499a9db05df7d", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e336a6d69795b085e74299c1a13eecc5221884056e39c2087a11484b909d1da393bcea3f585650713d8f670008e26a65cf362eb3b71412dc095d6dbac529c6cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7feb722678a92f01c0892034530af6875bb8f8c3233371f40b6b5b8213369bd71fba993987da00d2a6b5bebf0355b20770f13a77ff2784d94f7893de8e041f64"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-24 10:45:05.387611) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 10:45:05.387959) ========= 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.ZjTlcR2Z3n/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.L43hBsd6I1/Listeners TMPDIR=/var/folders/8n/198zz2_16l7gkp94nf1ypm3000000w/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.005279 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-24 10:45:05.436440) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 10:45:05.436763) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-24 10:45:05.437080) ========= ========= Total master_lag: 0.18 =========