builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0374 starttime: 1494871873.07 results: success (0) buildid: 20170515094125 builduid: 9927fe27590543a8ac74eacb874edc95 revision: eeeee18148df4e3a4b701aeffcbf490945d99267 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 11:11:13.069803) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-15 11:11:13.070285) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 11:11:13.070562) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-15 11:11:13.094030) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:11:13.094420) ========= 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.lVmkLD5IBX/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.TBf1iKOuPI/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-15 11:11:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2017-05-15 11:11:13 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.128597 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:11:13.246271) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:11:13.246590) ========= 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.lVmkLD5IBX/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.TBf1iKOuPI/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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.026300 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:11:13.294714) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-15 11:11:13.295067) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev eeeee18148df4e3a4b701aeffcbf490945d99267 --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 eeeee18148df4e3a4b701aeffcbf490945d99267 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.lVmkLD5IBX/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.TBf1iKOuPI/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-15 11:11:13,405 truncating revision to first 12 chars 2017-05-15 11:11:13,405 Setting DEBUG logging. 2017-05-15 11:11:13,405 attempt 1/10 2017-05-15 11:11:13,405 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/eeeee18148df?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-15 11:11:14,208 unpacking tar archive at: mozilla-beta-eeeee18148df/testing/mozharness/ program finished with exit code 0 elapsedTime=1.074503 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-15 11:11:14.392226) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 11:11:14.392530) ========= script_repo_revision: eeeee18148df4e3a4b701aeffcbf490945d99267 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-15 11:11:14.392924) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 11:11:14.393189) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-15 11:11:14.412350) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-15 11:11:14.412762) ========= /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.lVmkLD5IBX/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.TBf1iKOuPI/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 11:11:14 INFO - MultiFileLogger online at 20170515 11:11:14 in /builds/slave/test 11:11:14 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 11:11:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:11:14 INFO - {'append_to_log': False, 11:11:14 INFO - 'base_work_dir': '/builds/slave/test', 11:11:14 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:11:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:11:14 INFO - 'branch': 'Mozilla-Beta', 11:11:14 INFO - 'buildbot_json_path': 'buildprops.json', 11:11:14 INFO - 'config_files': ('talos/mac_config.py',), 11:11:14 INFO - 'default_actions': ('clobber', 11:11:14 INFO - 'read-buildbot-config', 11:11:14 INFO - 'download-and-extract', 11:11:14 INFO - 'populate-webroot', 11:11:14 INFO - 'create-virtualenv', 11:11:14 INFO - 'install', 11:11:14 INFO - 'run-tests'), 11:11:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:11:14 INFO - 'download_minidump_stackwalk': True, 11:11:14 INFO - 'download_symbols': 'ondemand', 11:11:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:11:14 INFO - 'tooltool.py': '/tools/tooltool.py', 11:11:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:11:14 INFO - '/tools/misc-python/virtualenv.py')}, 11:11:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:11:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:11:14 INFO - 'gecko_profile': False, 11:11:14 INFO - 'gecko_profile_interval': 0, 11:11:14 INFO - 'installer_path': 'installer.exe', 11:11:14 INFO - 'log_level': 'info', 11:11:14 INFO - 'log_name': 'talos', 11:11:14 INFO - 'log_to_console': True, 11:11:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:11:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:11:14 INFO - 'opt_config_files': (), 11:11:14 INFO - 'pip_index': False, 11:11:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:11:14 INFO - 'cmd': ('bash', 11:11:14 INFO - '-c', 11:11:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:11:14 INFO - 'enabled': True, 11:11:14 INFO - 'halt_on_failure': False, 11:11:14 INFO - 'name': 'check_screen_resolution'},), 11:11:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:11:14 INFO - 'cmd': ('bash', 11:11:14 INFO - '-c', 11:11:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:11:14 INFO - 'enabled': True, 11:11:14 INFO - 'halt_on_failure': False, 11:11:14 INFO - 'name': 'check_screen_resolution'},), 11:11:14 INFO - 'run_cmd_checks_enabled': True, 11:11:14 INFO - 'suite': 'dromaeojs-e10s', 11:11:14 INFO - 'system_bits': '32', 11:11:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:11:14 INFO - 'title': 't-yosemite-r7-0374', 11:11:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:11:14 INFO - 'use_talos_json': True, 11:11:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:11:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:11:14 INFO - 'work_dir': 'build'} 11:11:14 INFO - [mozharness: 2017-05-15 18:11:14.629165Z] Running clobber step. 11:11:14 INFO - Running pre-action listener: _resource_record_pre_action 11:11:14 INFO - Running main action method: clobber 11:11:14 INFO - rmtree: /builds/slave/test/build 11:11:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:11:19 INFO - Running post-action listener: _resource_record_post_action 11:11:19 INFO - [mozharness: 2017-05-15 18:11:19.409664Z] Finished clobber step (success) 11:11:19 INFO - [mozharness: 2017-05-15 18:11:19.409909Z] Running read-buildbot-config step. 11:11:19 INFO - Running pre-action listener: _resource_record_pre_action 11:11:19 INFO - Running main action method: read_buildbot_config 11:11:19 INFO - Using buildbot properties: 11:11:19 INFO - { 11:11:19 INFO - "project": "", 11:11:19 INFO - "product": "firefox", 11:11:19 INFO - "script_repo_revision": "production", 11:11:19 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 11:11:19 INFO - "repository": "", 11:11:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 11:11:19 INFO - "buildid": "20170515094125", 11:11:19 INFO - "pgo_build": "False", 11:11:19 INFO - "basedir": "/builds/slave/test", 11:11:19 INFO - "buildnumber": 45, 11:11:19 INFO - "slavename": "t-yosemite-r7-0374", 11:11:19 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:11:19 INFO - "platform": "yosemite_r7", 11:11:19 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 11:11:19 INFO - "branch": "mozilla-beta", 11:11:19 INFO - "repo_path": "releases/mozilla-beta", 11:11:19 INFO - "revision": "eeeee18148df4e3a4b701aeffcbf490945d99267", 11:11:19 INFO - "stage_platform": "macosx64", 11:11:19 INFO - "builduid": "9927fe27590543a8ac74eacb874edc95", 11:11:19 INFO - "slavebuilddir": "test" 11:11:19 INFO - } 11:11:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 11:11:19 INFO - Running post-action listener: _resource_record_post_action 11:11:19 INFO - [mozharness: 2017-05-15 18:11:19.415579Z] Finished read-buildbot-config step (success) 11:11:19 INFO - [mozharness: 2017-05-15 18:11:19.415774Z] Running download-and-extract step. 11:11:19 INFO - Running pre-action listener: _resource_record_pre_action 11:11:19 INFO - Running main action method: download_and_extract 11:11:19 INFO - mkdir: /builds/slave/test/build/tests 11:11:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:11:19 INFO - trying https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 11:11:19 INFO - Downloading https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/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 11:11:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/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 11:11:20 INFO - Downloaded 1287 bytes. 11:11:20 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 11:11:20 INFO - Using the following test package requirements: 11:11:20 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:11:20 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 11:11:20 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:11:20 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:11:20 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 11:11:20 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:11:20 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 11:11:20 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 11:11:20 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:11:20 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 11:11:20 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 11:11:20 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:11:20 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 11:11:20 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:11:20 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 11:11:20 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:11:20 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 11:11:20 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 11:11:20 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 11:11:20 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 11:11:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:11:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:11:20 INFO - Fetch https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:11:22 INFO - Expected file size: 51226222 11:11:22 INFO - Obtained file size: 51226222 11:11:38 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 11:11:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 11:11:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 11:11:38 INFO - Fetch https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 11:11:39 INFO - Expected file size: 51226222 11:11:39 INFO - Obtained file size: 51226222 11:11:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 11:11:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 11:11:54 INFO - Fetch https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 11:11:56 INFO - Expected file size: 14404449 11:11:56 INFO - Obtained file size: 14404449 11:11: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')]} 11:11:56 INFO - trying https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:11:56 INFO - Downloading https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 11:11:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/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 11:11:58 INFO - Downloaded 59539688 bytes. 11:11:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:11:58 INFO - mkdir: /builds/slave/test/properties 11:11:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:11:58 INFO - Writing to file /builds/slave/test/properties/build_url 11:11:58 INFO - Contents: 11:11:58 INFO - build_url:https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg 11:11:59 INFO - Running post-action listener: _resource_record_post_action 11:11:59 INFO - Running post-action listener: set_extra_try_arguments 11:11:59 INFO - [mozharness: 2017-05-15 18:11:59.202955Z] Finished download-and-extract step (success) 11:11:59 INFO - [mozharness: 2017-05-15 18:11:59.203099Z] Running populate-webroot step. 11:11:59 INFO - Running pre-action listener: _resource_record_pre_action 11:11:59 INFO - Running main action method: populate_webroot 11:11:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:11:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:11:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:11:59 INFO - '--tppagecycles', 11:11:59 INFO - '7'], 11:11:59 INFO - 'tests': ['tsvgm']}}, 11:11:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:11:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:11:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:11:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:11:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:11:59 INFO - 'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:11:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:11:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:11:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:11:59 INFO - 'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['damp', 'tps']}, 11:11:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:11:59 INFO - 'tests': ['damp', 'tps']}, 11:11:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['dromaeo_dom']}, 11:11:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:11:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['basic_compositor_video']}, 11:11:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:11:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['a11yr', 11:11:59 INFO - 'ts_paint', 11:11:59 INFO - 'tpaint', 11:11:59 INFO - 'sessionrestore', 11:11:59 INFO - 'sessionrestore_no_auto_restore', 11:11:59 INFO - 'tabpaint']}, 11:11:59 INFO - 'other-e10s': {'tests': ['a11yr', 11:11:59 INFO - 'ts_paint', 11:11:59 INFO - 'tpaint', 11:11:59 INFO - 'sessionrestore', 11:11:59 INFO - 'sessionrestore_no_auto_restore', 11:11:59 INFO - 'tabpaint']}, 11:11:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:11:59 INFO - 'ts_paint', 11:11:59 INFO - 'tpaint', 11:11:59 INFO - 'sessionrestore', 11:11:59 INFO - 'sessionrestore_no_auto_restore', 11:11:59 INFO - 'tabpaint']}, 11:11:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:11:59 INFO - 'ts_paint', 11:11:59 INFO - 'tpaint', 11:11:59 INFO - 'sessionrestore', 11:11:59 INFO - 'sessionrestore_no_auto_restore', 11:11:59 INFO - 'tabpaint']}, 11:11:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['a11yr', 11:11:59 INFO - 'ts_paint', 11:11:59 INFO - 'tpaint', 11:11:59 INFO - 'sessionrestore', 11:11:59 INFO - 'sessionrestore_no_auto_restore', 11:11:59 INFO - 'tabpaint']}, 11:11:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['a11yr', 11:11:59 INFO - 'ts_paint', 11:11:59 INFO - 'tpaint', 11:11:59 INFO - 'sessionrestore', 11:11:59 INFO - 'sessionrestore_no_auto_restore', 11:11:59 INFO - 'tabpaint']}, 11:11:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['tsvgx', 11:11:59 INFO - 'tsvgr_opacity', 11:11:59 INFO - 'tart', 11:11:59 INFO - 'tscrollx', 11:11:59 INFO - 'cart', 11:11:59 INFO - 'tsvg_static']}, 11:11:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:11:59 INFO - 'tsvgr_opacity', 11:11:59 INFO - 'tart', 11:11:59 INFO - 'tscrollx', 11:11:59 INFO - 'cart', 11:11:59 INFO - 'tsvg_static']}, 11:11:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:11:59 INFO - 'talos_options': ['--disable-e10s'], 11:11:59 INFO - 'tests': ['tp5o']}, 11:11:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:11:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:11:59 INFO - 'talos_options': ['--disable-e10s', 11:11:59 INFO - '--xperf_path', 11:11:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:11:59 INFO - 'tests': ['tp5n']}, 11:11:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:11:59 INFO - 'talos_options': ['--xperf_path', 11:11:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:11:59 INFO - 'tests': ['tp5n']}}, 11:11:59 INFO - 'talos.zip': {'path': '', 11:11:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:11:59 INFO - Running post-action listener: _resource_record_post_action 11:11:59 INFO - [mozharness: 2017-05-15 18:11:59.221374Z] Finished populate-webroot step (success) 11:11:59 INFO - [mozharness: 2017-05-15 18:11:59.221515Z] Running create-virtualenv step. 11:11:59 INFO - Running pre-action listener: _resource_record_pre_action 11:11:59 INFO - Running main action method: create_virtualenv 11:11:59 INFO - Creating virtualenv /builds/slave/test/build/venv 11:11:59 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 11:11:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:11:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:11:59 INFO - Using real prefix '/tools/python27' 11:11:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:12:00 INFO - Installing distribute.............................................................................................................................................................................................done. 11:12:02 INFO - Installing pip.................done. 11:12:02 INFO - Return code: 0 11:12:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:12:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:12:03 INFO - Reading from file tmpfile_stdout 11:12:03 INFO - Output received: 11:12:03 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) 11:12:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461cc9de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cd0d30>, '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 0x104bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cc5be8>, '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.lVmkLD5IBX/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.TBf1iKOuPI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 11:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:12:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVmkLD5IBX/Render', 11:12:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:03 INFO - 'HOME': '/Users/cltbld', 11:12:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:03 INFO - 'LOGNAME': 'cltbld', 11:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:03 INFO - 'MOZ_NO_REMOTE': '1', 11:12:03 INFO - 'NO_EM_RESTART': '1', 11:12:03 INFO - 'PAGER': '/bin/cat', 11:12:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:03 INFO - 'PWD': '/builds/slave/test', 11:12:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:03 INFO - 'SHELL': '/bin/bash', 11:12:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TBf1iKOuPI/Listeners', 11:12:03 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 11:12:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:03 INFO - 'USER': 'cltbld', 11:12:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:03 INFO - 'XPC_FLAGS': '0x0', 11:12:03 INFO - 'XPC_SERVICE_NAME': '0', 11:12:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:03 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 11:12:03 INFO - Cleaning up... 11:12:03 INFO - Return code: 0 11:12:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461cc9de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cd0d30>, '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 0x104bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cc5be8>, '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.lVmkLD5IBX/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.TBf1iKOuPI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 11:12:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:12:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:12:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVmkLD5IBX/Render', 11:12:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:03 INFO - 'HOME': '/Users/cltbld', 11:12:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:03 INFO - 'LOGNAME': 'cltbld', 11:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:03 INFO - 'MOZ_NO_REMOTE': '1', 11:12:03 INFO - 'NO_EM_RESTART': '1', 11:12:03 INFO - 'PAGER': '/bin/cat', 11:12:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:03 INFO - 'PWD': '/builds/slave/test', 11:12:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:03 INFO - 'SHELL': '/bin/bash', 11:12:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TBf1iKOuPI/Listeners', 11:12:03 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 11:12:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:03 INFO - 'USER': 'cltbld', 11:12:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:03 INFO - 'XPC_FLAGS': '0x0', 11:12:03 INFO - 'XPC_SERVICE_NAME': '0', 11:12:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:03 INFO - Downloading/unpacking psutil>=3.1.1 11:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:12:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:12:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:12:05 INFO - Installing collected packages: psutil 11:12:05 INFO - Running setup.py install for psutil 11:12:05 INFO - building 'psutil._psutil_osx' extension 11:12:05 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 11:12:06 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 11:12:06 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 11:12:06 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 11:12:06 INFO - building 'psutil._psutil_posix' extension 11:12:06 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 11:12:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:12:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:12:06 INFO - ^ 11:12:06 INFO - 1 warning generated. 11:12:06 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 11:12:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:12:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:12:06 INFO - Successfully installed psutil 11:12:06 INFO - Cleaning up... 11:12:06 INFO - Return code: 0 11:12:06 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:12:06 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')]} 11:12:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:06 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')]} 11:12:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:06 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 0x104ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461cc9de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cd0d30>, '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 0x104bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cc5be8>, '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.lVmkLD5IBX/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.TBf1iKOuPI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 11:12:06 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 11:12:06 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 11:12:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVmkLD5IBX/Render', 11:12:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:06 INFO - 'HOME': '/Users/cltbld', 11:12:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:06 INFO - 'LOGNAME': 'cltbld', 11:12:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:06 INFO - 'MOZ_NO_REMOTE': '1', 11:12:06 INFO - 'NO_EM_RESTART': '1', 11:12:06 INFO - 'PAGER': '/bin/cat', 11:12:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:06 INFO - 'PWD': '/builds/slave/test', 11:12:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:06 INFO - 'SHELL': '/bin/bash', 11:12:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TBf1iKOuPI/Listeners', 11:12:06 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 11:12:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:06 INFO - 'USER': 'cltbld', 11:12:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:06 INFO - 'XPC_FLAGS': '0x0', 11:12:06 INFO - 'XPC_SERVICE_NAME': '0', 11:12:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:12:07 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 11:12:07 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 11:12:07 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 11:12:07 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 11:12:08 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:12:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:12:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:12:08 INFO - Installing collected packages: mozsystemmonitor 11:12:08 INFO - Running setup.py install for mozsystemmonitor 11:12:08 INFO - Successfully installed mozsystemmonitor 11:12:08 INFO - Cleaning up... 11:12:08 INFO - Return code: 0 11:12:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:12:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:10 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 0x104ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461cc9de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cd0d30>, '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 0x104bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cc5be8>, '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.lVmkLD5IBX/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.TBf1iKOuPI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 11:12:10 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 11:12:10 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 11:12:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVmkLD5IBX/Render', 11:12:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:10 INFO - 'HOME': '/Users/cltbld', 11:12:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:10 INFO - 'LOGNAME': 'cltbld', 11:12:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:10 INFO - 'MOZ_NO_REMOTE': '1', 11:12:10 INFO - 'NO_EM_RESTART': '1', 11:12:10 INFO - 'PAGER': '/bin/cat', 11:12:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:10 INFO - 'PWD': '/builds/slave/test', 11:12:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:10 INFO - 'SHELL': '/bin/bash', 11:12:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TBf1iKOuPI/Listeners', 11:12:10 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 11:12:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:10 INFO - 'USER': 'cltbld', 11:12:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:10 INFO - 'XPC_FLAGS': '0x0', 11:12:10 INFO - 'XPC_SERVICE_NAME': '0', 11:12:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:10 INFO - Downloading/unpacking jsonschema==2.5.1 11:12:10 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 11:12:10 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 11:12:10 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 11:12:10 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 11:12:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:12:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:12 INFO - Downloading functools32-3.2.3-2.tar.gz 11:12:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:12:12 INFO - warning: no files found matching '*.txt' 11:12:12 INFO - no previously-included directories found matching 'build' 11:12:12 INFO - no previously-included directories found matching 'dist' 11:12:12 INFO - no previously-included directories found matching '.git*' 11:12:12 INFO - Installing collected packages: jsonschema, functools32 11:12:12 INFO - Running setup.py install for functools32 11:12:12 INFO - warning: no files found matching '*.txt' 11:12:12 INFO - no previously-included directories found matching 'build' 11:12:12 INFO - no previously-included directories found matching 'dist' 11:12:12 INFO - no previously-included directories found matching '.git*' 11:12:12 INFO - Successfully installed jsonschema functools32 11:12:12 INFO - Cleaning up... 11:12:12 INFO - Return code: 0 11:12:12 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461cc9de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cd0d30>, '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 0x104bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cc5be8>, '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.lVmkLD5IBX/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.TBf1iKOuPI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 11:12:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:12:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVmkLD5IBX/Render', 11:12:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:12 INFO - 'HOME': '/Users/cltbld', 11:12:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:12 INFO - 'LOGNAME': 'cltbld', 11:12:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:12 INFO - 'MOZ_NO_REMOTE': '1', 11:12:12 INFO - 'NO_EM_RESTART': '1', 11:12:12 INFO - 'PAGER': '/bin/cat', 11:12:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:12 INFO - 'PWD': '/builds/slave/test', 11:12:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:12 INFO - 'SHELL': '/bin/bash', 11:12:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TBf1iKOuPI/Listeners', 11:12:12 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 11:12:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:12 INFO - 'USER': 'cltbld', 11:12:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:12 INFO - 'XPC_FLAGS': '0x0', 11:12:12 INFO - 'XPC_SERVICE_NAME': '0', 11:12:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:12:13 INFO - Cleaning up... 11:12:13 INFO - Return code: 0 11:12:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:13 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 0x104ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461cc9de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cd0d30>, '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 0x104bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cc5be8>, '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.lVmkLD5IBX/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.TBf1iKOuPI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 11:12:13 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 11:12:13 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 11:12:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVmkLD5IBX/Render', 11:12:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:13 INFO - 'HOME': '/Users/cltbld', 11:12:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:13 INFO - 'LOGNAME': 'cltbld', 11:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:13 INFO - 'MOZ_NO_REMOTE': '1', 11:12:13 INFO - 'NO_EM_RESTART': '1', 11:12:13 INFO - 'PAGER': '/bin/cat', 11:12:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:13 INFO - 'PWD': '/builds/slave/test', 11:12:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:13 INFO - 'SHELL': '/bin/bash', 11:12:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TBf1iKOuPI/Listeners', 11:12:13 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 11:12:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:13 INFO - 'USER': 'cltbld', 11:12:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:13 INFO - 'XPC_FLAGS': '0x0', 11:12:13 INFO - 'XPC_SERVICE_NAME': '0', 11:12:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:13 INFO - Downloading/unpacking blobuploader==1.2.4 11:12:13 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 11:12:13 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 11:12:13 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 11:12:13 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 11:12:14 INFO - Downloading blobuploader-1.2.4.tar.gz 11:12:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:12:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:12:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:12:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:12:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:12:15 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 11:12:15 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 11:12:15 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 11:12:15 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 11:12:15 INFO - Downloading docopt-0.6.1.tar.gz 11:12:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:12:15 INFO - Installing collected packages: blobuploader, requests, docopt 11:12:15 INFO - Running setup.py install for blobuploader 11:12:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:12:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:12:15 INFO - Running setup.py install for requests 11:12:16 INFO - Running setup.py install for docopt 11:12:16 INFO - Successfully installed blobuploader requests docopt 11:12:16 INFO - Cleaning up... 11:12:16 INFO - Return code: 0 11:12:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:16 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 0x104ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461cc9de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cd0d30>, '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 0x104bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cc5be8>, '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.lVmkLD5IBX/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.TBf1iKOuPI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 11:12:16 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 11:12:16 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 11:12:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVmkLD5IBX/Render', 11:12:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:16 INFO - 'HOME': '/Users/cltbld', 11:12:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:16 INFO - 'LOGNAME': 'cltbld', 11:12:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:16 INFO - 'MOZ_NO_REMOTE': '1', 11:12:16 INFO - 'NO_EM_RESTART': '1', 11:12:16 INFO - 'PAGER': '/bin/cat', 11:12:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:16 INFO - 'PWD': '/builds/slave/test', 11:12:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:16 INFO - 'SHELL': '/bin/bash', 11:12:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TBf1iKOuPI/Listeners', 11:12:16 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 11:12:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:16 INFO - 'USER': 'cltbld', 11:12:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:16 INFO - 'XPC_FLAGS': '0x0', 11:12:16 INFO - 'XPC_SERVICE_NAME': '0', 11:12:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:12:16 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-JI4mKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:12:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:12:16 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-4GZ1ho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:12:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-eqXcV0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:12:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-1Fk5s1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:12:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-RaqTOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:12:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-3x0c3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:12:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-alJFpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:12:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-tXoZcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:12:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-m50r9o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:12:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-If1AiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:12:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:12:17 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-JhL2NF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:12:18 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-7QF97k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:12:18 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-kPSJaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:12:18 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-AhA7hA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:18 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-yrYNP1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:12:18 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-IKbxPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:12:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:12:18 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-pzRAhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:12:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:12:18 INFO - Running setup.py install for manifestparser 11:12:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:12:18 INFO - Running setup.py install for mozcrash 11:12:18 INFO - Running setup.py install for mozdebug 11:12:19 INFO - Running setup.py install for mozdevice 11:12:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Running setup.py install for mozfile 11:12:19 INFO - Running setup.py install for mozhttpd 11:12:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Running setup.py install for mozinfo 11:12:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Running setup.py install for mozInstall 11:12:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:12:19 INFO - Running setup.py install for mozleak 11:12:19 INFO - Running setup.py install for mozlog 11:12:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:12:20 INFO - Running setup.py install for moznetwork 11:12:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:12:20 INFO - Running setup.py install for mozprocess 11:12:20 INFO - Running setup.py install for mozprofile 11:12:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:12:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:12:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:12:20 INFO - Running setup.py install for mozrunner 11:12:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:12:20 INFO - Running setup.py install for mozscreenshot 11:12:20 INFO - Running setup.py install for moztest 11:12:21 INFO - Running setup.py install for mozversion 11:12:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:12:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:12:21 INFO - Cleaning up... 11:12:21 INFO - Return code: 0 11:12:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12: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')]} 11:12:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12: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')]} 11:12:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:21 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 0x104ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461cc9de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cd0d30>, '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 0x104bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cc5be8>, '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.lVmkLD5IBX/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.TBf1iKOuPI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 11:12:21 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 11:12:21 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 11:12:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVmkLD5IBX/Render', 11:12:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:21 INFO - 'HOME': '/Users/cltbld', 11:12:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:21 INFO - 'LOGNAME': 'cltbld', 11:12:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:21 INFO - 'MOZ_NO_REMOTE': '1', 11:12:21 INFO - 'NO_EM_RESTART': '1', 11:12:21 INFO - 'PAGER': '/bin/cat', 11:12:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:21 INFO - 'PWD': '/builds/slave/test', 11:12:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:21 INFO - 'SHELL': '/bin/bash', 11:12:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TBf1iKOuPI/Listeners', 11:12:21 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 11:12:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:21 INFO - 'USER': 'cltbld', 11:12:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:21 INFO - 'XPC_FLAGS': '0x0', 11:12:21 INFO - 'XPC_SERVICE_NAME': '0', 11:12:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:12:21 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-xWe4J8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:12:21 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)) 11:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:12:21 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-alyARM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:12:21 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)) 11:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:12:21 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-AP9ovL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:12:21 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)) 11:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:12:21 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-sv6oFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:12:21 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)) 11:12:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:12:21 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-wY4t2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:12:22 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)) 11:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:12:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-ci8D6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:12:22 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)) 11:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:12:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-hMimMA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:12:22 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)) 11:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:12:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-ILECnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:12:22 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)) 11:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:12:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-1VnVYE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:12:22 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)) 11:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:12:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-KkmJxB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:12:22 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)) 11:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:12:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-2IIP96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:12:22 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)) 11:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:12:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-3tdXOA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:12:22 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)) 11:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:12:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-KUNxvM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:12:22 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)) 11:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:12:22 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-9tenfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:12:22 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)) 11:12:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:23 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-EnO628-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:12:23 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)) 11:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:12:23 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-xDfE1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:12:23 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)) 11:12:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:12:23 INFO - Running setup.py (path:/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/pip-vJw957-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:12:23 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)) 11:12:23 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)) 11:12:23 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)) 11:12:23 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)) 11:12:23 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)) 11:12:23 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)) 11:12:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:12:23 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 11:12:23 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 11:12:23 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 11:12:23 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 11:12:24 INFO - Downloading blessings-1.6.tar.gz 11:12:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:12:24 INFO - Installing collected packages: blessings 11:12:24 INFO - Running setup.py install for blessings 11:12:24 INFO - Successfully installed blessings 11:12:24 INFO - Cleaning up... 11:12:25 INFO - Return code: 0 11:12:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:12:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:12:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:12:25 INFO - Reading from file tmpfile_stdout 11:12:25 INFO - Current package versions: 11:12:25 INFO - blessings == 1.6 11:12:25 INFO - blobuploader == 1.2.4 11:12:25 INFO - docopt == 0.6.1 11:12:25 INFO - functools32 == 3.2.3-2 11:12:25 INFO - jsonschema == 2.5.1 11:12:25 INFO - manifestparser == 1.1 11:12:25 INFO - mozInstall == 1.12 11:12:25 INFO - mozcrash == 1.0 11:12:25 INFO - mozdebug == 0.1 11:12:25 INFO - mozdevice == 0.49 11:12:25 INFO - mozfile == 1.2 11:12:25 INFO - mozhttpd == 0.7 11:12:25 INFO - mozinfo == 0.9 11:12:25 INFO - mozleak == 0.1 11:12:25 INFO - mozlog == 3.4 11:12:25 INFO - moznetwork == 0.27 11:12:25 INFO - mozprocess == 0.25 11:12:25 INFO - mozprofile == 0.28 11:12:25 INFO - mozrunner == 6.13 11:12:25 INFO - mozscreenshot == 0.1 11:12:25 INFO - mozsystemmonitor == 0.3 11:12:25 INFO - moztest == 0.8 11:12:25 INFO - mozversion == 1.4 11:12:25 INFO - psutil == 3.1.1 11:12:25 INFO - requests == 1.2.3 11:12:25 INFO - wsgiref == 0.1.2 11:12:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:25 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 0x104ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461cc9de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cd0d30>, '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 0x104bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cc5be8>, '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.lVmkLD5IBX/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.TBf1iKOuPI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 11:12:25 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 11:12:25 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 11:12:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVmkLD5IBX/Render', 11:12:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:25 INFO - 'HOME': '/Users/cltbld', 11:12:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:25 INFO - 'LOGNAME': 'cltbld', 11:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:25 INFO - 'MOZ_NO_REMOTE': '1', 11:12:25 INFO - 'NO_EM_RESTART': '1', 11:12:25 INFO - 'PAGER': '/bin/cat', 11:12:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:25 INFO - 'PWD': '/builds/slave/test', 11:12:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:25 INFO - 'SHELL': '/bin/bash', 11:12:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TBf1iKOuPI/Listeners', 11:12:25 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 11:12:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:25 INFO - 'USER': 'cltbld', 11:12:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:25 INFO - 'XPC_FLAGS': '0x0', 11:12:25 INFO - 'XPC_SERVICE_NAME': '0', 11:12:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:25 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)) 11:12:25 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)) 11:12:25 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)) 11:12:25 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)) 11:12:25 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)) 11:12:25 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)) 11:12:25 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)) 11:12:25 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)) 11:12:25 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)) 11:12:25 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)) 11:12:25 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)) 11:12:25 INFO - Cleaning up... 11:12:25 INFO - Return code: 0 11:12:25 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:12:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:12:25 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 0x104ef2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ef5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9461cc9de0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104cd0d30>, '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 0x104bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cc5be8>, '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.lVmkLD5IBX/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.TBf1iKOuPI/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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 11:12:25 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 11:12:25 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 11:12:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVmkLD5IBX/Render', 11:12:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:25 INFO - 'HOME': '/Users/cltbld', 11:12:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:25 INFO - 'LOGNAME': 'cltbld', 11:12:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:25 INFO - 'MOZ_NO_REMOTE': '1', 11:12:25 INFO - 'NO_EM_RESTART': '1', 11:12:25 INFO - 'PAGER': '/bin/cat', 11:12:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:25 INFO - 'PWD': '/builds/slave/test', 11:12:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:25 INFO - 'SHELL': '/bin/bash', 11:12:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TBf1iKOuPI/Listeners', 11:12:25 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 11:12:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:25 INFO - 'USER': 'cltbld', 11:12:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:25 INFO - 'XPC_FLAGS': '0x0', 11:12:25 INFO - 'XPC_SERVICE_NAME': '0', 11:12:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:12:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:12:25 INFO - Cleaning up... 11:12:25 INFO - Return code: 0 11:12:25 INFO - Running post-action listener: _resource_record_post_action 11:12:25 INFO - Running post-action listener: _start_resource_monitoring 11:12:25 INFO - Starting resource monitoring. 11:12:25 INFO - [mozharness: 2017-05-15 18:12:25.825922Z] Finished create-virtualenv step (success) 11:12:25 INFO - [mozharness: 2017-05-15 18:12:25.826857Z] Running install step. 11:12:25 INFO - Running pre-action listener: _resource_record_pre_action 11:12:25 INFO - Running main action method: install 11:12:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:12:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:12:26 INFO - Reading from file tmpfile_stdout 11:12:26 INFO - Detecting whether we're running mozinstall >=1.0... 11:12:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:12:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:12:26 INFO - Reading from file tmpfile_stdout 11:12:26 INFO - Output received: 11:12:26 INFO - Usage: mozinstall [options] installer 11:12:26 INFO - Options: 11:12:26 INFO - -h, --help show this help message and exit 11:12:26 INFO - -d DEST, --destination=DEST 11:12:26 INFO - Directory to install application into. [default: 11:12:26 INFO - "/builds/slave/test"] 11:12:26 INFO - --app=APP Application being installed. [default: firefox] 11:12:26 INFO - mkdir: /builds/slave/test/build/application 11:12:26 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'] 11:12:26 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 11:12:40 INFO - Reading from file tmpfile_stdout 11:12:40 INFO - Output received: 11:12:40 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 11:12:40 INFO - Running post-action listener: _resource_record_post_action 11:12:40 INFO - [mozharness: 2017-05-15 18:12:40.945671Z] Finished install step (success) 11:12:40 INFO - [mozharness: 2017-05-15 18:12:40.945823Z] Running run-tests step. 11:12:40 INFO - Running pre-action listener: _resource_record_pre_action 11:12:40 INFO - Running main action method: run_tests 11:12:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:12:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:12:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:12:40 INFO - 2017-05-15 11:12:40.960 screenresolution[2084:9087] starting screenresolution argv=screenresolution get 11:12:40 INFO - 2017-05-15 11:12:40.962 screenresolution[2084:9087] Display 0: 1600x1200x32@60 11:12:40 INFO - 2017-05-15 11:12:40.968 screenresolution[2085:9089] starting screenresolution argv=screenresolution list 11:12:40 INFO - Available Modes on Display 0 11:12:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:12:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:12:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:12:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:12:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:12:41 INFO - Intel Iris: 11:12:41 INFO - Chipset Model: Intel Iris 11:12:41 INFO - Type: GPU 11:12:41 INFO - Bus: Built-In 11:12:41 INFO - VRAM (Dynamic, Max): 1536 MB 11:12:41 INFO - Vendor: Intel (0x8086) 11:12:41 INFO - Device ID: 0x0a2e 11:12:41 INFO - Revision ID: 0x0009 11:12:41 INFO - Displays: 11:12:41 INFO - Display: 11:12:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:12:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:12:41 INFO - Display Serial Number: 1600x1200 60 11:12:41 INFO - Main Display: Yes 11:12:41 INFO - Mirror: Off 11:12:41 INFO - Online: Yes 11:12:41 INFO - Rotation: Supported 11:12:41 INFO - Return code: 0 11:12:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:12:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:12:41 INFO - Python 2.7.3 11:12:41 INFO - Return code: 0 11:12:41 INFO - grabbing minidump binary from tooltool 11:12:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:12:41 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 0x104cd0d30>, '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 0x104bf7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104cc5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:12:41 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 11:12:41 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 11:12:41 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 11:12:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:12:41 INFO - Return code: 0 11:12:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:12:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:12:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:12:41 INFO - ENV: RUST_BACKTRACE is now 1 11:12:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:12:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:12:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0374', '--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 11:12:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0374 --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 11:12:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.lVmkLD5IBX/Render', 11:12:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:12:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:12:41 INFO - 'HOME': '/Users/cltbld', 11:12:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:12:41 INFO - 'LOGNAME': 'cltbld', 11:12:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:12:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:12:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:12:41 INFO - 'MOZ_NO_REMOTE': '1', 11:12:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:12:41 INFO - 'NO_EM_RESTART': '1', 11:12:41 INFO - 'PAGER': '/bin/cat', 11:12:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:12:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:12:41 INFO - 'PWD': '/builds/slave/test', 11:12:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:12:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:12:41 INFO - 'RUST_BACKTRACE': '1', 11:12:41 INFO - 'SHELL': '/bin/bash', 11:12:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.TBf1iKOuPI/Listeners', 11:12:41 INFO - 'TMPDIR': '/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/', 11:12:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:12:41 INFO - 'USER': 'cltbld', 11:12:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:12:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:12:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:12:41 INFO - 'XPC_FLAGS': '0x0', 11:12:41 INFO - 'XPC_SERVICE_NAME': '0', 11:12:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:12:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0374', '--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 11:12:41 INFO - mozversion application_buildid: 20170515094125 11:12:41 INFO - mozversion application_changeset: eeeee18148df4e3a4b701aeffcbf490945d99267 11:12:41 INFO - mozversion application_display_name: Firefox 11:12:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:12:41 INFO - mozversion application_name: Firefox 11:12:41 INFO - mozversion application_remotingname: firefox 11:12:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:12:41 INFO - mozversion application_vendor: Mozilla 11:12:41 INFO - mozversion application_version: 54.0 11:12:41 INFO - mozversion platform_buildid: 20170515094125 11:12:41 INFO - mozversion platform_changeset: eeeee18148df4e3a4b701aeffcbf490945d99267 11:12:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:12:41 INFO - mozversion platform_version: 54.0 11:12:41 INFO - using testdate: 1494871961 11:12:41 INFO - actual date: 1494871961 11:12:41 INFO - starting webserver on 'localhost:49237' 11:12:41 INFO - SUITE-START | Running 2 tests 11:12:41 INFO - TEST-START | dromaeo_css 11:12:41 INFO - operating with platform_type : mac_ 11:12:41 INFO - Initialising browser for dromaeo_css test... 11:12:41 INFO - TEST-INFO | started process 2095 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpSXKcPR/profile http://localhost:49237/getInfo.html) 11:12:44 INFO - PROCESS | 2095 | 2017-05-15 11:12:44.186 plugin-container[2098:9258] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f4b, name = 'com.apple.tsm.portname' 11:12:44 INFO - PROCESS | 2095 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:12:44 INFO - PROCESS | 2095 | 2017-05-15 11:12:44.186 plugin-container[2098:9258] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4b23, name = 'com.apple.CFPasteboardClient' 11:12:44 INFO - PROCESS | 2095 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:12:44 INFO - PROCESS | 2095 | 2017-05-15 11:12:44.186 plugin-container[2098:9258] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:12:44 INFO - PROCESS | 2095 | __metrics Screen width/height:1600/1200 11:12:44 INFO - PROCESS | 2095 | colorDepth:24 11:12:44 INFO - PROCESS | 2095 | Browser inner width/height: 1024/689 11:12:44 INFO - PROCESS | 2095 | __metrics 11:12:44 ERROR - PROCESS | 2095 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 11:12:44 INFO - TEST-INFO | 2095: exit 0 11:12:44 INFO - Browser initialized. 11:12:44 INFO - Running cycle 1/1 for dromaeo_css test... 11:12:44 INFO - TEST-INFO | started process 2100 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmpSXKcPR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:12:47 INFO - PROCESS | 2100 | 2017-05-15 11:12:47.206 plugin-container[2101:9397] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 11:12:47 INFO - PROCESS | 2100 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:12:47 INFO - PROCESS | 2100 | 2017-05-15 11:12:47.206 plugin-container[2101:9397] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 11:12:47 INFO - PROCESS | 2100 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:12:47 INFO - PROCESS | 2100 | 2017-05-15 11:12:47.206 plugin-container[2101:9397] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:12:57 INFO - PROCESS | 2100 | RSS: Main: 174567424 11:12:57 INFO - PROCESS | 2100 | 11:15:59 INFO - PROCESS | 2100 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 11:16:01 INFO - PROCESS | 2100 | RSS: Main: 189054976 11:16:01 INFO - PROCESS | 2100 | 11:19:03 INFO - PROCESS | 2100 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 11:19:04 INFO - PROCESS | 2100 | RSS: Main: 198508544 11:19:04 INFO - PROCESS | 2100 | 11:22:06 INFO - PROCESS | 2100 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 11:22:06 INFO - PROCESS | 2100 | RSS: Main: 204800000 11:22:06 INFO - PROCESS | 2100 | 11:25:08 INFO - PROCESS | 2100 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 11:25:09 INFO - PROCESS | 2100 | RSS: Main: 207933440 11:25:09 INFO - PROCESS | 2100 | 11:28:12 INFO - PROCESS | 2100 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 11:28:12 INFO - PROCESS | 2100 | RSS: Main: 210477056 11:28:12 INFO - PROCESS | 2100 | 11:31:15 INFO - PROCESS | 2100 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 11:31:15 INFO - PROCESS | 2100 | RSS: Main: 208338944 11:31:15 INFO - PROCESS | 2100 | 11:31:15 INFO - PROCESS | 2100 | __start_tp_report 11:31:15 INFO - PROCESS | 2100 | _x_x_mozilla_page_load 11:31:15 INFO - PROCESS | 2100 | _x_x_mozilla_page_load_details 11:31:15 INFO - PROCESS | 2100 | |i|pagename|runs| 11:31:15 INFO - PROCESS | 2100 | |0;dojo.html;3507.2108775525494;3544.1848374873775;3579.534660494137;3590.4614307853817;3593.694535964443;6082.908756368653;6096.4513193812545;6146.170267013952;6193.597416167946;6207.5033997280225;4679.859604211879;6160.784372546962;6204.503639708823;6231.252249730027;6231.376862313778;5447.945520544793;5523.447655234465;5531.115021596545;5540.9168862467095;5595.832125036255;5176;5579.13523403871;5586.944130558703;5599.468050535193;5635.267415236013;5324.935012997416;5372.382176049765;5518.530924871391;5529.502344788988;5540.50135487806;5419.295491586068;5445.50990410865;5450.264214331054;5462.890742185174;5511.503964643161;5430.91853622196;5450.91823622646;5463.453654634544;5466.699331536764;5472.425395333473;4809.254565542346;5932.377100404482;5973;6091.208142941389;6176.413240742122;6380.298167201604;6433.549651524395;6442.645654489002;6460.22477302722;6470.514711396664;6590.967045164792;6596.175478065242;6597.538172327939;6597.93402065978;6602.636854972975;6251.156093927352;6294.213223347082;6297.33877942814;6316.463100636452;6324.841878953063;6305.527085468608;6481.578697384655;6669.699863506154;6670.099536562599;6717.193936727624;6407.967960160169;6433.806985790434;6438.517111216677;6445.742170313229;6457.773977910751;5149.922751158736;6548.476121910236;6589.110470086573;6623.4701223901975;6739.460843132538;6280.403361680633;6355.205599300088;6396.744130234832;6419.839504012437;6486.351364863476;6489.415952564292;6525;6533.314002029813;6535.281119076898;6546.214454265423;4724.173269677793;5240.502152295526;5242.999999999924;5255.343082114736;5298.993191293642;4145.88061223468;4543.4775000875325;4571.634269258451;4590.97704511482;4626.930596041062;5212.765425555859;6224.346443623445;6285.214348206474;6293.2762732285455;6330.778422755181;5585.661107950867;6567.802965911031;6587.83530411734;6633.834154146089;6635.435987940982;6061.575689701766;6088.238970128734;6157.630542167484;6178;6215.254169499689;4904.852854414374;4918.729469879119;5811.012127938261;5838;5898.852528686817;6732.999999999902;6737.764178253836;6749.392554670103;6753.358430949052;6765.985102234704;6333.714982825876;6349.396807303298;6512.446442052289;6553.705083271359;6563.113979612882;6729.528932974645;6757.3242675732035;6771;6772.390484856386;6786.0838786765125;6510.414062734377;6539.509536784855;6542.901856472062;6544.901826472702;6554.901676474952;5188.688678679291;6321.49428045746;6447.258565265054;6617.669116544057;6650.102236198051;6217.533684973555;6387.552871298964;6410.935890641221;6457.6448295342325;6459.321771213955;6434.453071488976;6438;6438.356164383524;6444.903326450013;6455.838604034937;6117.796956032775;6180.351063138485;6254.655993920378;6282.654454005074;6323.399277068856;6330.556861019869;6335.936640633719;6351.396617321347;6374.107624932421;6384.010478375859;6200.0699895016105;6337.841553961188;6338.207724034496;6347.301796802256;6349.9682501587195 11:31:15 INFO - PROCESS | 2100 | |1;ext.html;786.7482405630197;829.001053730255;852.2883392367374;916.6287653500331;985.6353149334748;38424.61575384245;39380;39515.209695806065;40869.978250543754;40881.79559102208;31763.205919852015;39864.202715945736;39938.8003059985;39963;40052.79973600128;8042.7185048523315;8189.918100819005;8326.50040997542;8442.282405995498;8462.957685211566;12022.759544809098;12212.084093692964;12248.203866748632;12251.999999999955;12290.385480725972;12224.388780560981;12240.632781016584;12314.75370492594;12331.321777302244;12343.691407714789;12261.448234829453;12266.509339626404;12266.509339626404;12282.01743860489;12285.508579656804;11877.762444751143;11894.464749086312;11942.402879855972;11959.820602690967;11994.64016079519;41689.1662166755;41964.580354196376;41990.950226244284;41995.37006944898;42019;3962.2075584883137;3964.7621142731527;3996.4205190247544;4013.779242141681;4029.5164580250557;29431.85284073566;29551.817927282882;29928.551071733942;30892.8455357724;31528.842355788303;3213.9196520086953;3353.4802105673416;3445.793252404864;3452.3267962747273;3474.1488335357744;317011;373129;379638;380709;382224;43505.347419788726;44478;50481;53175.468245317825;53223.73388133034;47059.82350441266;57830.13254801181;58191.709041454524;58544;58709.70645146747;15758.448454304043;15846.366145354288;15849;15855.207239638203;15876.523704288658;13300.534481293109;13327.466901323838;13335.93332033353;13363.933180334036;13368;13068.803967940488;13138.671533211555;13159.868401316056;13248.006399520074;13272.867271327163;5858.5898987070495;5888.381719919433;5912.556558258148;5925.081612350093;5926;1143.5128595305343;1148.5520646947698;1162.7325715085578;1172.8006238939402;1216.1547724331504;21222.78777212239;27722.5841612376;28607.427851442855;28627.14118576446;28685.56971645427;1084.3896813232343;1248.8126780982925;1265.9620211393674;1298.7077907470743;1309.1752196116577;14701.92649036748;15493.147876866655;15928.203589820463;16219.837801621832;16471.599914007205;326326;328437;330289;333678.6632133696;334473;58367;58802.705986469795;58974;58989.11516327259;58998.820023599284;1315.8552559218479;1504.1125735815701;1541.1600677630943;1550.8601178134063;1557.322564684366;26598.867005665623;27608.585871212348;28548.714512855437;28873.85563072171;29353.412931741674;46711.76644116758;46861.828454287555;46907.76546117248;46973.530264696914;46986.530134698216;311739;312818;313170;313216;314310;30847.84576077105;33251.33497330088;33897;34819;36455;41416.75749727513;41617.1676566473;42419;42424.78787606042;43020.78489607532;29251;29400.26499337534;29815.85092074526;29979.70020299769;30393.392132157674;29257.26856828511;29504.262393440335;29843.95546155874;30085.69914300829;30223;35007.64992350044;35585.1103722409;35766.463503048;35817.283654326246;35839;26058.86970565211;26666.733332666427;27316.31709207286;27428.314292142855;27474.450510990067;20579.794202057787;22472.438189045406;22551.77448225497;22573;22599.322020339416 11:31:15 INFO - PROCESS | 2100 | |2;jquery.html;353.4609720176729;367.46533793330707;417.43854515676406;426.89754458929866;439.83066519390024;16980.915095424538;18321.72517412238;21627.891860540694;22130.44673883154;22374.664380034294;22322.553548929052;22685;22909;22931.082756689753;22939.853007349648;6854.691538880737;7025.648717564101;7046.295370462938;7064.6114463704735;7100.218975912646;6789.321067893221;6995.895061574055;7004.299570043006;7075.681594328267;7095.0776399068045;7057.223705392403;7087.9291207088045;7103.751368702096;7111.004459375691;7440;6596.670166491656;7766;7864.646090925922;7906.604669766518;7931.762047138595;6954.513184077116;7268.927310726931;7607.782754759212;7729.227077292282;7791.688332466694;25603.359916002064;26244.868775656192;26697.733022669912;26984;27064.646767661736;2644.4843255565174;2985.1939976206518;2994.1317018064733;3061.9693803061905;3071.3703690743346;16253.837461625351;16452.588685282844;16589.419370321983;16756;17042.31830726782;2139.5934772393193;2168.7011767526474;2170.6418440957223;2179.618566750828;2225.9777402226096;147214;149185;157200;157384.99999999886;159243;20473.18107275722;20875.58248835045;21148.682769758467;21181;21235.362939111852;31701.20746981344;31888;31936.680633193835;43345.56654433415;45138.77430612826;12226.327551984727;12305.815412768816;12313.199642023239;12516;12645.367731613382;8792.648294068293;9354.345195836226;9885.25860560461;10203.846942295871;10459.94770026145;9670.371425857296;11962.880371196177;12111.333876636694;12164.513419463123;12309.815352769716;4187.392828039917;4562.855457934766;4944.752762361867;5042.991401719671;5145.845624631267;1049.2026060194155;1091.307020042279;1112.9666110016713;1129.6611016695124;1160.6692092753694;21331.46671333229;21435.249766258417;21641.13435462564;21676.24133155332;21718.565628687338;1016.2022812092472;1041.4896700616796;1114.2144787924494;1151.5739176504746;1153.7865494883474;9147.908520914838;10673.57305707763;11165.38590377537;11318.660440186808;11379.260348077514;660525;735607;739432;745190;777608;157464;165527;166611;169680;170540;1216.598522487577;1243.2975368916534;1269.4224128021729;1271.853736820259;1279.609719035703;18334.449966501026;18712.906435467736;18996.810031899506;19000.049997500348;19244;62376;66883;70501.64749176426;70578.94131588133;70826.29173708196;81213;81720;90428;91641;93339;9637.9036209637;10083.596656133672;10422.947885260828;11733.823992640288;12121.818172727593;13617.31913404306;13735;13742.381592828147;14082.929585352007;14140.15159090458;8787;9633.951830240803;11430.142739294752;11482.655520334403;11708.356040417859;8534.189252021297;10234.590616375262;10634.84047739269;10825.621103261348;10995.835062473909;11820;11831.526738930346;11857.525698971944;12110.45502952387;12205.511779528719;11957.94021029924;12000.819987700366;12009.339486328336;12273.877261227273;12292.201006934522;9656.565454554422;9767.951160244153;11737.295762254293;11783.410829458664;12113.515459381526 11:31:15 INFO - PROCESS | 2100 | |3;mootools.html;1638.5166375919102;1667.291401154509;1672.8670944545695;1673.9414120505785;1693.8221486743892;9842.655507057254;10102.797944041133;10723.249372543925;11329.716757081078;11338.829917551233;11172.273802202872;11522.769544609102;11807.11446641501;11834.23077499962;11835;2419.407245224918;2471.0980492120425;2474.8762561871927;2476.5418397596413;2480.838745481538;2093.884836334001;2346.6597343385197;2427.745086765891;2438.4757277185404;2438.7683170098812;2371.691680081587;2414.396400899775;2418.286605451396;2423.53952748978;2423.866687332196;2379.9524009519887;2384.666146739458;2392.449736560592;2400.7719266669637;2402.0631953538223;2367.6448532720055;2388.5700573896693;2393.1743548475747;2396.9640455393182;2400.8919598618104;11512.136589755719;11695.35675537845;11761.58834440799;11765.235259708177;11790.823137652942;5734.369219385864;5945;5950.672713000783;5968.820935371946;5969.970150149265;3573.4818451324413;3669.7064234861414;3686.3364594373;3733.8798360492015;3736.0099573613015;1260.5840072775961;1264.8355713757244;1286.6011536423646;1313.9605811825566;1314.5859054397833;15977.041377517386;16138.677226455522;16155.273012714457;16178.676426471404;16218.594535136597;4695.859124226278;4887.608991280689;4948.134076536592;4949.529794669501;4961.107000739901;13464.192148471124;13475.730485390237;13505.527306544223;13540.661483462794;13554.186748795104;5994.670293133832;6012.458878700938;6021.578489505692;6034.09488576717;6051.0923361496125;5143.202803565453;5234.110201265794;5329.9467005330225;5344.599155063386;5355.410904800468;4037.6769858411258;4137.480060635493;4144.212599606126;4179.393987871742;4260.147970405931;1122.6183097746805;1125.752334486428;1129.2264798612975;1130.0620484997432;1144.3991904250336;932.6036434515304;944.7543638653862;950.3252690589759;959.7600599850037;1003.8243307421318;6559.770408035792;6754.797356079325;6765.188177418741;6872.484563657746;6974.302569742985;834.3992325525612;922.1331947968888;1022.2026819081128;1043.76515284062;1051.75283808305;3450.8274586270586;3518.3842827505086;4251.404803327537;4364.214441400578;4378.934315985262;13596.86403135956;14110.35585765702;14191.290435478391;14326.212058336683;14330.996830221993;13990.230537320342;14284.928575357264;14367.20980346091;14376.065555738844;14429.85570144306;8602.096779838152;9178.67874624385;10555.313904596176;10590.523426445829;10595.629152979609;6559.639219842955;6580.638064906476;6631.270560238467;6648.335166483313;6716.731330746716;12549.93725031369;12555;12656.303903285409;12709.87290127124;12726;10194.388336699822;12719.618411447302;12753.234805911676;12969.54606588765;13176.538821141598;8796.208341249318;9188.58651360677;9355.906440935503;9406.576704048472;9440.244780417688;10394.58421663125;10420.374777513374;10431.895681043092;10515.631952881613;10554.630587929385;8865.468071915706;8964.193222609736;9032.86450703227;9045.864312035194;9244.676436324697;7205.855882882208;8838.204561589488;8906.86639700392;8974.86537701922;9017.458952462874;7882;8012.919870801217;8068.394870384627;8127.593620318842;8130.959345203472;5969.164316995711;5991.2510936132985;5998.1002849572915;6165.84585385369;6231.532635052298;6088.299845517822;6126.754929802758;6225.252969643671;6245.0632405139595;6278.121063051085 11:31:15 INFO - PROCESS | 2100 | |4;prototype.html;338.45170823266295;391.3308242904633;392.7682667226336;401.1855932457112;404.31872295182603;4211.536730959592;4397.6701747369;4830.541098595632;5163.32876726026;5260.710660913648;4277.764722940237;4354.629856462197;4469.620082293;5175.24958880962;5195.714235717034;4075.816588253521;4468.664850136237;4522.924411400247;4553.521880202581;4567.246404343279;4016.5180178378637;4081.5714499925084;4122.557105013237;4218.093109981353;4626.514216007321;4213.536510983789;4230.407742915979;4588.403507544014;4594.74728889911;4598;3715.8262416486386;3799.563050249229;3980.5223373195404;4093.079057212133;4506.954930450686;3629.8366573504254;4288.656907447396;4297.6677230058785;4338.761368124752;4387;4426.867193984185;4556.658250631182;5156.871078223075;5188.558972487343;5219.164933610642;4770.856874293777;4969.378827646544;5021.6735912165595;5311.601629877775;5374.4894235048;4589.334546490773;4929.753512324405;5142;5601;5721.370649228582;4121.732087414309;4137.77242251676;4661.930071048937;4891.388576427947;4897.8775530611665;5535.201059655628;5560.471755183251;5735.655860648374;5958.212536239111;5984.401559844025;4799.736014519235;4800.759962001886;4818.578519336804;4835.95164048362;4838.95161048392;4189.162167566499;4418.315161150027;4508.819647214074;4699.389079419653;4871.342368780241;4705.670603057822;4804.35141255926;4941.925871111936;5145.511176438233;5273.841784746463;4839.104765618372;5269.894602107936;5294.391145018372;5314.707691077027;5549.361823390281;1632.3633782824709;1663.69221693987;1744.7121224997734;1752.3603884582035;1758.9736153957701;1269.504893091695;1285.724268045573;1331.42083193811;1427.129451034868;1437.5040610989192;5237.423883372826;5273.499017593323;5289.3917199521775;5300.257963885059;5323.22813192085;4998.6500944933505;5023.718951667374;5719.799807006818;5787.151544223045;5847.473727364558;4513.368128462018;4944.90110197794;5170.741462926839;5296.1261391870685;5339.786408543692;2967.866446009935;2978.047024952038;2986.2385091801452;2987.850607469618;3006.3987202559574;5029.84910452687;5048.596112311006;5791.681457519877;5957.851053723692;6058.515318774487;4902.485239049848;5704.830509745492;5860.033094539353;5867.765289388462;5969.552283578749;5001.549860512571;5520.3565646673005;5659.726561523624;5864.560157988082;5981.850453738692;5136.640435169502;5245.475452454724;5413.756380962945;5694.810905892713;5866.853328666817;4952.628552858478;5558.249636299048;5558.471945165203;5609.943900560942;5618.775248989995;4687.507811679861;4928.901421971612;5091.646642121082;5545.694986775766;5621.634593751393;4143.56492568288;4175.498940127204;4450.777461126866;4738.957429365534;5064.366954130734;4880.706612747554;5025.522575355335;5385.111456609616;5531.9723401382735;5650.124230744241;4644.349791029326;4854.68444551103;5099.286099945936;5425.321834770653;5599.32808063035;4906.607471402208;5027.622928280467;5164.509371609691;5396.3524377075;5513.889722205614;5027.698338099725;5259.947400525945;5385.219143224289;5461.678786515794;5474.781008759605;4777.880552986203;4817.78319975609;5332.33345831771;5494.835154945358;5506.779728810803;4564.207582038912;4945.579625731853;4983.8504844854715;5428.7557059931605;5530.91703624454 11:31:15 INFO - PROCESS | 2100 | |5;yui.html;624.6970219443572;744.226004954847;753.7211231844215;900.3832374823245;902.9819403611928;16012.03927764335;17397.391091311805;17404.82595174048;17463.563410914732;17511.912440437794;16004.359825606962;16013.19934003301;16031.35874565019;16054.518364449055;16222.432214872453;963.4219468319022;971.0871780526318;993.662154867345;997.9301448898543;1009.1321463541349;994.1698681600872;1007.7178390050798;1009.2632378363799;1009.5860697114184;1010.2524132142198;1046.2937517175915;1046.7540128069897;1049.774298525817;1050.3697781331216;1050.3907733514582;1020.4897551224387;1023;1042.4370839746603;1044.3942513342242;1044.650042235848;779.0884664942031;779.3959681247079;781.1680560203422;781.1953687701676;782.5304817109688;17177.65644687099;17195.570110747205;17209.1395430228;18121.275148994024;18358.724619130724;14400.567982960423;14453.277336133151;14792.11247325153;14951.775723364159;15083.396664133532;12027.216826391033;12082.27506349613;12300.81548776769;12816.038797090163;12893.742125157445;3825.8331208981526;3835.5780864104927;3942.99453639323;4039.171969746195;4076.8165432555606;92104;112577.43711281392;113944;114380;116659.83340166659;11053.392063436428;11062.81708513192;11100.722481938015;11407.543698252142;11664.358460284597;23535.293941181793;23535.88232058863;23536.7646323538;23545.175918843102;23565;8063.637136328879;8149.592520373958;8234.300084492885;8262.710805121791;8281.799139124792;4514.212142220475;4548.476925153584;4666.2067448533835;4716.434027916672;4867.440244371872;3544.149404142987;3581.83881725323;3594.928101438009;3631.963680363215;3643.2349206666895;645.9334876451604;646.8415238266655;648.0506058624138;648.9870202595922;657.5955787190843;702.796189105164;711.6263961420295;723.1430754555868;734.2730696610317;751.1136858506906;10699.197560183018;10810.35137891729;11000.779984400267;11275.097992160607;11352.262102963447;698.7449580903035;724.409606170969;736.3556887723242;747.3124725252671;752.3229093815501;4659.510751371125;5287.7884884604955;5294.788208471618;5298.337707786527;5306.495882891119;103804;107824.8435031295;108197;109070.45464772783;109292;96328.03671963331;99149.50425247828;99479;100599.49700251452;102433;71129.93305100428;71672.64163679148;82099;85696;86850.56574717212;9738.951305243429;9978.900210998088;10256.230782691477;10406.10269297669;11529.654110376701;39121;42164.156716866106;47671.61641917821;48415.5158448411;51864.48135518596;70513.64743176251;71980.64009679918;72364.27635723575;73025.90461143194;73764.26235737574;13099.410526526139;13336.733265334446;13441.260730659907;13519.11389826107;13815;15206.93551451432;15353.692926141191;15658.608534786721;15753.842461575237;16465.34138634441;13839.654008649864;14423.639409014859;14485;14530.200838953959;14613.123212607277;14275.427453356013;14325.426982920566;14356;14493.347799348843;14643.707125857636;13320.147047070937;14337;14349.713005739617;14395.928020359832;14549.272536373352;9904.306698531322;10307.742306442398;10696;10765.73085672833;11021.952914473111;8083.63623636926;10029.077687457058;11409.087273017973;11771.411429428666;11788.823167652321 11:31:15 INFO - PROCESS | 2100 | __end_tp_report 11:31:15 INFO - PROCESS | 2100 | __start_cc_report 11:31:15 INFO - PROCESS | 2100 | _x_x_mozilla_cycle_collect,393 11:31:15 INFO - PROCESS | 2100 | __end_cc_report 11:31:15 INFO - PROCESS | 2100 | __startTimestamp1494873075950__endTimestamp 11:31:15 INFO - PROCESS | 2100 | 11:31:15 INFO - PROCESS | 2100 | ------- Summary: start ------- 11:31:15 INFO - PROCESS | 2100 | Number of tests: 6 11:31:15 INFO - PROCESS | 2100 | 11:31:15 INFO - PROCESS | 2100 | [#0] dojo.html Cycles:165 Average:6018.90 Median:6305.53 stddev:716.06 (11.4%) stddev-sans-first:690.62 11:31:15 INFO - PROCESS | 2100 | Values: 3507.2 3544.2 3579.5 3590.5 3593.7 6082.9 6096.5 6146.2 6193.6 6207.5 4679.9 6160.8 6204.5 6231.3 6231.4 5447.9 5523.4 5531.1 5540.9 5595.8 5176.0 5579.1 5586.9 5599.5 5635.3 5324.9 5372.4 5518.5 5529.5 5540.5 5419.3 5445.5 5450.3 5462.9 5511.5 5430.9 5450.9 5463.5 5466.7 5472.4 4809.3 5932.4 5973.0 6091.2 6176.4 6380.3 6433.5 6442.6 6460.2 6470.5 6591.0 6596.2 6597.5 6597.9 6602.6 6251.2 6294.2 6297.3 6316.5 6324.8 6305.5 6481.6 6669.7 6670.1 6717.2 6408.0 6433.8 6438.5 6445.7 6457.8 5149.9 6548.5 6589.1 6623.5 6739.5 6280.4 6355.2 6396.7 6419.8 6486.4 6489.4 6525.0 6533.3 6535.3 6546.2 4724.2 5240.5 5243.0 5255.3 5299.0 4145.9 4543.5 4571.6 4591.0 4626.9 5212.8 6224.3 6285.2 6293.3 6330.8 5585.7 6567.8 6587.8 6633.8 6635.4 6061.6 6088.2 6157.6 6178.0 6215.3 4904.9 4918.7 5811.0 5838.0 5898.9 6733.0 6737.8 6749.4 6753.4 6766.0 6333.7 6349.4 6512.4 6553.7 6563.1 6729.5 6757.3 6771.0 6772.4 6786.1 6510.4 6539.5 6542.9 6544.9 6554.9 5188.7 6321.5 6447.3 6617.7 6650.1 6217.5 6387.6 6410.9 6457.6 6459.3 6434.5 6438.0 6438.4 6444.9 6455.8 6117.8 6180.4 6254.7 6282.7 6323.4 6330.6 6335.9 6351.4 6374.1 6384.0 6200.1 6337.8 6338.2 6347.3 6350.0 11:31:15 INFO - PROCESS | 2100 | 11:31:15 INFO - PROCESS | 2100 | [#1] ext.html Cycles:180 Average:49444.23 Median:312278.50 stddev:88687.27 (28.4%) stddev-sans-first:88860.82 11:31:15 INFO - PROCESS | 2100 | Values: 786.7 829.0 852.3 916.6 985.6 38424.6 39380.0 39515.2 40870.0 40881.8 31763.2 39864.2 39938.8 39963.0 40052.8 8042.7 8189.9 8326.5 8442.3 8463.0 12022.8 12212.1 12248.2 12252.0 12290.4 12224.4 12240.6 12314.8 12331.3 12343.7 12261.4 12266.5 12266.5 12282.0 12285.5 11877.8 11894.5 11942.4 11959.8 11994.6 41689.2 41964.6 41991.0 41995.4 42019.0 3962.2 3964.8 3996.4 4013.8 4029.5 29431.9 29551.8 29928.6 30892.8 31528.8 3213.9 3353.5 3445.8 3452.3 3474.1 317011.0 373129.0 379638.0 380709.0 382224.0 43505.3 44478.0 50481.0 53175.5 53223.7 47059.8 57830.1 58191.7 58544.0 58709.7 15758.4 15846.4 15849.0 15855.2 15876.5 13300.5 13327.5 13335.9 13363.9 13368.0 13068.8 13138.7 13159.9 13248.0 13272.9 5858.6 5888.4 5912.6 5925.1 5926.0 1143.5 1148.6 1162.7 1172.8 1216.2 21222.8 27722.6 28607.4 28627.1 28685.6 1084.4 1248.8 1266.0 1298.7 1309.2 14701.9 15493.1 15928.2 16219.8 16471.6 326326.0 328437.0 330289.0 333678.7 334473.0 58367.0 58802.7 58974.0 58989.1 58998.8 1315.9 1504.1 1541.2 1550.9 1557.3 26598.9 27608.6 28548.7 28873.9 29353.4 46711.8 46861.8 46907.8 46973.5 46986.5 311739.0 312818.0 313170.0 313216.0 314310.0 30847.8 33251.3 33897.0 34819.0 36455.0 41416.8 41617.2 42419.0 42424.8 43020.8 29251.0 29400.3 29815.9 29979.7 30393.4 29257.3 29504.3 29844.0 30085.7 30223.0 35007.6 35585.1 35766.5 35817.3 35839.0 26058.9 26666.7 27316.3 27428.3 27474.5 20579.8 22472.4 22551.8 22573.0 22599.3 11:31:15 INFO - PROCESS | 2100 | 11:31:15 INFO - PROCESS | 2100 | [#2] jquery.html Cycles:180 Average:43530.87 Median:21658.69 stddev:122659.67 (566.3%) stddev-sans-first:122960.92 11:31:15 INFO - PROCESS | 2100 | Values: 353.5 367.5 417.4 426.9 439.8 16980.9 18321.7 21627.9 22130.4 22374.7 22322.6 22685.0 22909.0 22931.1 22939.9 6854.7 7025.6 7046.3 7064.6 7100.2 6789.3 6995.9 7004.3 7075.7 7095.1 7057.2 7087.9 7103.8 7111.0 7440.0 6596.7 7766.0 7864.6 7906.6 7931.8 6954.5 7268.9 7607.8 7729.2 7791.7 25603.4 26244.9 26697.7 26984.0 27064.6 2644.5 2985.2 2994.1 3062.0 3071.4 16253.8 16452.6 16589.4 16756.0 17042.3 2139.6 2168.7 2170.6 2179.6 2226.0 147214.0 149185.0 157200.0 157385.0 159243.0 20473.2 20875.6 21148.7 21181.0 21235.4 31701.2 31888.0 31936.7 43345.6 45138.8 12226.3 12305.8 12313.2 12516.0 12645.4 8792.6 9354.3 9885.3 10203.8 10459.9 9670.4 11962.9 12111.3 12164.5 12309.8 4187.4 4562.9 4944.8 5043.0 5145.8 1049.2 1091.3 1113.0 1129.7 1160.7 21331.5 21435.2 21641.1 21676.2 21718.6 1016.2 1041.5 1114.2 1151.6 1153.8 9147.9 10673.6 11165.4 11318.7 11379.3 660525.0 735607.0 739432.0 745190.0 777608.0 157464.0 165527.0 166611.0 169680.0 170540.0 1216.6 1243.3 1269.4 1271.9 1279.6 18334.4 18712.9 18996.8 19000.0 19244.0 62376.0 66883.0 70501.6 70578.9 70826.3 81213.0 81720.0 90428.0 91641.0 93339.0 9637.9 10083.6 10422.9 11733.8 12121.8 13617.3 13735.0 13742.4 14082.9 14140.2 8787.0 9634.0 11430.1 11482.7 11708.4 8534.2 10234.6 10634.8 10825.6 10995.8 11820.0 11831.5 11857.5 12110.5 12205.5 11957.9 12000.8 12009.3 12273.9 12292.2 9656.6 9768.0 11737.3 11783.4 12113.5 11:31:15 INFO - PROCESS | 2100 | 11:31:15 INFO - PROCESS | 2100 | [#3] mootools.html Cycles:180 Average:6943.17 Median:2438.62 stddev:4412.34 (180.9%) stddev-sans-first:4406.71 11:31:15 INFO - PROCESS | 2100 | Values: 1638.5 1667.3 1672.9 1673.9 1693.8 9842.7 10102.8 10723.2 11329.7 11338.8 11172.3 11522.8 11807.1 11834.2 11835.0 2419.4 2471.1 2474.9 2476.5 2480.8 2093.9 2346.7 2427.7 2438.5 2438.8 2371.7 2414.4 2418.3 2423.5 2423.9 2380.0 2384.7 2392.4 2400.8 2402.1 2367.6 2388.6 2393.2 2397.0 2400.9 11512.1 11695.4 11761.6 11765.2 11790.8 5734.4 5945.0 5950.7 5968.8 5970.0 3573.5 3669.7 3686.3 3733.9 3736.0 1260.6 1264.8 1286.6 1314.0 1314.6 15977.0 16138.7 16155.3 16178.7 16218.6 4695.9 4887.6 4948.1 4949.5 4961.1 13464.2 13475.7 13505.5 13540.7 13554.2 5994.7 6012.5 6021.6 6034.1 6051.1 5143.2 5234.1 5329.9 5344.6 5355.4 4037.7 4137.5 4144.2 4179.4 4260.1 1122.6 1125.8 1129.2 1130.1 1144.4 932.6 944.8 950.3 959.8 1003.8 6559.8 6754.8 6765.2 6872.5 6974.3 834.4 922.1 1022.2 1043.8 1051.8 3450.8 3518.4 4251.4 4364.2 4378.9 13596.9 14110.4 14191.3 14326.2 14331.0 13990.2 14284.9 14367.2 14376.1 14429.9 8602.1 9178.7 10555.3 10590.5 10595.6 6559.6 6580.6 6631.3 6648.3 6716.7 12549.9 12555.0 12656.3 12709.9 12726.0 10194.4 12719.6 12753.2 12969.5 13176.5 8796.2 9188.6 9355.9 9406.6 9440.2 10394.6 10420.4 10431.9 10515.6 10554.6 8865.5 8964.2 9032.9 9045.9 9244.7 7205.9 8838.2 8906.9 8974.9 9017.5 7882.0 8012.9 8068.4 8127.6 8131.0 5969.2 5991.3 5998.1 6165.8 6231.5 6088.3 6126.8 6225.3 6245.1 6278.1 11:31:15 INFO - PROCESS | 2100 | 11:31:15 INFO - PROCESS | 2100 | [#4] prototype.html Cycles:180 Average:4632.68 Median:4945.24 stddev:1229.38 (24.9%) stddev-sans-first:1189.83 11:31:15 INFO - PROCESS | 2100 | Values: 338.5 391.3 392.8 401.2 404.3 4211.5 4397.7 4830.5 5163.3 5260.7 4277.8 4354.6 4469.6 5175.2 5195.7 4075.8 4468.7 4522.9 4553.5 4567.2 4016.5 4081.6 4122.6 4218.1 4626.5 4213.5 4230.4 4588.4 4594.7 4598.0 3715.8 3799.6 3980.5 4093.1 4507.0 3629.8 4288.7 4297.7 4338.8 4387.0 4426.9 4556.7 5156.9 5188.6 5219.2 4770.9 4969.4 5021.7 5311.6 5374.5 4589.3 4929.8 5142.0 5601.0 5721.4 4121.7 4137.8 4661.9 4891.4 4897.9 5535.2 5560.5 5735.7 5958.2 5984.4 4799.7 4800.8 4818.6 4836.0 4839.0 4189.2 4418.3 4508.8 4699.4 4871.3 4705.7 4804.4 4941.9 5145.5 5273.8 4839.1 5269.9 5294.4 5314.7 5549.4 1632.4 1663.7 1744.7 1752.4 1759.0 1269.5 1285.7 1331.4 1427.1 1437.5 5237.4 5273.5 5289.4 5300.3 5323.2 4998.7 5023.7 5719.8 5787.2 5847.5 4513.4 4944.9 5170.7 5296.1 5339.8 2967.9 2978.0 2986.2 2987.9 3006.4 5029.8 5048.6 5791.7 5957.9 6058.5 4902.5 5704.8 5860.0 5867.8 5969.6 5001.5 5520.4 5659.7 5864.6 5981.9 5136.6 5245.5 5413.8 5694.8 5866.9 4952.6 5558.2 5558.5 5609.9 5618.8 4687.5 4928.9 5091.6 5545.7 5621.6 4143.6 4175.5 4450.8 4739.0 5064.4 4880.7 5025.5 5385.1 5532.0 5650.1 4644.3 4854.7 5099.3 5425.3 5599.3 4906.6 5027.6 5164.5 5396.4 5513.9 5027.7 5259.9 5385.2 5461.7 5474.8 4777.9 4817.8 5332.3 5494.8 5506.8 4564.2 4945.6 4983.9 5428.8 5530.9 11:31:15 INFO - PROCESS | 2100 | 11:31:15 INFO - PROCESS | 2100 | [#5] yui.html Cycles:180 Average:21545.48 Median:17303.27 stddev:30933.06 (178.8%) stddev-sans-first:30979.95 11:31:15 INFO - PROCESS | 2100 | Values: 624.7 744.2 753.7 900.4 903.0 16012.0 17397.4 17404.8 17463.6 17511.9 16004.4 16013.2 16031.4 16054.5 16222.4 963.4 971.1 993.7 997.9 1009.1 994.2 1007.7 1009.3 1009.6 1010.3 1046.3 1046.8 1049.8 1050.4 1050.4 1020.5 1023.0 1042.4 1044.4 1044.7 779.1 779.4 781.2 781.2 782.5 17177.7 17195.6 17209.1 18121.3 18358.7 14400.6 14453.3 14792.1 14951.8 15083.4 12027.2 12082.3 12300.8 12816.0 12893.7 3825.8 3835.6 3943.0 4039.2 4076.8 92104.0 112577.4 113944.0 114380.0 116659.8 11053.4 11062.8 11100.7 11407.5 11664.4 23535.3 23535.9 23536.8 23545.2 23565.0 8063.6 8149.6 8234.3 8262.7 8281.8 4514.2 4548.5 4666.2 4716.4 4867.4 3544.1 3581.8 3594.9 3632.0 3643.2 645.9 646.8 648.1 649.0 657.6 702.8 711.6 723.1 734.3 751.1 10699.2 10810.4 11000.8 11275.1 11352.3 698.7 724.4 736.4 747.3 752.3 4659.5 5287.8 5294.8 5298.3 5306.5 103804.0 107824.8 108197.0 109070.5 109292.0 96328.0 99149.5 99479.0 100599.5 102433.0 71129.9 71672.6 82099.0 85696.0 86850.6 9739.0 9978.9 10256.2 10406.1 11529.7 39121.0 42164.2 47671.6 48415.5 51864.5 70513.6 71980.6 72364.3 73025.9 73764.3 13099.4 13336.7 13441.3 13519.1 13815.0 15206.9 15353.7 15658.6 15753.8 16465.3 13839.7 14423.6 14485.0 14530.2 14613.1 14275.4 14325.4 14356.0 14493.3 14643.7 13320.1 14337.0 14349.7 14395.9 14549.3 9904.3 10307.7 10696.0 10765.7 11022.0 8083.6 10029.1 11409.1 11771.4 11788.8 11:31:15 INFO - PROCESS | 2100 | -------- Summary: end -------- 11:31:15 INFO - PROCESS | 2100 | 11:31:16 INFO - TEST-INFO | 2100: exit 0 11:31:16 INFO - TEST-OK | dromaeo_css | took 1114935ms 11:31:16 INFO - TEST-START | kraken 11:31:16 INFO - operating with platform_type : mac_ 11:31:16 INFO - Initialising browser for kraken test... 11:31:16 INFO - TEST-INFO | started process 2108 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmptW0xDU/profile http://localhost:49237/getInfo.html) 11:31:19 INFO - PROCESS | 2108 | 2017-05-15 11:31:19.063 plugin-container[2110:11653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 11:31:19 INFO - PROCESS | 2108 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:31:19 INFO - PROCESS | 2108 | 2017-05-15 11:31:19.063 plugin-container[2110:11653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 11:31:19 INFO - PROCESS | 2108 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:31:19 INFO - PROCESS | 2108 | 2017-05-15 11:31:19.063 plugin-container[2110:11653] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:31:19 INFO - PROCESS | 2108 | __metrics Screen width/height:1600/1200 11:31:19 INFO - PROCESS | 2108 | colorDepth:24 11:31:19 INFO - PROCESS | 2108 | Browser inner width/height: 1024/689 11:31:19 INFO - PROCESS | 2108 | __metrics 11:31:19 ERROR - PROCESS | 2108 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 11:31:19 INFO - TEST-INFO | 2108: exit 0 11:31:19 INFO - Browser initialized. 11:31:19 INFO - Running cycle 1/1 for kraken test... 11:31:19 INFO - TEST-INFO | started process 2111 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/T/tmptW0xDU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:31:22 INFO - PROCESS | 2111 | 2017-05-15 11:31:22.016 plugin-container[2112:11775] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 11:31:22 INFO - PROCESS | 2111 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:31:22 INFO - PROCESS | 2111 | 2017-05-15 11:31:22.018 plugin-container[2112:11775] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.CFPasteboardClient' 11:31:22 INFO - PROCESS | 2111 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:31:22 INFO - PROCESS | 2111 | 2017-05-15 11:31:22.018 plugin-container[2112:11775] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:31:31 INFO - PROCESS | 2111 | RSS: Main: 174604288 11:31:31 INFO - PROCESS | 2111 | 11:31:34 INFO - PROCESS | 2111 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 11:31:35 INFO - PROCESS | 2111 | RSS: Main: 188047360 11:31:35 INFO - PROCESS | 2111 | 11:31:38 INFO - PROCESS | 2111 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 11:31:38 INFO - PROCESS | 2111 | RSS: Main: 196001792 11:31:38 INFO - PROCESS | 2111 | 11:31:42 INFO - PROCESS | 2111 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 11:31:42 INFO - PROCESS | 2111 | RSS: Main: 200097792 11:31:42 INFO - PROCESS | 2111 | 11:31:44 INFO - PROCESS | 2111 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 11:31:45 INFO - PROCESS | 2111 | RSS: Main: 200949760 11:31:45 INFO - PROCESS | 2111 | 11:31:47 INFO - PROCESS | 2111 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:31:47 INFO - PROCESS | 2111 | RSS: Main: 201379840 11:31:47 INFO - PROCESS | 2111 | 11:31:51 INFO - PROCESS | 2111 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 11:31:51 INFO - PROCESS | 2111 | RSS: Main: 205221888 11:31:51 INFO - PROCESS | 2111 | 11:31:54 INFO - PROCESS | 2111 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 11:31:55 INFO - PROCESS | 2111 | RSS: Main: 204906496 11:31:55 INFO - PROCESS | 2111 | 11:31:58 INFO - PROCESS | 2111 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 11:31:59 INFO - PROCESS | 2111 | RSS: Main: 205246464 11:31:59 INFO - PROCESS | 2111 | 11:32:00 INFO - PROCESS | 2111 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:32:01 INFO - PROCESS | 2111 | RSS: Main: 205266944 11:32:01 INFO - PROCESS | 2111 | 11:32:03 INFO - PROCESS | 2111 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:32:03 INFO - PROCESS | 2111 | RSS: Main: 204824576 11:32:03 INFO - PROCESS | 2111 | 11:32:05 INFO - PROCESS | 2111 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:32:06 INFO - PROCESS | 2111 | RSS: Main: 204804096 11:32:06 INFO - PROCESS | 2111 | 11:32:09 INFO - PROCESS | 2111 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:32:10 INFO - PROCESS | 2111 | RSS: Main: 205201408 11:32:10 INFO - PROCESS | 2111 | 11:32:13 INFO - PROCESS | 2111 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:32:13 INFO - PROCESS | 2111 | RSS: Main: 205074432 11:32:13 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 11:32:15 INFO - PROCESS | 2111 | RSS: Main: 207175680 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | __start_tp_report 11:32:15 INFO - PROCESS | 2111 | _x_x_mozilla_page_load 11:32:15 INFO - PROCESS | 2111 | _x_x_mozilla_page_load_details 11:32:15 INFO - PROCESS | 2111 | |i|pagename|runs| 11:32:15 INFO - PROCESS | 2111 | |0;ai-astar;108;99;177;178;130;119;119;117;119;116 11:32:15 INFO - PROCESS | 2111 | |1;audio-beat-detection;145;111;110;143;111;115;112;113;129;109 11:32:15 INFO - PROCESS | 2111 | |2;audio-dft;194;198;196;200;201;199;199;196;200;194 11:32:15 INFO - PROCESS | 2111 | |3;audio-fft;82;59;61;105;89;85;70;62;73;59 11:32:15 INFO - PROCESS | 2111 | |4;audio-oscillator;86;68;85;101;106;83;81;70;70;86 11:32:15 INFO - PROCESS | 2111 | |5;imaging-gaussian-blur;90;93;127;87;103;115;99;109;93;134 11:32:15 INFO - PROCESS | 2111 | |6;imaging-darkroom;105;154;153;96;96;97;97;95;96;103 11:32:15 INFO - PROCESS | 2111 | |7;imaging-desaturate;112;99;91;76;137;146;93;90;86;89 11:32:15 INFO - PROCESS | 2111 | |8;json-parse-financial;62;66;65;66;67;68;69;67;67;69 11:32:15 INFO - PROCESS | 2111 | |9;json-stringify-tinderbox;58;63;59;69;58;58;58;58;57;59 11:32:15 INFO - PROCESS | 2111 | |10;stanford-crypto-aes;81;85;86;94;79;87;82;86;82;85 11:32:15 INFO - PROCESS | 2111 | |11;stanford-crypto-ccm;142;147;146;135;136;135;158;161;189;148 11:32:15 INFO - PROCESS | 2111 | |12;stanford-crypto-pbkdf2;159;162;187;162;165;163;181;164;225;204 11:32:15 INFO - PROCESS | 2111 | |13;stanford-crypto-sha256-iterative;62;61;57;70;79;64;67;75;82;83 11:32:15 INFO - PROCESS | 2111 | __end_tp_report 11:32:15 INFO - PROCESS | 2111 | __start_cc_report 11:32:15 INFO - PROCESS | 2111 | _x_x_mozilla_cycle_collect,984 11:32:15 INFO - PROCESS | 2111 | __end_cc_report 11:32:15 INFO - PROCESS | 2111 | __startTimestamp1494873135773__endTimestamp 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | ------- Summary: start ------- 11:32:15 INFO - PROCESS | 2111 | Number of tests: 14 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | [#0] ai-astar Cycles:10 Average:128.20 Median:124.50 stddev:27.19 (21.8%) stddev-sans-first:27.84 11:32:15 INFO - PROCESS | 2111 | Values: 108.0 99.0 177.0 178.0 130.0 119.0 119.0 117.0 119.0 116.0 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | [#1] audio-beat-detection Cycles:10 Average:119.80 Median:114.00 stddev:13.97 (12.3%) stddev-sans-first:11.46 11:32:15 INFO - PROCESS | 2111 | Values: 145.0 111.0 110.0 143.0 111.0 115.0 112.0 113.0 129.0 109.0 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | [#2] audio-dft Cycles:10 Average:197.70 Median:199.00 stddev:2.54 (1.3%) stddev-sans-first:2.32 11:32:15 INFO - PROCESS | 2111 | Values: 194.0 198.0 196.0 200.0 201.0 199.0 199.0 196.0 200.0 194.0 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | [#3] audio-fft Cycles:10 Average:74.50 Median:71.50 stddev:15.45 (21.6%) stddev-sans-first:16.15 11:32:15 INFO - PROCESS | 2111 | Values: 82.0 59.0 61.0 105.0 89.0 85.0 70.0 62.0 73.0 59.0 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | [#4] audio-oscillator Cycles:10 Average:83.60 Median:82.00 stddev:12.64 (15.4%) stddev-sans-first:13.38 11:32:15 INFO - PROCESS | 2111 | Values: 86.0 68.0 85.0 101.0 106.0 83.0 81.0 70.0 70.0 86.0 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | [#5] imaging-gaussian-blur Cycles:10 Average:105.00 Median:88.50 stddev:16.05 (18.1%) stddev-sans-first:16.08 11:32:15 INFO - PROCESS | 2111 | Values: 90.0 93.0 127.0 87.0 103.0 115.0 99.0 109.0 93.0 134.0 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | [#6] imaging-darkroom Cycles:10 Average:109.20 Median:96.00 stddev:23.58 (24.6%) stddev-sans-first:24.96 11:32:15 INFO - PROCESS | 2111 | Values: 105.0 154.0 153.0 96.0 96.0 97.0 97.0 95.0 96.0 103.0 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | [#7] imaging-desaturate Cycles:10 Average:101.90 Median:89.50 stddev:22.89 (25.6%) stddev-sans-first:23.99 11:32:15 INFO - PROCESS | 2111 | Values: 112.0 99.0 91.0 76.0 137.0 146.0 93.0 90.0 86.0 89.0 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | [#8] json-parse-financial Cycles:10 Average:66.60 Median:67.00 stddev:2.07 (3.1%) stddev-sans-first:1.36 11:32:15 INFO - PROCESS | 2111 | Values: 62.0 66.0 65.0 66.0 67.0 68.0 69.0 67.0 67.0 69.0 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | [#9] json-stringify-tinderbox Cycles:10 Average:59.70 Median:58.50 stddev:3.65 (6.2%) stddev-sans-first:3.82 11:32:15 INFO - PROCESS | 2111 | Values: 58.0 63.0 59.0 69.0 58.0 58.0 58.0 58.0 57.0 59.0 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:85.50 stddev:4.16 (4.9%) stddev-sans-first:4.20 11:32:15 INFO - PROCESS | 2111 | Values: 81.0 85.0 86.0 94.0 79.0 87.0 82.0 86.0 82.0 85.0 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:147.50 stddev:16.48 (11.2%) stddev-sans-first:17.24 11:32:15 INFO - PROCESS | 2111 | Values: 142.0 147.0 146.0 135.0 136.0 135.0 158.0 161.0 189.0 148.0 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.20 Median:173.00 stddev:22.19 (12.8%) stddev-sans-first:22.54 11:32:15 INFO - PROCESS | 2111 | Values: 159.0 162.0 187.0 162.0 165.0 163.0 181.0 164.0 225.0 204.0 11:32:15 INFO - PROCESS | 2111 | 11:32:15 INFO - PROCESS | 2111 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.00 Median:72.50 stddev:9.30 (12.8%) stddev-sans-first:9.40 11:32:15 INFO - PROCESS | 2111 | Values: 62.0 61.0 57.0 70.0 79.0 64.0 67.0 75.0 82.0 83.0 11:32:15 INFO - PROCESS | 2111 | -------- Summary: end -------- 11:32:15 INFO - PROCESS | 2111 | 11:32:16 INFO - TEST-INFO | 2111: exit 0 11:32:16 INFO - TEST-OK | kraken | took 60112ms 11:32:16 INFO - SUITE-END | took 1175s 11:32:16 INFO - Completed test suite (00:19:35) 11:32:16 INFO - javascript benchmark 11:32:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7948.546167535565, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3507.2108775525494, 3544.1848374873775, 3579.534660494137, 3590.4614307853817, 3593.694535964443, 6082.908756368653, 6096.4513193812545, 6146.170267013952, 6193.597416167946, 6207.5033997280225, 4679.859604211879, 6160.784372546962, 6204.503639708823, 6231.252249730027, 6231.376862313778, 5447.945520544793, 5523.447655234465, 5531.115021596545, 5540.9168862467095, 5595.832125036255, 5176.0, 5579.13523403871, 5586.944130558703, 5599.468050535193, 5635.267415236013, 5324.935012997416, 5372.382176049765, 5518.530924871391, 5529.502344788988, 5540.50135487806, 5419.295491586068, 5445.50990410865, 5450.264214331054, 5462.890742185174, 5511.503964643161, 5430.91853622196, 5450.91823622646, 5463.453654634544, 5466.699331536764, 5472.425395333473, 4809.254565542346, 5932.377100404482, 5973.0, 6091.208142941389, 6176.413240742122, 6380.298167201604, 6433.549651524395, 6442.645654489002, 6460.22477302722, 6470.514711396664, 6590.967045164792, 6596.175478065242, 6597.538172327939, 6597.93402065978, 6602.636854972975, 6251.156093927352, 6294.213223347082, 6297.33877942814, 6316.463100636452, 6324.841878953063, 6305.527085468608, 6481.578697384655, 6669.699863506154, 6670.099536562599, 6717.193936727624, 6407.967960160169, 6433.806985790434, 6438.517111216677, 6445.742170313229, 6457.773977910751, 5149.922751158736, 6548.476121910236, 6589.110470086573, 6623.4701223901975, 6739.460843132538, 6280.403361680633, 6355.205599300088, 6396.744130234832, 6419.839504012437, 6486.351364863476, 6489.415952564292, 6525.0, 6533.314002029813, 6535.281119076898, 6546.214454265423, 4724.173269677793, 5240.502152295526, 5242.999999999924, 5255.343082114736, 5298.993191293642, 4145.88061223468, 4543.4775000875325, 4571.634269258451, 4590.97704511482, 4626.930596041062, 5212.765425555859, 6224.346443623445, 6285.214348206474, 6293.2762732285455, 6330.778422755181, 5585.661107950867, 6567.802965911031, 6587.83530411734, 6633.834154146089, 6635.435987940982, 6061.575689701766, 6088.238970128734, 6157.630542167484, 6178.0, 6215.254169499689, 4904.852854414374, 4918.729469879119, 5811.012127938261, 5838.0, 5898.852528686817, 6732.999999999902, 6737.764178253836, 6749.392554670103, 6753.358430949052, 6765.985102234704, 6333.714982825876, 6349.396807303298, 6512.446442052289, 6553.705083271359, 6563.113979612882, 6729.528932974645, 6757.3242675732035, 6771.0, 6772.390484856386, 6786.0838786765125, 6510.414062734377, 6539.509536784855, 6542.901856472062, 6544.901826472702, 6554.901676474952, 5188.688678679291, 6321.49428045746, 6447.258565265054, 6617.669116544057, 6650.102236198051, 6217.533684973555, 6387.552871298964, 6410.935890641221, 6457.6448295342325, 6459.321771213955, 6434.453071488976, 6438.0, 6438.356164383524, 6444.903326450013, 6455.838604034937, 6117.796956032775, 6180.351063138485, 6254.655993920378, 6282.654454005074, 6323.399277068856, 6330.556861019869, 6335.936640633719, 6351.396617321347, 6374.107624932421, 6384.010478375859, 6200.0699895016105, 6337.841553961188, 6338.207724034496, 6347.301796802256, 6349.9682501587195], "value": 5974.617828735342, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.7482405630197, 829.001053730255, 852.2883392367374, 916.6287653500331, 985.6353149334748, 38424.61575384245, 39380.0, 39515.209695806065, 40869.978250543754, 40881.79559102208, 31763.205919852015, 39864.202715945736, 39938.8003059985, 39963.0, 40052.79973600128, 8042.7185048523315, 8189.918100819005, 8326.50040997542, 8442.282405995498, 8462.957685211566, 12022.759544809098, 12212.084093692964, 12248.203866748632, 12251.999999999955, 12290.385480725972, 12224.388780560981, 12240.632781016584, 12314.75370492594, 12331.321777302244, 12343.691407714789, 12261.448234829453, 12266.509339626404, 12266.509339626404, 12282.01743860489, 12285.508579656804, 11877.762444751143, 11894.464749086312, 11942.402879855972, 11959.820602690967, 11994.64016079519, 41689.1662166755, 41964.580354196376, 41990.950226244284, 41995.37006944898, 42019.0, 3962.2075584883137, 3964.7621142731527, 3996.4205190247544, 4013.779242141681, 4029.5164580250557, 29431.85284073566, 29551.817927282882, 29928.551071733942, 30892.8455357724, 31528.842355788303, 3213.9196520086953, 3353.4802105673416, 3445.793252404864, 3452.3267962747273, 3474.1488335357744, 317011.0, 373129.0, 379638.0, 380709.0, 382224.0, 43505.347419788726, 44478.0, 50481.0, 53175.468245317825, 53223.73388133034, 47059.82350441266, 57830.13254801181, 58191.709041454524, 58544.0, 58709.70645146747, 15758.448454304043, 15846.366145354288, 15849.0, 15855.207239638203, 15876.523704288658, 13300.534481293109, 13327.466901323838, 13335.93332033353, 13363.933180334036, 13368.0, 13068.803967940488, 13138.671533211555, 13159.868401316056, 13248.006399520074, 13272.867271327163, 5858.5898987070495, 5888.381719919433, 5912.556558258148, 5925.081612350093, 5926.0, 1143.5128595305343, 1148.5520646947698, 1162.7325715085578, 1172.8006238939402, 1216.1547724331504, 21222.78777212239, 27722.5841612376, 28607.427851442855, 28627.14118576446, 28685.56971645427, 1084.3896813232343, 1248.8126780982925, 1265.9620211393674, 1298.7077907470743, 1309.1752196116577, 14701.92649036748, 15493.147876866655, 15928.203589820463, 16219.837801621832, 16471.599914007205, 326326.0, 328437.0, 330289.0, 333678.6632133696, 334473.0, 58367.0, 58802.705986469795, 58974.0, 58989.11516327259, 58998.820023599284, 1315.8552559218479, 1504.1125735815701, 1541.1600677630943, 1550.8601178134063, 1557.322564684366, 26598.867005665623, 27608.585871212348, 28548.714512855437, 28873.85563072171, 29353.412931741674, 46711.76644116758, 46861.828454287555, 46907.76546117248, 46973.530264696914, 46986.530134698216, 311739.0, 312818.0, 313170.0, 313216.0, 314310.0, 30847.84576077105, 33251.33497330088, 33897.0, 34819.0, 36455.0, 41416.75749727513, 41617.1676566473, 42419.0, 42424.78787606042, 43020.78489607532, 29251.0, 29400.26499337534, 29815.85092074526, 29979.70020299769, 30393.392132157674, 29257.26856828511, 29504.262393440335, 29843.95546155874, 30085.69914300829, 30223.0, 35007.64992350044, 35585.1103722409, 35766.463503048, 35817.283654326246, 35839.0, 26058.86970565211, 26666.733332666427, 27316.31709207286, 27428.314292142855, 27474.450510990067, 20579.794202057787, 22472.438189045406, 22551.77448225497, 22573.0, 22599.322020339416], "value": 19129.28918637986, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.4609720176729, 367.46533793330707, 417.43854515676406, 426.89754458929866, 439.83066519390024, 16980.915095424538, 18321.72517412238, 21627.891860540694, 22130.44673883154, 22374.664380034294, 22322.553548929052, 22685.0, 22909.0, 22931.082756689753, 22939.853007349648, 6854.691538880737, 7025.648717564101, 7046.295370462938, 7064.6114463704735, 7100.218975912646, 6789.321067893221, 6995.895061574055, 7004.299570043006, 7075.681594328267, 7095.0776399068045, 7057.223705392403, 7087.9291207088045, 7103.751368702096, 7111.004459375691, 7440.0, 6596.670166491656, 7766.0, 7864.646090925922, 7906.604669766518, 7931.762047138595, 6954.513184077116, 7268.927310726931, 7607.782754759212, 7729.227077292282, 7791.688332466694, 25603.359916002064, 26244.868775656192, 26697.733022669912, 26984.0, 27064.646767661736, 2644.4843255565174, 2985.1939976206518, 2994.1317018064733, 3061.9693803061905, 3071.3703690743346, 16253.837461625351, 16452.588685282844, 16589.419370321983, 16756.0, 17042.31830726782, 2139.5934772393193, 2168.7011767526474, 2170.6418440957223, 2179.618566750828, 2225.9777402226096, 147214.0, 149185.0, 157200.0, 157384.99999999886, 159243.0, 20473.18107275722, 20875.58248835045, 21148.682769758467, 21181.0, 21235.362939111852, 31701.20746981344, 31888.0, 31936.680633193835, 43345.56654433415, 45138.77430612826, 12226.327551984727, 12305.815412768816, 12313.199642023239, 12516.0, 12645.367731613382, 8792.648294068293, 9354.345195836226, 9885.25860560461, 10203.846942295871, 10459.94770026145, 9670.371425857296, 11962.880371196177, 12111.333876636694, 12164.513419463123, 12309.815352769716, 4187.392828039917, 4562.855457934766, 4944.752762361867, 5042.991401719671, 5145.845624631267, 1049.2026060194155, 1091.307020042279, 1112.9666110016713, 1129.6611016695124, 1160.6692092753694, 21331.46671333229, 21435.249766258417, 21641.13435462564, 21676.24133155332, 21718.565628687338, 1016.2022812092472, 1041.4896700616796, 1114.2144787924494, 1151.5739176504746, 1153.7865494883474, 9147.908520914838, 10673.57305707763, 11165.38590377537, 11318.660440186808, 11379.260348077514, 660525.0, 735607.0, 739432.0, 745190.0, 777608.0, 157464.0, 165527.0, 166611.0, 169680.0, 170540.0, 1216.598522487577, 1243.2975368916534, 1269.4224128021729, 1271.853736820259, 1279.609719035703, 18334.449966501026, 18712.906435467736, 18996.810031899506, 19000.049997500348, 19244.0, 62376.0, 66883.0, 70501.64749176426, 70578.94131588133, 70826.29173708196, 81213.0, 81720.0, 90428.0, 91641.0, 93339.0, 9637.9036209637, 10083.596656133672, 10422.947885260828, 11733.823992640288, 12121.818172727593, 13617.31913404306, 13735.0, 13742.381592828147, 14082.929585352007, 14140.15159090458, 8787.0, 9633.951830240803, 11430.142739294752, 11482.655520334403, 11708.356040417859, 8534.189252021297, 10234.590616375262, 10634.84047739269, 10825.621103261348, 10995.835062473909, 11820.0, 11831.526738930346, 11857.525698971944, 12110.45502952387, 12205.511779528719, 11957.94021029924, 12000.819987700366, 12009.339486328336, 12273.877261227273, 12292.201006934522, 9656.565454554422, 9767.951160244153, 11737.295762254293, 11783.410829458664, 12113.515459381526], "value": 12133.88740366161, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1638.5166375919102, 1667.291401154509, 1672.8670944545695, 1673.9414120505785, 1693.8221486743892, 9842.655507057254, 10102.797944041133, 10723.249372543925, 11329.716757081078, 11338.829917551233, 11172.273802202872, 11522.769544609102, 11807.11446641501, 11834.23077499962, 11835.0, 2419.407245224918, 2471.0980492120425, 2474.8762561871927, 2476.5418397596413, 2480.838745481538, 2093.884836334001, 2346.6597343385197, 2427.745086765891, 2438.4757277185404, 2438.7683170098812, 2371.691680081587, 2414.396400899775, 2418.286605451396, 2423.53952748978, 2423.866687332196, 2379.9524009519887, 2384.666146739458, 2392.449736560592, 2400.7719266669637, 2402.0631953538223, 2367.6448532720055, 2388.5700573896693, 2393.1743548475747, 2396.9640455393182, 2400.8919598618104, 11512.136589755719, 11695.35675537845, 11761.58834440799, 11765.235259708177, 11790.823137652942, 5734.369219385864, 5945.0, 5950.672713000783, 5968.820935371946, 5969.970150149265, 3573.4818451324413, 3669.7064234861414, 3686.3364594373, 3733.8798360492015, 3736.0099573613015, 1260.5840072775961, 1264.8355713757244, 1286.6011536423646, 1313.9605811825566, 1314.5859054397833, 15977.041377517386, 16138.677226455522, 16155.273012714457, 16178.676426471404, 16218.594535136597, 4695.859124226278, 4887.608991280689, 4948.134076536592, 4949.529794669501, 4961.107000739901, 13464.192148471124, 13475.730485390237, 13505.527306544223, 13540.661483462794, 13554.186748795104, 5994.670293133832, 6012.458878700938, 6021.578489505692, 6034.09488576717, 6051.0923361496125, 5143.202803565453, 5234.110201265794, 5329.9467005330225, 5344.599155063386, 5355.410904800468, 4037.6769858411258, 4137.480060635493, 4144.212599606126, 4179.393987871742, 4260.147970405931, 1122.6183097746805, 1125.752334486428, 1129.2264798612975, 1130.0620484997432, 1144.3991904250336, 932.6036434515304, 944.7543638653862, 950.3252690589759, 959.7600599850037, 1003.8243307421318, 6559.770408035792, 6754.797356079325, 6765.188177418741, 6872.484563657746, 6974.302569742985, 834.3992325525612, 922.1331947968888, 1022.2026819081128, 1043.76515284062, 1051.75283808305, 3450.8274586270586, 3518.3842827505086, 4251.404803327537, 4364.214441400578, 4378.934315985262, 13596.86403135956, 14110.35585765702, 14191.290435478391, 14326.212058336683, 14330.996830221993, 13990.230537320342, 14284.928575357264, 14367.20980346091, 14376.065555738844, 14429.85570144306, 8602.096779838152, 9178.67874624385, 10555.313904596176, 10590.523426445829, 10595.629152979609, 6559.639219842955, 6580.638064906476, 6631.270560238467, 6648.335166483313, 6716.731330746716, 12549.93725031369, 12555.0, 12656.303903285409, 12709.87290127124, 12726.0, 10194.388336699822, 12719.618411447302, 12753.234805911676, 12969.54606588765, 13176.538821141598, 8796.208341249318, 9188.58651360677, 9355.906440935503, 9406.576704048472, 9440.244780417688, 10394.58421663125, 10420.374777513374, 10431.895681043092, 10515.631952881613, 10554.630587929385, 8865.468071915706, 8964.193222609736, 9032.86450703227, 9045.864312035194, 9244.676436324697, 7205.855882882208, 8838.204561589488, 8906.86639700392, 8974.86537701922, 9017.458952462874, 7882.0, 8012.919870801217, 8068.394870384627, 8127.593620318842, 8130.959345203472, 5969.164316995711, 5991.2510936132985, 5998.1002849572915, 6165.84585385369, 6231.532635052298, 6088.299845517822, 6126.754929802758, 6225.252969643671, 6245.0632405139595, 6278.121063051085], "value": 5278.8282782097285, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [338.45170823266295, 391.3308242904633, 392.7682667226336, 401.1855932457112, 404.31872295182603, 4211.536730959592, 4397.6701747369, 4830.541098595632, 5163.32876726026, 5260.710660913648, 4277.764722940237, 4354.629856462197, 4469.620082293, 5175.24958880962, 5195.714235717034, 4075.816588253521, 4468.664850136237, 4522.924411400247, 4553.521880202581, 4567.246404343279, 4016.5180178378637, 4081.5714499925084, 4122.557105013237, 4218.093109981353, 4626.514216007321, 4213.536510983789, 4230.407742915979, 4588.403507544014, 4594.74728889911, 4598.0, 3715.8262416486386, 3799.563050249229, 3980.5223373195404, 4093.079057212133, 4506.954930450686, 3629.8366573504254, 4288.656907447396, 4297.6677230058785, 4338.761368124752, 4387.0, 4426.867193984185, 4556.658250631182, 5156.871078223075, 5188.558972487343, 5219.164933610642, 4770.856874293777, 4969.378827646544, 5021.6735912165595, 5311.601629877775, 5374.4894235048, 4589.334546490773, 4929.753512324405, 5142.0, 5601.0, 5721.370649228582, 4121.732087414309, 4137.77242251676, 4661.930071048937, 4891.388576427947, 4897.8775530611665, 5535.201059655628, 5560.471755183251, 5735.655860648374, 5958.212536239111, 5984.401559844025, 4799.736014519235, 4800.759962001886, 4818.578519336804, 4835.95164048362, 4838.95161048392, 4189.162167566499, 4418.315161150027, 4508.819647214074, 4699.389079419653, 4871.342368780241, 4705.670603057822, 4804.35141255926, 4941.925871111936, 5145.511176438233, 5273.841784746463, 4839.104765618372, 5269.894602107936, 5294.391145018372, 5314.707691077027, 5549.361823390281, 1632.3633782824709, 1663.69221693987, 1744.7121224997734, 1752.3603884582035, 1758.9736153957701, 1269.504893091695, 1285.724268045573, 1331.42083193811, 1427.129451034868, 1437.5040610989192, 5237.423883372826, 5273.499017593323, 5289.3917199521775, 5300.257963885059, 5323.22813192085, 4998.6500944933505, 5023.718951667374, 5719.799807006818, 5787.151544223045, 5847.473727364558, 4513.368128462018, 4944.90110197794, 5170.741462926839, 5296.1261391870685, 5339.786408543692, 2967.866446009935, 2978.047024952038, 2986.2385091801452, 2987.850607469618, 3006.3987202559574, 5029.84910452687, 5048.596112311006, 5791.681457519877, 5957.851053723692, 6058.515318774487, 4902.485239049848, 5704.830509745492, 5860.033094539353, 5867.765289388462, 5969.552283578749, 5001.549860512571, 5520.3565646673005, 5659.726561523624, 5864.560157988082, 5981.850453738692, 5136.640435169502, 5245.475452454724, 5413.756380962945, 5694.810905892713, 5866.853328666817, 4952.628552858478, 5558.249636299048, 5558.471945165203, 5609.943900560942, 5618.775248989995, 4687.507811679861, 4928.901421971612, 5091.646642121082, 5545.694986775766, 5621.634593751393, 4143.56492568288, 4175.498940127204, 4450.777461126866, 4738.957429365534, 5064.366954130734, 4880.706612747554, 5025.522575355335, 5385.111456609616, 5531.9723401382735, 5650.124230744241, 4644.349791029326, 4854.68444551103, 5099.286099945936, 5425.321834770653, 5599.32808063035, 4906.607471402208, 5027.622928280467, 5164.509371609691, 5396.3524377075, 5513.889722205614, 5027.698338099725, 5259.947400525945, 5385.219143224289, 5461.678786515794, 5474.781008759605, 4777.880552986203, 4817.78319975609, 5332.33345831771, 5494.835154945358, 5506.779728810803, 4564.207582038912, 4945.579625731853, 4983.8504844854715, 5428.7557059931605, 5530.91703624454], "value": 4288.6504607113075, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [624.6970219443572, 744.226004954847, 753.7211231844215, 900.3832374823245, 902.9819403611928, 16012.03927764335, 17397.391091311805, 17404.82595174048, 17463.563410914732, 17511.912440437794, 16004.359825606962, 16013.19934003301, 16031.35874565019, 16054.518364449055, 16222.432214872453, 963.4219468319022, 971.0871780526318, 993.662154867345, 997.9301448898543, 1009.1321463541349, 994.1698681600872, 1007.7178390050798, 1009.2632378363799, 1009.5860697114184, 1010.2524132142198, 1046.2937517175915, 1046.7540128069897, 1049.774298525817, 1050.3697781331216, 1050.3907733514582, 1020.4897551224387, 1023.0, 1042.4370839746603, 1044.3942513342242, 1044.650042235848, 779.0884664942031, 779.3959681247079, 781.1680560203422, 781.1953687701676, 782.5304817109688, 17177.65644687099, 17195.570110747205, 17209.1395430228, 18121.275148994024, 18358.724619130724, 14400.567982960423, 14453.277336133151, 14792.11247325153, 14951.775723364159, 15083.396664133532, 12027.216826391033, 12082.27506349613, 12300.81548776769, 12816.038797090163, 12893.742125157445, 3825.8331208981526, 3835.5780864104927, 3942.99453639323, 4039.171969746195, 4076.8165432555606, 92104.0, 112577.43711281392, 113944.0, 114380.0, 116659.83340166659, 11053.392063436428, 11062.81708513192, 11100.722481938015, 11407.543698252142, 11664.358460284597, 23535.293941181793, 23535.88232058863, 23536.7646323538, 23545.175918843102, 23565.0, 8063.637136328879, 8149.592520373958, 8234.300084492885, 8262.710805121791, 8281.799139124792, 4514.212142220475, 4548.476925153584, 4666.2067448533835, 4716.434027916672, 4867.440244371872, 3544.149404142987, 3581.83881725323, 3594.928101438009, 3631.963680363215, 3643.2349206666895, 645.9334876451604, 646.8415238266655, 648.0506058624138, 648.9870202595922, 657.5955787190843, 702.796189105164, 711.6263961420295, 723.1430754555868, 734.2730696610317, 751.1136858506906, 10699.197560183018, 10810.35137891729, 11000.779984400267, 11275.097992160607, 11352.262102963447, 698.7449580903035, 724.409606170969, 736.3556887723242, 747.3124725252671, 752.3229093815501, 4659.510751371125, 5287.7884884604955, 5294.788208471618, 5298.337707786527, 5306.495882891119, 103804.0, 107824.8435031295, 108197.0, 109070.45464772783, 109292.0, 96328.03671963331, 99149.50425247828, 99479.0, 100599.49700251452, 102433.0, 71129.93305100428, 71672.64163679148, 82099.0, 85696.0, 86850.56574717212, 9738.951305243429, 9978.900210998088, 10256.230782691477, 10406.10269297669, 11529.654110376701, 39121.0, 42164.156716866106, 47671.61641917821, 48415.5158448411, 51864.48135518596, 70513.64743176251, 71980.64009679918, 72364.27635723575, 73025.90461143194, 73764.26235737574, 13099.410526526139, 13336.733265334446, 13441.260730659907, 13519.11389826107, 13815.0, 15206.93551451432, 15353.692926141191, 15658.608534786721, 15753.842461575237, 16465.34138634441, 13839.654008649864, 14423.639409014859, 14485.0, 14530.200838953959, 14613.123212607277, 14275.427453356013, 14325.426982920566, 14356.0, 14493.347799348843, 14643.707125857636, 13320.147047070937, 14337.0, 14349.713005739617, 14395.928020359832, 14549.272536373352, 9904.306698531322, 10307.742306442398, 10696.0, 10765.73085672833, 11021.952914473111, 8083.63623636926, 10029.077687457058, 11409.087273017973, 11771.411429428666, 11788.823167652321], "value": 8031.897245756743, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1527.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 99.0, 177.0, 178.0, 130.0, 119.0, 119.0, 117.0, 119.0, 116.0], "value": 128.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 111.0, 110.0, 143.0, 111.0, 115.0, 112.0, 113.0, 129.0, 109.0], "value": 119.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 198.0, 196.0, 200.0, 201.0, 199.0, 199.0, 196.0, 200.0, 194.0], "value": 197.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 61.0, 105.0, 89.0, 85.0, 70.0, 62.0, 73.0, 59.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 68.0, 85.0, 101.0, 106.0, 83.0, 81.0, 70.0, 70.0, 86.0], "value": 83.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 93.0, 127.0, 87.0, 103.0, 115.0, 99.0, 109.0, 93.0, 134.0], "value": 105.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 154.0, 153.0, 96.0, 96.0, 97.0, 97.0, 95.0, 96.0, 103.0], "value": 109.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 99.0, 91.0, 76.0, 137.0, 146.0, 93.0, 90.0, 86.0, 89.0], "value": 101.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 65.0, 66.0, 67.0, 68.0, 69.0, 67.0, 67.0, 69.0], "value": 66.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 63.0, 59.0, 69.0, 58.0, 58.0, 58.0, 58.0, 57.0, 59.0], "value": 59.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 85.0, 86.0, 94.0, 79.0, 87.0, 82.0, 86.0, 82.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 147.0, 146.0, 135.0, 136.0, 135.0, 158.0, 161.0, 189.0, 148.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 162.0, 187.0, 162.0, 165.0, 163.0, 181.0, 164.0, 225.0, 204.0], "value": 177.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 57.0, 70.0, 79.0, 64.0, 67.0, 75.0, 82.0, 83.0], "value": 70.0, "unit": "score"}]}]} 11:32:16 INFO - Return code: 0 11:32:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:32:17 ERROR - # TBPL SUCCESS # 11:32:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:32:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:32:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:32:17 INFO - 2017-05-15 11:32:17.077 screenresolution[2116:12153] starting screenresolution argv=screenresolution get 11:32:17 INFO - 2017-05-15 11:32:17.080 screenresolution[2116:12153] Display 0: 1600x1200x32@60 11:32:17 INFO - 2017-05-15 11:32:17.086 screenresolution[2117:12156] starting screenresolution argv=screenresolution list 11:32:17 INFO - Available Modes on Display 0 11:32:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:32:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:32:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:32:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:32:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:32:17 INFO - Intel Iris: 11:32:17 INFO - Chipset Model: Intel Iris 11:32:17 INFO - Type: GPU 11:32:17 INFO - Bus: Built-In 11:32:17 INFO - VRAM (Dynamic, Max): 1536 MB 11:32:17 INFO - Vendor: Intel (0x8086) 11:32:17 INFO - Device ID: 0x0a2e 11:32:17 INFO - Revision ID: 0x0009 11:32:17 INFO - Displays: 11:32:17 INFO - Display: 11:32:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:32:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:32:17 INFO - Display Serial Number: 1600x1200 60 11:32:17 INFO - Main Display: Yes 11:32:17 INFO - Mirror: Off 11:32:17 INFO - Online: Yes 11:32:17 INFO - Rotation: Supported 11:32:17 INFO - Return code: 0 11:32:17 INFO - Running post-action listener: _resource_record_post_action 11:32:17 INFO - [mozharness: 2017-05-15 18:32:17.338546Z] Finished run-tests step (success) 11:32:17 INFO - Running post-run listener: _resource_record_post_run 11:32:17 INFO - Total resource usage - Wall time: 1191s; CPU: 54.0%; Read bytes: 34291712; Write bytes: 426057728; Read time: 205; Write time: 1422 11:32:17 INFO - TinderboxPrint: CPU usage
54.4% 11:32:17 INFO - TinderboxPrint: I/O read bytes / time
34,291,712 / 205 11:32:17 INFO - TinderboxPrint: I/O write bytes / time
426,057,728 / 1,422 11:32:17 INFO - TinderboxPrint: CPU idle
2,171.2 (45.6%) 11:32:17 INFO - TinderboxPrint: CPU system
956.4 (20.1%) 11:32:17 INFO - TinderboxPrint: CPU user
1,638.3 (34.4%) 11:32:17 INFO - TinderboxPrint: Swap in / out
152,682,496 / 0 11:32:17 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 123015680; Write bytes: 124004352; Read time: 11155; Write time: 279 11:32:17 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 30240768; Write bytes: 299173888; Read time: 180; Write time: 1125 11:32:17 INFO - Running post-run listener: _upload_blobber_files 11:32:17 INFO - Blob upload gear active. 11:32:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:32:17 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 11:32:17 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'] 11:32:17 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 11:32:18 INFO - (blobuploader) - INFO - Open directory for files ... 11:32:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:32:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:32:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:32:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:19 INFO - (blobuploader) - INFO - Done attempting. 11:32:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:32:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:32:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:32:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:20 INFO - (blobuploader) - INFO - Done attempting. 11:32:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:32:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:32:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:21 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:32:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:21 INFO - (blobuploader) - INFO - Done attempting. 11:32:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:32:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:32:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:32:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:32:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:32:23 INFO - (blobuploader) - INFO - Done attempting. 11:32:23 INFO - (blobuploader) - INFO - Iteration through files over. 11:32:23 INFO - Return code: 0 11:32:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:32:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:32:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/542c2226205dbbd033431c88816f3cbf8614136fcb1ad927a3b593bf991f4519f506cbaf4e01761d5491a9654ec0d7fc1deb1e84867c44e953b29cd6c528f5b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b1da18b2fd09ffb8537134ff1d0005f589cc08645d38c3de9ce2eb77be8c1ee016a7197593db0686682f0219e621fee202f51d3245ff8d10c2a735665d7cfa68", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7e3e72ac7a9d9dbf31392523805d877c66c688512acdefd92d05b79c7b8d352aa3164f892303160bea7909f48776dae1594cf0418037f32315275f476a1dad3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11bd59c00358892e9d8c05e9ea6909714d4092736020cfe4a01cd3add458449d59ce3d7df48ea8fe557bf3c5ae768d5ede9af78f98988406f42ea4dda01ad739"} 11:32:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:32:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:32:23 INFO - Contents: 11:32:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/542c2226205dbbd033431c88816f3cbf8614136fcb1ad927a3b593bf991f4519f506cbaf4e01761d5491a9654ec0d7fc1deb1e84867c44e953b29cd6c528f5b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b1da18b2fd09ffb8537134ff1d0005f589cc08645d38c3de9ce2eb77be8c1ee016a7197593db0686682f0219e621fee202f51d3245ff8d10c2a735665d7cfa68", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7e3e72ac7a9d9dbf31392523805d877c66c688512acdefd92d05b79c7b8d352aa3164f892303160bea7909f48776dae1594cf0418037f32315275f476a1dad3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11bd59c00358892e9d8c05e9ea6909714d4092736020cfe4a01cd3add458449d59ce3d7df48ea8fe557bf3c5ae768d5ede9af78f98988406f42ea4dda01ad739"} 11:32:23 INFO - Running post-run listener: copy_logs_to_upload_dir 11:32:23 INFO - Copying logs to upload dir... 11:32:23 INFO - mkdir: /builds/slave/test/build/upload/logs 11:32:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.491761 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2017-05-15 11:32:23.961715) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 11:32:23.966020) ========= 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.lVmkLD5IBX/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.TBf1iKOuPI/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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/542c2226205dbbd033431c88816f3cbf8614136fcb1ad927a3b593bf991f4519f506cbaf4e01761d5491a9654ec0d7fc1deb1e84867c44e953b29cd6c528f5b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b1da18b2fd09ffb8537134ff1d0005f589cc08645d38c3de9ce2eb77be8c1ee016a7197593db0686682f0219e621fee202f51d3245ff8d10c2a735665d7cfa68", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7e3e72ac7a9d9dbf31392523805d877c66c688512acdefd92d05b79c7b8d352aa3164f892303160bea7909f48776dae1594cf0418037f32315275f476a1dad3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11bd59c00358892e9d8c05e9ea6909714d4092736020cfe4a01cd3add458449d59ce3d7df48ea8fe557bf3c5ae768d5ede9af78f98988406f42ea4dda01ad739"} build_url:https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011100 build_url: 'https://queue.taskcluster.net/v1/task/CE_5vMU9RGO2RhnR-OhVWQ/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/542c2226205dbbd033431c88816f3cbf8614136fcb1ad927a3b593bf991f4519f506cbaf4e01761d5491a9654ec0d7fc1deb1e84867c44e953b29cd6c528f5b1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b1da18b2fd09ffb8537134ff1d0005f589cc08645d38c3de9ce2eb77be8c1ee016a7197593db0686682f0219e621fee202f51d3245ff8d10c2a735665d7cfa68", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a7e3e72ac7a9d9dbf31392523805d877c66c688512acdefd92d05b79c7b8d352aa3164f892303160bea7909f48776dae1594cf0418037f32315275f476a1dad3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/11bd59c00358892e9d8c05e9ea6909714d4092736020cfe4a01cd3add458449d59ce3d7df48ea8fe557bf3c5ae768d5ede9af78f98988406f42ea4dda01ad739"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-15 11:32:24.002439) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:32:24.002822) ========= 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.lVmkLD5IBX/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.TBf1iKOuPI/Listeners TMPDIR=/var/folders/v2/kpd6f83x6g5fhqcys8fl7zs800000w/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.005070 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-15 11:32:24.051328) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 11:32:24.052644) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-15 11:32:24.052967) ========= ========= Total master_lag: 0.19 =========