builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0205 starttime: 1495400921.89 results: success (0) revision: cf9f9525e4d1afa2129e997b38414ba8fe8e8e14 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-21 14:08:41.889070) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-21 14:08:41.889469) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-21 14:08:41.889790) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-21 14:08:42.027363) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 14:08:42.027764) ========= 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.wDquRS5ijJ/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.mfQbGwipg9/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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-21 14:08:42-- 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.5M=0.001s 2017-05-21 14:08:42 (18.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.167754 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 14:08:42.269253) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 14:08:42.269589) ========= 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.wDquRS5ijJ/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.mfQbGwipg9/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.027506 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 14:08:42.335819) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-21 14:08:42.336139) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cf9f9525e4d1afa2129e997b38414ba8fe8e8e14 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev cf9f9525e4d1afa2129e997b38414ba8fe8e8e14 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wDquRS5ijJ/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.mfQbGwipg9/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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-21 14:08:42,437 truncating revision to first 12 chars 2017-05-21 14:08:42,437 Setting DEBUG logging. 2017-05-21 14:08:42,437 attempt 1/10 2017-05-21 14:08:42,437 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cf9f9525e4d1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-21 14:08:44,317 unpacking tar archive at: mozilla-central-cf9f9525e4d1/testing/mozharness/ program finished with exit code 0 elapsedTime=2.177263 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-21 14:08:44.535429) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-21 14:08:44.535748) ========= script_repo_revision: cf9f9525e4d1afa2129e997b38414ba8fe8e8e14 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-21 14:08:44.536159) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-21 14:08:44.536522) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-21 14:08:44.554619) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-21 14:08:44.555004) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.wDquRS5ijJ/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.mfQbGwipg9/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 14:08:44 INFO - MultiFileLogger online at 20170521 14:08:44 in /builds/slave/test 14:08:44 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:08:44 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:08:44 INFO - {'append_to_log': False, 14:08:44 INFO - 'base_work_dir': '/builds/slave/test', 14:08:44 INFO - 'blob_upload_branch': 'Firefox', 14:08:44 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:08:44 INFO - 'branch': 'Firefox', 14:08:44 INFO - 'buildbot_json_path': 'buildprops.json', 14:08:44 INFO - 'config_files': ('talos/mac_config.py',), 14:08:44 INFO - 'default_actions': ('clobber', 14:08:44 INFO - 'read-buildbot-config', 14:08:44 INFO - 'download-and-extract', 14:08:44 INFO - 'populate-webroot', 14:08:44 INFO - 'create-virtualenv', 14:08:44 INFO - 'install', 14:08:44 INFO - 'run-tests'), 14:08:44 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:08:44 INFO - 'download_minidump_stackwalk': True, 14:08:44 INFO - 'download_symbols': 'ondemand', 14:08:44 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:08:44 INFO - 'tooltool.py': '/tools/tooltool.py', 14:08:44 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:08:44 INFO - '/tools/misc-python/virtualenv.py')}, 14:08:44 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:08:44 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:08:44 INFO - 'gecko_profile': False, 14:08:44 INFO - 'gecko_profile_interval': 0, 14:08:44 INFO - 'installer_path': 'installer.exe', 14:08:44 INFO - 'log_level': 'info', 14:08:44 INFO - 'log_name': 'talos', 14:08:44 INFO - 'log_to_console': True, 14:08:44 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:08:44 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:08:44 INFO - 'opt_config_files': (), 14:08:44 INFO - 'pip_index': False, 14:08:44 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:08:44 INFO - 'cmd': ('bash', 14:08:44 INFO - '-c', 14:08:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:08:44 INFO - 'enabled': True, 14:08:44 INFO - 'halt_on_failure': False, 14:08:44 INFO - 'name': 'check_screen_resolution'},), 14:08:44 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:08:44 INFO - 'cmd': ('bash', 14:08:44 INFO - '-c', 14:08:44 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:08:44 INFO - 'enabled': True, 14:08:44 INFO - 'halt_on_failure': False, 14:08:44 INFO - 'name': 'check_screen_resolution'},), 14:08:44 INFO - 'run_cmd_checks_enabled': True, 14:08:44 INFO - 'suite': 'dromaeojs-e10s', 14:08:44 INFO - 'system_bits': '32', 14:08:44 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:08:44 INFO - 'title': 't-yosemite-r7-0205', 14:08:44 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:08:44 INFO - 'use_talos_json': True, 14:08:44 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:08:44 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:08:44 INFO - 'work_dir': 'build'} 14:08:44 INFO - [mozharness: 2017-05-21 21:08:44.767733Z] Running clobber step. 14:08:44 INFO - Running pre-action listener: _resource_record_pre_action 14:08:44 INFO - Running main action method: clobber 14:08:44 INFO - rmtree: /builds/slave/test/build 14:08:44 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:08:48 INFO - Running post-action listener: _resource_record_post_action 14:08:48 INFO - [mozharness: 2017-05-21 21:08:48.158829Z] Finished clobber step (success) 14:08:48 INFO - [mozharness: 2017-05-21 21:08:48.158967Z] Running read-buildbot-config step. 14:08:48 INFO - Running pre-action listener: _resource_record_pre_action 14:08:48 INFO - Running main action method: read_buildbot_config 14:08:48 INFO - Using buildbot properties: 14:08:48 INFO - { 14:08:48 INFO - "project": "", 14:08:48 INFO - "product": "firefox", 14:08:48 INFO - "who": "philringnalda@gmail.com", 14:08:48 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 14:08:48 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:08:48 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:08:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:08:48 INFO - "stage_platform": "macosx64", 14:08:48 INFO - "basedir": "/builds/slave/test", 14:08:48 INFO - "buildnumber": 30, 14:08:48 INFO - "platform": "yosemite_r7", 14:08:48 INFO - "parent_task_id": "OsmhPs-ORaymlRlfl7WpVA", 14:08:48 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 14:08:48 INFO - "slavebuilddir": "test", 14:08:48 INFO - "taskId": "cDLxefE3RiSiuZU26DfPUw", 14:08:48 INFO - "branch": "mozilla-central", 14:08:48 INFO - "script_repo_revision": "production", 14:08:48 INFO - "revision": "cf9f9525e4d1afa2129e997b38414ba8fe8e8e14", 14:08:48 INFO - "slavename": "t-yosemite-r7-0205", 14:08:48 INFO - "repo_path": "mozilla-central" 14:08:48 INFO - } 14:08:48 INFO - Finding installer, test and symbols from parent task. 14:08:48 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cDLxefE3RiSiuZU26DfPUw'}, attempt #1 14:08:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cDLxefE3RiSiuZU26DfPUw'}, attempt #1 14:08:49 INFO - Task dependencies: VuKgYLz9S7msb2_mH_DI0A OsmhPs-ORaymlRlfl7WpVA 14:08:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA'}, attempt #1 14:08:50 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:08:50 INFO - Set test_url: https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/test_packages.json 14:08:50 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/target.crashreporter-symbols.zip 14:08:50 INFO - Running post-action listener: _resource_record_post_action 14:08:50 INFO - [mozharness: 2017-05-21 21:08:50.008127Z] Finished read-buildbot-config step (success) 14:08:50 INFO - [mozharness: 2017-05-21 21:08:50.008276Z] Running download-and-extract step. 14:08:50 INFO - Running pre-action listener: _resource_record_pre_action 14:08:50 INFO - Running main action method: download_and_extract 14:08:50 INFO - mkdir: /builds/slave/test/build/tests 14:08:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:08:50 INFO - trying https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:08:50 INFO - Downloading https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:08:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 14:08:51 INFO - Downloaded 1325 bytes. 14:08:51 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:08:51 INFO - Using the following test package requirements: 14:08:51 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:08:51 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:08:51 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:08:51 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:08:51 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:08:51 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:08:51 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:08:51 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:08:51 INFO - u'jsshell-mac.zip'], 14:08:51 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:08:51 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:08:51 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:08:51 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:08:51 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:08:51 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:08:51 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:08:51 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:08:51 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 14:08:51 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:08:51 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:08:51 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:08:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:08:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:08:51 INFO - Fetch https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:08:53 INFO - Content-Length response header: 57622785 14:08:53 INFO - Bytes received: 57622785 14:09:11 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:09:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:09:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:09:11 INFO - Fetch https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:09:12 INFO - Content-Length response header: 57622785 14:09:12 INFO - Bytes received: 57622785 14:09:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:09:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:09:30 INFO - Fetch https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:09:31 INFO - Content-Length response header: 14410000 14:09:31 INFO - Bytes received: 14410000 14:09:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:31 INFO - trying https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:09:31 INFO - Downloading https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:09:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 14:09:33 INFO - Downloaded 68055621 bytes. 14:09:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:09:33 INFO - mkdir: /builds/slave/test/properties 14:09:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:09:33 INFO - Writing to file /builds/slave/test/properties/build_url 14:09:33 INFO - Contents: 14:09:33 INFO - build_url:https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:09:33 INFO - Running post-action listener: _resource_record_post_action 14:09:33 INFO - Running post-action listener: set_extra_try_arguments 14:09:33 INFO - [mozharness: 2017-05-21 21:09:33.740555Z] Finished download-and-extract step (success) 14:09:33 INFO - [mozharness: 2017-05-21 21:09:33.740699Z] Running populate-webroot step. 14:09:33 INFO - Running pre-action listener: _resource_record_pre_action 14:09:33 INFO - Running main action method: populate_webroot 14:09:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:09:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:09:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:09:33 INFO - '--tppagecycles', 14:09:33 INFO - '7'], 14:09:33 INFO - 'tests': ['tsvgm']}}, 14:09:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:09:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:09:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:09:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:09:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:09:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:09:33 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:09:33 INFO - 'talos_options': ['--disable-e10s'], 14:09:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:09:33 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:09:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:09:33 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:09:33 INFO - 'talos_options': ['--disable-e10s'], 14:09:33 INFO - 'tests': ['damp', 'tps']}, 14:09:33 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:09:33 INFO - 'tests': ['damp', 'tps']}, 14:09:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:09:33 INFO - 'tests': ['dromaeo_dom']}, 14:09:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:09:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:09:33 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:09:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:09:33 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:09:33 INFO - 'tests': ['ts_paint', 'tp5o']}, 14:09:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:09:33 INFO - 'tests': ['a11yr', 14:09:33 INFO - 'ts_paint', 14:09:33 INFO - 'tpaint', 14:09:33 INFO - 'sessionrestore', 14:09:33 INFO - 'sessionrestore_no_auto_restore', 14:09:33 INFO - 'tabpaint']}, 14:09:33 INFO - 'other-e10s': {'tests': ['a11yr', 14:09:33 INFO - 'ts_paint', 14:09:33 INFO - 'tpaint', 14:09:33 INFO - 'sessionrestore', 14:09:33 INFO - 'sessionrestore_no_auto_restore', 14:09:33 INFO - 'tabpaint']}, 14:09:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:09:33 INFO - 'ts_paint', 14:09:33 INFO - 'tpaint', 14:09:33 INFO - 'sessionrestore', 14:09:33 INFO - 'sessionrestore_no_auto_restore', 14:09:33 INFO - 'tabpaint']}, 14:09:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:09:33 INFO - 'ts_paint', 14:09:33 INFO - 'tpaint', 14:09:33 INFO - 'sessionrestore', 14:09:33 INFO - 'sessionrestore_no_auto_restore', 14:09:33 INFO - 'tabpaint']}, 14:09:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:09:33 INFO - 'tests': ['a11yr', 14:09:33 INFO - 'ts_paint', 14:09:33 INFO - 'tpaint', 14:09:33 INFO - 'sessionrestore', 14:09:33 INFO - 'sessionrestore_no_auto_restore', 14:09:33 INFO - 'tabpaint']}, 14:09:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:09:33 INFO - 'tests': ['a11yr', 14:09:33 INFO - 'ts_paint', 14:09:33 INFO - 'tpaint', 14:09:33 INFO - 'sessionrestore', 14:09:33 INFO - 'sessionrestore_no_auto_restore', 14:09:33 INFO - 'tabpaint']}, 14:09:33 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:09:33 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:09:33 INFO - 'bloom_basic_ref']}, 14:09:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:09:33 INFO - 'tests': ['tsvgx', 14:09:33 INFO - 'tsvgr_opacity', 14:09:33 INFO - 'tart', 14:09:33 INFO - 'tscrollx', 14:09:33 INFO - 'cart', 14:09:33 INFO - 'tsvg_static']}, 14:09:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:09:33 INFO - 'tsvgr_opacity', 14:09:33 INFO - 'tart', 14:09:33 INFO - 'tscrollx', 14:09:33 INFO - 'cart', 14:09:33 INFO - 'tsvg_static']}, 14:09:33 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:09:33 INFO - 'talos_options': ['--disable-e10s'], 14:09:33 INFO - 'tests': ['tp5o']}, 14:09:33 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:09:33 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:09:33 INFO - 'talos_options': ['--disable-e10s', 14:09:33 INFO - '--xperf_path', 14:09:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:09:33 INFO - 'tests': ['tp5n']}, 14:09:33 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:09:33 INFO - 'talos_options': ['--xperf_path', 14:09:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:09:33 INFO - 'tests': ['tp5n']}}, 14:09:33 INFO - 'talos.zip': {'path': '', 14:09:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:09:33 INFO - Running post-action listener: _resource_record_post_action 14:09:33 INFO - [mozharness: 2017-05-21 21:09:33.762529Z] Finished populate-webroot step (success) 14:09:33 INFO - [mozharness: 2017-05-21 21:09:33.762665Z] Running create-virtualenv step. 14:09:33 INFO - Running pre-action listener: _resource_record_pre_action 14:09:33 INFO - Running main action method: create_virtualenv 14:09:33 INFO - Creating virtualenv /builds/slave/test/build/venv 14:09:33 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 14:09:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:09:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:09:33 INFO - Using real prefix '/tools/python27' 14:09:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:09:34 INFO - Installing distribute.............................................................................................................................................................................................done. 14:09:37 INFO - Installing pip.................done. 14:09:37 INFO - Return code: 0 14:09:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:09:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:09:37 INFO - Reading from file tmpfile_stdout 14:09:37 INFO - Output received: 14:09:37 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) 14:09:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:09:37 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')]} 14:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:37 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')]} 14:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:37 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 0x100fd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fd6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b58551110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce2d30>, '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 0x100c96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd7be8>, '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.wDquRS5ijJ/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.mfQbGwipg9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 14:09:37 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 14:09:37 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 14:09:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDquRS5ijJ/Render', 14:09:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:37 INFO - 'HOME': '/Users/cltbld', 14:09:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:37 INFO - 'LOGNAME': 'cltbld', 14:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:37 INFO - 'MOZ_NO_REMOTE': '1', 14:09:37 INFO - 'NO_EM_RESTART': '1', 14:09:37 INFO - 'PAGER': '/bin/cat', 14:09:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:37 INFO - 'PWD': '/builds/slave/test', 14:09:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:37 INFO - 'SHELL': '/bin/bash', 14:09:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mfQbGwipg9/Listeners', 14:09:37 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 14:09:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:37 INFO - 'USER': 'cltbld', 14:09:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:37 INFO - 'XPC_FLAGS': '0x0', 14:09:37 INFO - 'XPC_SERVICE_NAME': '0', 14:09:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:38 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 14:09:38 INFO - Cleaning up... 14:09:38 INFO - Return code: 0 14:09:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100fd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fd6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b58551110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce2d30>, '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 0x100c96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd7be8>, '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.wDquRS5ijJ/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.mfQbGwipg9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 14:09:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:09:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDquRS5ijJ/Render', 14:09:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:38 INFO - 'HOME': '/Users/cltbld', 14:09:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:38 INFO - 'LOGNAME': 'cltbld', 14:09:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:38 INFO - 'MOZ_NO_REMOTE': '1', 14:09:38 INFO - 'NO_EM_RESTART': '1', 14:09:38 INFO - 'PAGER': '/bin/cat', 14:09:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:38 INFO - 'PWD': '/builds/slave/test', 14:09:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:38 INFO - 'SHELL': '/bin/bash', 14:09:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mfQbGwipg9/Listeners', 14:09:38 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 14:09:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:38 INFO - 'USER': 'cltbld', 14:09:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:38 INFO - 'XPC_FLAGS': '0x0', 14:09:38 INFO - 'XPC_SERVICE_NAME': '0', 14:09:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:38 INFO - Downloading/unpacking psutil>=3.1.1 14:09:38 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 14:09:38 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 14:09:38 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 14:09:38 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 14:09:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:09:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:40 INFO - Installing collected packages: psutil 14:09:40 INFO - Running setup.py install for psutil 14:09:40 INFO - building 'psutil._psutil_osx' extension 14:09:40 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 14:09:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:09:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:09:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:09:41 INFO - building 'psutil._psutil_posix' extension 14:09:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:09:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:09:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:09:41 INFO - ^ 14:09:41 INFO - 1 warning generated. 14:09:41 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:09:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:41 INFO - Successfully installed psutil 14:09:41 INFO - Cleaning up... 14:09:41 INFO - Return code: 0 14:09:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:09: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')]} 14:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09: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')]} 14:09:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:41 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 0x100fd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fd6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b58551110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce2d30>, '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 0x100c96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd7be8>, '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.wDquRS5ijJ/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.mfQbGwipg9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 14:09:41 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 14:09:41 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 14:09:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDquRS5ijJ/Render', 14:09:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:41 INFO - 'HOME': '/Users/cltbld', 14:09:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:41 INFO - 'LOGNAME': 'cltbld', 14:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:41 INFO - 'MOZ_NO_REMOTE': '1', 14:09:41 INFO - 'NO_EM_RESTART': '1', 14:09:41 INFO - 'PAGER': '/bin/cat', 14:09:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:41 INFO - 'PWD': '/builds/slave/test', 14:09:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:41 INFO - 'SHELL': '/bin/bash', 14:09:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mfQbGwipg9/Listeners', 14:09:41 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 14:09:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:41 INFO - 'USER': 'cltbld', 14:09:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:41 INFO - 'XPC_FLAGS': '0x0', 14:09:41 INFO - 'XPC_SERVICE_NAME': '0', 14:09:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:41 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:09:41 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 14:09:41 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 14:09:41 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 14:09:41 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 14:09:43 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:09:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:09:43 INFO - Installing collected packages: mozsystemmonitor 14:09:43 INFO - Running setup.py install for mozsystemmonitor 14:09:43 INFO - Successfully installed mozsystemmonitor 14:09:43 INFO - Cleaning up... 14:09:43 INFO - Return code: 0 14:09:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100fd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fd6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b58551110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce2d30>, '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 0x100c96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd7be8>, '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.wDquRS5ijJ/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.mfQbGwipg9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 14:09:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:09:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:09:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDquRS5ijJ/Render', 14:09:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:43 INFO - 'HOME': '/Users/cltbld', 14:09:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:43 INFO - 'LOGNAME': 'cltbld', 14:09:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:43 INFO - 'MOZ_NO_REMOTE': '1', 14:09:43 INFO - 'NO_EM_RESTART': '1', 14:09:43 INFO - 'PAGER': '/bin/cat', 14:09:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:43 INFO - 'PWD': '/builds/slave/test', 14:09:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:43 INFO - 'SHELL': '/bin/bash', 14:09:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mfQbGwipg9/Listeners', 14:09:43 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 14:09:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:43 INFO - 'USER': 'cltbld', 14:09:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:43 INFO - 'XPC_FLAGS': '0x0', 14:09:43 INFO - 'XPC_SERVICE_NAME': '0', 14:09:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:43 INFO - Downloading/unpacking jsonschema==2.5.1 14:09:43 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 14:09:43 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 14:09:43 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 14:09:43 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 14:09:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:09:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:45 INFO - Downloading functools32-3.2.3-2.tar.gz 14:09:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:09:45 INFO - warning: no files found matching '*.txt' 14:09:45 INFO - no previously-included directories found matching 'build' 14:09:45 INFO - no previously-included directories found matching 'dist' 14:09:45 INFO - no previously-included directories found matching '.git*' 14:09:45 INFO - Installing collected packages: jsonschema, functools32 14:09:45 INFO - Running setup.py install for functools32 14:09:45 INFO - warning: no files found matching '*.txt' 14:09:45 INFO - no previously-included directories found matching 'build' 14:09:45 INFO - no previously-included directories found matching 'dist' 14:09:45 INFO - no previously-included directories found matching '.git*' 14:09:45 INFO - Successfully installed jsonschema functools32 14:09:45 INFO - Cleaning up... 14:09:45 INFO - Return code: 0 14:09:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:09:45 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')]} 14:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:45 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')]} 14:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:45 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 0x100fd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fd6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b58551110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce2d30>, '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 0x100c96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd7be8>, '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.wDquRS5ijJ/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.mfQbGwipg9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 14:09:45 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 14:09:45 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 14:09:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDquRS5ijJ/Render', 14:09:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:45 INFO - 'HOME': '/Users/cltbld', 14:09:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:45 INFO - 'LOGNAME': 'cltbld', 14:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:45 INFO - 'MOZ_NO_REMOTE': '1', 14:09:45 INFO - 'NO_EM_RESTART': '1', 14:09:45 INFO - 'PAGER': '/bin/cat', 14:09:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:45 INFO - 'PWD': '/builds/slave/test', 14:09:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:45 INFO - 'SHELL': '/bin/bash', 14:09:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mfQbGwipg9/Listeners', 14:09:45 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 14:09:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:45 INFO - 'USER': 'cltbld', 14:09:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:45 INFO - 'XPC_FLAGS': '0x0', 14:09:45 INFO - 'XPC_SERVICE_NAME': '0', 14:09:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:09:46 INFO - Cleaning up... 14:09:46 INFO - Return code: 0 14:09:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:09:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100fd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fd6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b58551110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce2d30>, '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 0x100c96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd7be8>, '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.wDquRS5ijJ/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.mfQbGwipg9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 14:09:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:09:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:09:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDquRS5ijJ/Render', 14:09:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:46 INFO - 'HOME': '/Users/cltbld', 14:09:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:46 INFO - 'LOGNAME': 'cltbld', 14:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:46 INFO - 'MOZ_NO_REMOTE': '1', 14:09:46 INFO - 'NO_EM_RESTART': '1', 14:09:46 INFO - 'PAGER': '/bin/cat', 14:09:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:46 INFO - 'PWD': '/builds/slave/test', 14:09:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:46 INFO - 'SHELL': '/bin/bash', 14:09:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mfQbGwipg9/Listeners', 14:09:46 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 14:09:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:46 INFO - 'USER': 'cltbld', 14:09:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:46 INFO - 'XPC_FLAGS': '0x0', 14:09:46 INFO - 'XPC_SERVICE_NAME': '0', 14:09:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:46 INFO - Downloading/unpacking blobuploader==1.2.4 14:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:47 INFO - Downloading blobuploader-1.2.4.tar.gz 14:09:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:09:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:09:47 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 14:09:47 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 14:09:47 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 14:09:47 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 14:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:09:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:48 INFO - Downloading docopt-0.6.1.tar.gz 14:09:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:09:48 INFO - Installing collected packages: blobuploader, requests, docopt 14:09:48 INFO - Running setup.py install for blobuploader 14:09:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:09:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:09:48 INFO - Running setup.py install for requests 14:09:49 INFO - Running setup.py install for docopt 14:09:49 INFO - Successfully installed blobuploader requests docopt 14:09:49 INFO - Cleaning up... 14:09:49 INFO - Return code: 0 14:09:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:49 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 0x100fd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fd6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b58551110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce2d30>, '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 0x100c96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd7be8>, '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.wDquRS5ijJ/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.mfQbGwipg9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 14:09:49 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 14:09:49 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 14:09:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDquRS5ijJ/Render', 14:09:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:49 INFO - 'HOME': '/Users/cltbld', 14:09:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:49 INFO - 'LOGNAME': 'cltbld', 14:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:49 INFO - 'MOZ_NO_REMOTE': '1', 14:09:49 INFO - 'NO_EM_RESTART': '1', 14:09:49 INFO - 'PAGER': '/bin/cat', 14:09:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:49 INFO - 'PWD': '/builds/slave/test', 14:09:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:49 INFO - 'SHELL': '/bin/bash', 14:09:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mfQbGwipg9/Listeners', 14:09:49 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 14:09:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:49 INFO - 'USER': 'cltbld', 14:09:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:49 INFO - 'XPC_FLAGS': '0x0', 14:09:49 INFO - 'XPC_SERVICE_NAME': '0', 14:09:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:09:49 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-nREVNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:09:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:09:49 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-dl8VPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:09:50 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-J0m1Os-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:09:50 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-RQGn24-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:09:50 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-nhku7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:09:50 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-EAFi2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:09:50 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-VIG8xJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:09:50 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-MzQEVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:09:50 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-z8Vx2g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:09:50 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-JypYFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:09:50 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-qqyJAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:09:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:09:51 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-pjyiSX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:09:51 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-QYkfVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:09:51 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-SLHApR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:51 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-S6NwMP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:09:51 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-jblPBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:09:51 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-6AzDFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:09:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:09:51 INFO - Running setup.py install for manifestparser 14:09:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:09:51 INFO - Running setup.py install for mozcrash 14:09:51 INFO - Running setup.py install for mozdebug 14:09:52 INFO - Running setup.py install for mozdevice 14:09:52 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:09:52 INFO - Running setup.py install for mozfile 14:09:52 INFO - Running setup.py install for mozhttpd 14:09:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:09:52 INFO - Running setup.py install for mozinfo 14:09:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:09:52 INFO - Running setup.py install for mozInstall 14:09:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:09:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:09:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:09:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:09:52 INFO - Running setup.py install for mozleak 14:09:52 INFO - Running setup.py install for mozlog 14:09:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:09:53 INFO - Running setup.py install for moznetwork 14:09:53 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:09:53 INFO - Running setup.py install for mozprocess 14:09:53 INFO - Running setup.py install for mozprofile 14:09:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:09:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:09:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:09:53 INFO - Running setup.py install for mozrunner 14:09:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:09:53 INFO - Running setup.py install for mozscreenshot 14:09:53 INFO - Running setup.py install for moztest 14:09:54 INFO - Running setup.py install for mozversion 14:09:54 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:09:54 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:09:54 INFO - Cleaning up... 14:09:54 INFO - Return code: 0 14:09:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:54 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 0x100fd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fd6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b58551110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce2d30>, '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 0x100c96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd7be8>, '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.wDquRS5ijJ/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.mfQbGwipg9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 14:09:54 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 14:09:54 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 14:09:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDquRS5ijJ/Render', 14:09:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:54 INFO - 'HOME': '/Users/cltbld', 14:09:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:54 INFO - 'LOGNAME': 'cltbld', 14:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:54 INFO - 'MOZ_NO_REMOTE': '1', 14:09:54 INFO - 'NO_EM_RESTART': '1', 14:09:54 INFO - 'PAGER': '/bin/cat', 14:09:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:54 INFO - 'PWD': '/builds/slave/test', 14:09:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:54 INFO - 'SHELL': '/bin/bash', 14:09:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mfQbGwipg9/Listeners', 14:09:54 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 14:09:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:54 INFO - 'USER': 'cltbld', 14:09:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:54 INFO - 'XPC_FLAGS': '0x0', 14:09:54 INFO - 'XPC_SERVICE_NAME': '0', 14:09:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:09:54 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-BcUiA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:09:54 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)) 14:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:09:54 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-FX0eWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:09:54 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)) 14:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:09:54 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-lzXmNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:09:54 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)) 14:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:09:54 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-68tviZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:09:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 14:09:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:09:54 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-KzR_cM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:09:55 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)) 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:09:55 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-boyqCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:09:55 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)) 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:09:55 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-DAl0ub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:09:55 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)) 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:09:55 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Tkr4pO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:09:55 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)) 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:09:55 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-f1mr29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:09:55 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)) 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:09:55 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-bX6PWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:09:55 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)) 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:09:55 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-Y8YDKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:09:55 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)) 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:09:55 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-iJogeJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:09:55 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)) 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:09:55 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-KFUtVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:09:55 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)) 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:09:55 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-cyZBjO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:09:55 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)) 14:09:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:55 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-6hC3ZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:56 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)) 14:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:09:56 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-lgCE7M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:09:56 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)) 14:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:09:56 INFO - Running setup.py (path:/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/pip-UKJ3NL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:09:56 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)) 14:09:56 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)) 14:09:56 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)) 14:09:56 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)) 14:09:56 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:09:56 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:09:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:09:56 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 14:09:56 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 14:09:56 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 14:09:56 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 14:09:57 INFO - Downloading blessings-1.6.tar.gz 14:09:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:09:57 INFO - Installing collected packages: blessings 14:09:57 INFO - Running setup.py install for blessings 14:09:57 INFO - Successfully installed blessings 14:09:57 INFO - Cleaning up... 14:09:58 INFO - Return code: 0 14:09:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:09:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:09:58 INFO - Reading from file tmpfile_stdout 14:09:58 INFO - Current package versions: 14:09:58 INFO - blessings == 1.6 14:09:58 INFO - blobuploader == 1.2.4 14:09:58 INFO - docopt == 0.6.1 14:09:58 INFO - functools32 == 3.2.3-2 14:09:58 INFO - jsonschema == 2.5.1 14:09:58 INFO - manifestparser == 1.1 14:09:58 INFO - mozInstall == 1.12 14:09:58 INFO - mozcrash == 1.0 14:09:58 INFO - mozdebug == 0.1 14:09:58 INFO - mozdevice == 0.50 14:09:58 INFO - mozfile == 1.2 14:09:58 INFO - mozhttpd == 0.7 14:09:58 INFO - mozinfo == 0.9 14:09:58 INFO - mozleak == 0.1 14:09:58 INFO - mozlog == 3.4 14:09:58 INFO - moznetwork == 0.27 14:09:58 INFO - mozprocess == 0.25 14:09:58 INFO - mozprofile == 0.28 14:09:58 INFO - mozrunner == 6.13 14:09:58 INFO - mozscreenshot == 0.1 14:09:58 INFO - mozsystemmonitor == 0.3 14:09:58 INFO - moztest == 0.8 14:09:58 INFO - mozversion == 1.4 14:09:58 INFO - psutil == 3.1.1 14:09:58 INFO - requests == 1.2.3 14:09:58 INFO - wsgiref == 0.1.2 14:09:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09:58 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')]} 14:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:58 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')]} 14:09:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:58 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 0x100fd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fd6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b58551110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce2d30>, '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 0x100c96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd7be8>, '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.wDquRS5ijJ/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.mfQbGwipg9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 14:09:58 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 14:09:58 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 14:09:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDquRS5ijJ/Render', 14:09:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:58 INFO - 'HOME': '/Users/cltbld', 14:09:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:58 INFO - 'LOGNAME': 'cltbld', 14:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:58 INFO - 'MOZ_NO_REMOTE': '1', 14:09:58 INFO - 'NO_EM_RESTART': '1', 14:09:58 INFO - 'PAGER': '/bin/cat', 14:09:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:58 INFO - 'PWD': '/builds/slave/test', 14:09:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:58 INFO - 'SHELL': '/bin/bash', 14:09:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mfQbGwipg9/Listeners', 14:09:58 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 14:09:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:58 INFO - 'USER': 'cltbld', 14:09:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:58 INFO - 'XPC_FLAGS': '0x0', 14:09:58 INFO - 'XPC_SERVICE_NAME': '0', 14:09:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:58 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)) 14:09:58 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)) 14:09:58 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)) 14:09:58 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)) 14:09:58 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)) 14:09:58 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)) 14:09:58 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)) 14:09:58 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)) 14:09:58 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)) 14:09:58 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)) 14:09:58 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)) 14:09:58 INFO - Cleaning up... 14:09:58 INFO - Return code: 0 14:09:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:09:58 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')]} 14:09:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:58 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')]} 14:09:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:58 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 0x100fd2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100fd6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b58551110>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ce2d30>, '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 0x100c96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd7be8>, '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.wDquRS5ijJ/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.mfQbGwipg9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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 14:09:58 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 14:09:58 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 14:09:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDquRS5ijJ/Render', 14:09:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:58 INFO - 'HOME': '/Users/cltbld', 14:09:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:58 INFO - 'LOGNAME': 'cltbld', 14:09:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:58 INFO - 'MOZ_NO_REMOTE': '1', 14:09:58 INFO - 'NO_EM_RESTART': '1', 14:09:58 INFO - 'PAGER': '/bin/cat', 14:09:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:58 INFO - 'PWD': '/builds/slave/test', 14:09:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:58 INFO - 'SHELL': '/bin/bash', 14:09:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mfQbGwipg9/Listeners', 14:09:58 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 14:09:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:58 INFO - 'USER': 'cltbld', 14:09:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:58 INFO - 'XPC_FLAGS': '0x0', 14:09:58 INFO - 'XPC_SERVICE_NAME': '0', 14:09:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:09:58 INFO - Cleaning up... 14:09:58 INFO - Return code: 0 14:09:58 INFO - Running post-action listener: _resource_record_post_action 14:09:58 INFO - Running post-action listener: _start_resource_monitoring 14:09:58 INFO - Starting resource monitoring. 14:09:58 INFO - [mozharness: 2017-05-21 21:09:58.833263Z] Finished create-virtualenv step (success) 14:09:58 INFO - [mozharness: 2017-05-21 21:09:58.834195Z] Running install step. 14:09:58 INFO - Running pre-action listener: _resource_record_pre_action 14:09:58 INFO - Running main action method: install 14:09:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:09:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:09:59 INFO - Reading from file tmpfile_stdout 14:09:59 INFO - Detecting whether we're running mozinstall >=1.0... 14:09:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:09:59 INFO - Reading from file tmpfile_stdout 14:09:59 INFO - Output received: 14:09:59 INFO - Usage: mozinstall [options] installer 14:09:59 INFO - Options: 14:09:59 INFO - -h, --help show this help message and exit 14:09:59 INFO - -d DEST, --destination=DEST 14:09:59 INFO - Directory to install application into. [default: 14:09:59 INFO - "/builds/slave/test"] 14:09:59 INFO - --app=APP Application being installed. [default: firefox] 14:09:59 INFO - mkdir: /builds/slave/test/build/application 14:09:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:10:20 INFO - Reading from file tmpfile_stdout 14:10:20 INFO - Output received: 14:10:20 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:10:20 INFO - Running post-action listener: _resource_record_post_action 14:10:20 INFO - [mozharness: 2017-05-21 21:10:20.099203Z] Finished install step (success) 14:10:20 INFO - [mozharness: 2017-05-21 21:10:20.099359Z] Running run-tests step. 14:10:20 INFO - Running pre-action listener: _resource_record_pre_action 14:10:20 INFO - Running main action method: run_tests 14:10:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:10:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:10:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:10:20 INFO - 2017-05-21 14:10:20.113 screenresolution[1639:12120] starting screenresolution argv=screenresolution get 14:10:20 INFO - 2017-05-21 14:10:20.116 screenresolution[1639:12120] Display 0: 1600x1200x32@60 14:10:20 INFO - 2017-05-21 14:10:20.122 screenresolution[1640:12123] starting screenresolution argv=screenresolution list 14:10:20 INFO - Available Modes on Display 0 14:10:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:10:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:10:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:10:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:10:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:10:20 INFO - Intel Iris: 14:10:20 INFO - Chipset Model: Intel Iris 14:10:20 INFO - Type: GPU 14:10:20 INFO - Bus: Built-In 14:10:20 INFO - VRAM (Dynamic, Max): 1536 MB 14:10:20 INFO - Vendor: Intel (0x8086) 14:10:20 INFO - Device ID: 0x0a2e 14:10:20 INFO - Revision ID: 0x0009 14:10:20 INFO - Displays: 14:10:20 INFO - Display: 14:10:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:10:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:10:20 INFO - Display Serial Number: 1600x1200 60 14:10:20 INFO - Main Display: Yes 14:10:20 INFO - Mirror: Off 14:10:20 INFO - Online: Yes 14:10:20 INFO - Rotation: Supported 14:10:20 INFO - Return code: 0 14:10:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:10:20 INFO - Python 2.7.3 14:10:20 INFO - Return code: 0 14:10:20 INFO - grabbing minidump binary from tooltool 14:10:20 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')]} 14:10:20 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 0x100ce2d30>, '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 0x100c96db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cd7be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:10:20 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 14:10:20 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 14:10:20 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 14:10:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:10:20 INFO - Return code: 0 14:10:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:10:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:10:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:10:20 INFO - ENV: RUST_BACKTRACE is now 1 14:10:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:10:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:10:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--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 14:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0205 --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 14:10:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.wDquRS5ijJ/Render', 14:10:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:10:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:10:20 INFO - 'HOME': '/Users/cltbld', 14:10:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:10:20 INFO - 'LOGNAME': 'cltbld', 14:10:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:10:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:20 INFO - 'MOZ_NO_REMOTE': '1', 14:10:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:10:20 INFO - 'NO_EM_RESTART': '1', 14:10:20 INFO - 'PAGER': '/bin/cat', 14:10:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:20 INFO - 'PWD': '/builds/slave/test', 14:10:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:10:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:10:20 INFO - 'RUST_BACKTRACE': '1', 14:10:20 INFO - 'SHELL': '/bin/bash', 14:10:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mfQbGwipg9/Listeners', 14:10:20 INFO - 'TMPDIR': '/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/', 14:10:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:10:20 INFO - 'USER': 'cltbld', 14:10:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:10:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:20 INFO - 'XPC_FLAGS': '0x0', 14:10:20 INFO - 'XPC_SERVICE_NAME': '0', 14:10:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:10:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0205', '--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 14:10:20 INFO - mozversion application_buildid: 20170521130644 14:10:20 INFO - mozversion application_changeset: cf9f9525e4d1afa2129e997b38414ba8fe8e8e14 14:10:20 INFO - mozversion application_display_name: Nightly 14:10:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:10:20 INFO - mozversion application_name: Firefox 14:10:20 INFO - mozversion application_remotingname: firefox 14:10:20 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:10:20 INFO - mozversion application_vendor: Mozilla 14:10:20 INFO - mozversion application_version: 55.0a1 14:10:20 INFO - mozversion platform_buildid: 20170521130644 14:10:20 INFO - mozversion platform_changeset: cf9f9525e4d1afa2129e997b38414ba8fe8e8e14 14:10:20 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:10:20 INFO - mozversion platform_version: 55.0a1 14:10:20 INFO - using testdate: 1495401020 14:10:20 INFO - actual date: 1495401020 14:10:20 INFO - starting webserver on 'localhost:49226' 14:10:20 INFO - SUITE-START | Running 2 tests 14:10:20 INFO - TEST-START | dromaeo_css 14:10:20 INFO - operating with platform_type : mac_ 14:10:20 INFO - Initialising browser for dromaeo_css test... 14:10:20 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpO4nX9A/profile http://localhost:49226/getInfo.html) 14:10:21 INFO - PID 1650 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2970: String.localeCompare is deprecated; use String.prototype.localeCompare instead 14:10:23 INFO - PID 1650 | 2017-05-21 14:10:23.323 plugin-container[1653:12305] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b3f, name = 'com.apple.tsm.portname' 14:10:23 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:10:23 INFO - PID 1650 | 2017-05-21 14:10:23.326 plugin-container[1653:12305] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa30b, name = 'com.apple.CFPasteboardClient' 14:10:23 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:10:23 INFO - PID 1650 | 2017-05-21 14:10:23.326 plugin-container[1653:12305] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:10:23 INFO - PID 1650 | __metrics Screen width/height:1600/1200 14:10:23 INFO - PID 1650 | colorDepth:24 14:10:23 INFO - PID 1650 | Browser inner width/height: 1024/691 14:10:23 INFO - PID 1650 | __metrics 14:10:23 INFO - PID 1650 | 2017-05-21 14:10:23.852 plugin-container[1654:12358] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 14:10:23 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:10:23 INFO - PID 1650 | 2017-05-21 14:10:23.852 plugin-container[1654:12358] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e03, name = 'com.apple.CFPasteboardClient' 14:10:23 INFO - PID 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:10:23 INFO - PID 1650 | 2017-05-21 14:10:23.852 plugin-container[1654:12358] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:10:24 INFO - TEST-INFO | 1650: exit 0 14:10:24 INFO - Browser initialized. 14:10:24 INFO - Running cycle 1/1 for dromaeo_css test... 14:10:24 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpO4nX9A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:10:26 INFO - PID 1655 | 2017-05-21 14:10:26.308 plugin-container[1656:12456] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 14:10:26 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:10:26 INFO - PID 1655 | 2017-05-21 14:10:26.309 plugin-container[1656:12456] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 14:10:26 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:10:26 INFO - PID 1655 | 2017-05-21 14:10:26.309 plugin-container[1656:12456] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:10:26 INFO - PID 1655 | 2017-05-21 14:10:26.967 plugin-container[1657:12503] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9543, name = 'com.apple.tsm.portname' 14:10:26 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:10:26 INFO - PID 1655 | 2017-05-21 14:10:26.967 plugin-container[1657:12503] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 14:10:26 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:10:26 INFO - PID 1655 | 2017-05-21 14:10:26.967 plugin-container[1657:12503] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:10:36 INFO - PID 1655 | RSS: Main: 240414720 14:10:36 INFO - PID 1655 | 14:13:39 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 14:13:41 INFO - PID 1655 | RSS: Main: 262131712 14:13:41 INFO - PID 1655 | 14:16:42 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 14:16:43 INFO - PID 1655 | RSS: Main: 268894208 14:16:43 INFO - PID 1655 | 14:19:45 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 14:19:46 INFO - PID 1655 | RSS: Main: 272588800 14:19:46 INFO - PID 1655 | 14:22:47 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 14:22:48 INFO - PID 1655 | RSS: Main: 270409728 14:22:48 INFO - PID 1655 | 14:25:51 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 14:25:51 INFO - PID 1655 | RSS: Main: 271900672 14:25:51 INFO - PID 1655 | 14:28:53 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 14:28:54 INFO - PID 1655 | RSS: Main: 273178624 14:28:54 INFO - PID 1655 | 14:28:54 INFO - PID 1655 | __start_tp_report 14:28:54 INFO - PID 1655 | _x_x_mozilla_page_load 14:28:54 INFO - PID 1655 | _x_x_mozilla_page_load_details 14:28:54 INFO - PID 1655 | |i|pagename|runs| 14:28:54 INFO - PID 1655 | |0;dojo.html;3453;3479.704225140863;3492.807895565743;3494.8602055917763;3502.91242718932;6042.818715438544;6090.66501342426;6168.691565421734;6168.784092556762;6187.1956645636;6111.083337499377;6111.755529778815;6128.632282063079;6135.81592552223;6143.32423433422;5250.684958902459;5278.604104692145;5282.471752824716;5301.946980530184;5395.082835917884;4776.09254241693;5420.403755586883;5438.048341540234;5483.314585676791;5487.506124448799;4476.9552304476865;4756.096341695084;5219.504147106038;5418.891622167574;5438.374586922495;5424.701641409741;5433.347998240237;5436.374816896028;5476.26070480484;5480.588955828329;4033.9394909076386;5169.431362550116;5221.817236396746;5282.81510147143;5327.46725327468;5919.230500034968;6218.378162183791;6231.189945307126;6264.1543391642;6267.561270711052;6013.579049466539;6539.149910511651;6573.1783527059115;6574.605523668547;6605.108310378086;6522.967385163091;6623.966880165616;6704.229013663442;6748.763793267212;6752.088468056799;6253.906191407133;6271.153394291803;6298.275698294663;6318.462930650854;6352.174217351715;6835.384815366641;6861.176658800976;6861.897071543931;6864.1762988440705;6877.58734475933;6308.148399965946;6469.870602587922;6491.642959637265;6495.382938620824;6526.902096468557;6558.147440832661;6613.14029176204;6673.599584024974;6682.298358672367;6711.463082953352;6494.772682956074;6571.835704107436;6580.440662543641;6609;6617.437517810944;5671.574631902624;6352.7141278642575;6538.411542961157;6632.834179145463;6679.76620818269;4251.6598672106775;5242.9213561796605;5299.867503312448;5369.3556773187465;5378.300820893259;4727.834525791581;4739.857804265877;4760.238361862085;4762.023785124111;4766.380370551876;6247.031710084944;6282;6293.590916590407;6304.936950630527;6332.968335158294;6509.609423434609;6594.769183078569;6683.465322774166;6730.125083739083;6731.49513786468;5280.6039547034125;6206.534509911775;6295.748170073146;6307;6346.30190679025;5873.3832947539895;5978.432048955342;5999.160117583542;6003.969980150071;6012;6653.700583473658;6744.393004629607;6784.86430271382;6903.1716194055025;6953.096097507393;6783.05037294789;6784.898226526508;6805.795826125224;6810.455163586802;6814.2163651180745;6857.245702972768;6919.204291506541;6924.861502770017;6927.826804329932;6958.582485050913;6388.520860935356;6595.114176855726;6627.569208001464;6658.866822663616;6662.100616416916;6590.5716128451495;6652.800415987528;6733.3939945405145;6769.221539523018;6775.86448271022;6513.54405191632;6533.869322613426;6547.672616369257;6550.934490655223;6554.082428460115;6442.774502892376;6492.642904640101;6523.478121750343;6542.084108224757;6546.214454265519;5630.268065151504;6109.43503133753;6168.25980882297;6264.812055638338;6291.245050593958;6457.903131453126;6477.352264773485;6481.287058423664;6491.935080649133;6516.478681705547;5209.817656382009;6219.502439804714;6261.467775239155;6281.152044473937;6325.620462772248 14:28:54 INFO - PID 1655 | |1;ext.html;691.7371398868429;758.2909979169474;828.0849661124455;1221.8778122187782;1275.993620031901;38612.613873861286;39528.01179970502;39790;39797.20405591886;39899.60100398995;40407.787766366986;40535.59464405362;41120.383194252114;41121;41144.17711645765;6849.897251541231;7814.296713295788;7973.800654983643;8512.78718032051;8532.402731808776;8456.492610443393;8490.702825401113;8551.016633087182;8557.48655080694;8576.228139467446;8519.488830670149;8581.141885811432;8591.957040214791;8602.956985215065;8603.354748393864;7894;8086.989126359205;8539.14608539141;8560.871586926201;8572.271356934669;8191.303739182176;8238.217369349903;8282.13037631052;8309.709160179425;8317.376196785204;37849.81075094607;38029.61970380316;38073.85778426652;38140.61859381398;38251;3553.289342131584;3593.694535964446;3595.5505561804775;3596.3706351388664;3597.6762091411897;30258.54612180797;30727.38545229083;30742.538861917088;30782;30824.691753082407;3032.36320372722;3111.6888311168927;3117.9688203117908;3125.796823206484;3132.8120312781307;298067;301211;313592;316564;318054;48354.516454835706;48525.5147448521;48551.757241214276;48669.756651216514;48713;50021.9995600086;50597.747011264706;50673.43939045504;50733.492665073616;50751.746241268556;14152.646183845487;14233.928830355782;14247.78628320576;14254.287285635677;14300.85699143016;9658.517074146264;11360.20478566509;11594.01450876668;11605.419729013684;11652.41737913101;13599;13605.659858503617;13617.387217575233;13645.795313070312;13651.726965460635;4913.680610760289;5707.915496055736;5877.118432235114;5894.498967587803;5948.464638182584;846.3060290561681;1010.3836659637615;1012.584840215508;1016.506994107857;1043.2540733375522;22446;24182;24358.39104022378;24394.87802560976;24438.63342049835;926.7961048569304;1112.238116889933;1153.1293873125903;1156.0866915136949;1156.7975604269388;10551.15590752738;11663.708407289885;12359;12481.875181248071;12487.562935297221;319420;321207.78792212246;321454;322016;322474;52799.73600131975;53189.7340513295;53255.20117198245;53260.73369633127;53277;1466.5820241231197;1503.421182844591;1530.2042937672347;1537.5848520899294;1550.2791202090857;23800.88099559491;23816.285511434686;23947.281581552583;24013.75986240115;24033.158839440537;37329.81335093307;37348.62651373452;37469;37544.74731516118;37555.2488950214;268647.97028044984;269747;270037;270350;270421;33061.33877322392;34325.828370857984;35742.4638630426;35889.74385896481;36112;42780;43374;45023.549764501935;45115.87210319768;45141;29326.560101598065;29827.10518684356;30488.23779405444;30555.54166687457;30920.69079309178;29914.850425747733;30350.544741829333;30621.846890765402;30906.536401953537;30967.535486968165;38016.85949421522;38124;38134.809325953196;38136.809315953244;38194.236115278094;25081.871315790468;25496.872515637304;29513.704862951956;30380.93666721654;31363.529547057267;22643.660345094508;24370.025198992553;24457.10980468453;24768.25695229146;24783.380415489755 14:28:54 INFO - PID 1655 | |2;jquery.html;397.3086828917684;425.5106627378515;429.3130990415334;435.1188842593749;457.87363086806477;21159.153633854647;21412.89293553534;21963.450913727163;22640.547189056208;22853.657195142085;22659.660105098435;22764.317070487912;23182.536349273003;23281.952312145953;23348.18281360153;5318.414974352818;6031.69841507924;6165.586638748014;6246.718897649616;6248.812535623916;5902.2622172228475;6130.172426722403;6175.907361389583;6182.1345011698395;6193.504519638418;5918.082697181922;6024.397560243984;6151.323354431009;6156.261248650168;6156.630602163885;6086.969565152146;6092.299385570672;6158.876822463548;6163.445289923929;6166.907496387513;5407.4732830874755;5422.647527910713;5438.15708565173;5439.945600544023;5483.7806487740445;22673.093076276928;27153.86423067892;27940.860295698392;28413.71586284152;29095.709042909722;2116.375669177596;2197.4726065744267;2198.1537108213292;2209.06114901168;2210.182232573958;16359.509214723459;16590.755693322928;17629.294828206857;18026.098695065193;18403.803752756026;2111.5987962287118;2122.989385053065;2171.3160354488446;2173.347995601329;2174.4781252499442;164019;166015.3398466024;166581.17222380507;167659.1617041919;168218;12447.75104497905;12691.873081269254;12700.364981750876;12791;12931.806022909665;13573.389197486136;16455.42406015784;16457.835421645632;16483.093429861474;16505.33978640865;8668.566571671392;8957.59690813902;8995.840998160395;9084.8637270441;9140.771480712903;8508.829823403621;8532.488050716853;8557.743267701979;8689.913100869036;8749.16882896124;6515.348465153542;7812.687492500237;7977.122516523178;7978.481398709181;8001.359891208689;1857.3406440713622;2038.4190505705803;2202.1631779923528;2212.7565967743535;2218.589560931233;919.8758167647281;959.8098358035985;962.3862396700346;963.5760265483165;975.7072878136672;20410.795892041187;20629.968501574862;20699.06854191565;20770.896145519477;21174.47063823417;966.2415004221652;966.5891995901713;980.352967041749;991.3506653142204;1004.457592899826;9133.49765762876;9345.859812102824;10545.525451354708;10605.681829545127;10920.344779313267;413771.9311403424;506249.46875266125;506322.4683876631;510168;511872;104828;131171.03243451356;134317.32841335927;137371;137775;1054.1619412567027;1164.5225457562528;1186.0570846177309;1219.1587804414926;1220.395904027512;21945.670814937468;22116.33650990473;22327.77672223257;22364.440888977904;22543.98552065128;48349.75825120852;48759.781005475146;58161.709191453774;59348.40651593602;59725.70137149286;54916;79610.20389796028;80250.59874700589;80368.59815700885;80808.59595701983;8841.823163536565;8873.689420870496;8938;9057.230135438298;9384.718458446258;12241.20432171906;13444.327783610584;14533.709325813636;14558.12651240929;14596.489122880648;11757.294562325946;11975.820362694742;12063.51745930188;12250.938745306217;12269.447874845475;10342.5345859435;11346.94326528362;11582.305061696325;11628.88371116278;11945.924866762032;11453.942730286295;11735.647930562096;12026.27842329463;12311.19977201479;12362.567310144103;8338.62476188585;8462.026866910384;8528.957355213184;8604.096569860074;8735.126487351214;9642.903570964481;11594.47824847867;11996.040316774812;12171.939140304241;12252.693682658004 14:28:54 INFO - PID 1655 | |3;mootools.html;1357.6945187332851;1420.4673247532176;1431.3058166789112;1433.8709516143538;1438.388684808956;11194.272372295805;11559.537618495271;11685.415729213548;11758.118141139417;11794.23337483063;11700.531978720863;11736.35450050247;11905.40472976352;11923.403829808496;11968.820467692969;2534.645149679042;2571.2543362424967;2573.369524466508;2578.1105518596146;2581.303048176991;2488.191337815208;2536.822422430431;2540.720520742717;2549.923502294934;2553.80846436518;2497.4505608766044;2517.0812653381477;2517.5216708825265;2542.5042116787235;2542.5550528657504;2146.4097373222335;2149.5163588192686;2506.9373265668414;2521.911733089333;2537.8857951392233;2435.2329016359968;2502.8998840046374;2503.8998440062373;2543.6566063581363;2546.1597672767944;9439.339246252764;11225.607103751412;11315.151363647677;11336.6032188873;11352.48913798881;5572.136318870582;6375.362463753634;6520.869582608369;6583.769568065095;6625.900611490832;3932.6067393260732;4238.957610423887;4266.31738921771;4269.807858646368;4271.295236286041;1199.8500187476566;1208.800547909594;1255.3974092435683;1258.3771033338464;1261.9936900315531;15715.607109822231;15760.76358854618;15800.683986320324;15870.920645396815;16115.516534503866;5264.736763161826;5310.840674779763;5443.618946673774;5455.918161227585;5474.151506516497;11089.390083545482;11250.887491125148;11262.718432039264;11264.098872090213;11277.266977646426;6370.044493326038;6383.39357761012;6384.7126879290545;6396.232452105777;6410.423061924356;5849.093390524476;6088.330283668793;6119.694015299217;6159.599626024294;6182.165407669907;4208.8316467340965;4252.192083504125;4295.763732994716;4317.395564620955;4321.459817522809;1214.0954988533422;1242.863285038645;1246.1028059797113;1246.4453318273281;1254.0719867298133;801.8155824160476;804.155636581587;830.3315830756178;844.273924424994;863.1196179896596;5483.067878460672;6063.454289114001;6097.725602347905;6123.8162855114415;6275.121482992385;580.5152697497637;582.2634367525084;600.9303439877026;602.9849253768604;608.0241212853396;3812.8838494635743;4217.831286748557;4235;4272.230998420252;4298.76356800379;11925.344106074075;13989.300534973414;14139.080959737585;14226.288685565887;14400.351984160738;13902.791458128137;14150.646233844236;14364.640883977776;14396.992210545372;14594.708105837824;6133.447989680861;8308.6676532938;8450.154984501502;8453.112423195476;8472.618732157067;6295.338989406229;6337.936620633919;6461.160049193575;6598.472122230114;6631.568948018364;12799.104062715893;12832;12855.614331569694;13118.212907225598;13143.934280328536;13103.672408189872;13298.933505332412;13342.599722008355;13369.933150334185;13457.327133643083;6783.86432271342;7926.48477848938;8035.55804430762;8099.757007289791;8114.513129212266;9062.682806101755;10002.499875006366;10167.542460589144;10397.11624511925;10491;8962.103789620985;9005.63977440895;9037.231835293811;9042.683506077256;9174.633014679339;8843;8893.155150260714;8940.776480588038;8946.776330591787;8952;7113.53762005468;7460.552366858006;8056.83886322282;8136;8209.671613135646;4902.509749025212;5676.148577713376;5874.911876321944;6016.789412370546;6128;5210.452902445189;5490.176473529003;5943.554233432596;6132.641527284621;6171.3828617139725 14:28:54 INFO - PID 1655 | |4;prototype.html;323.4436768757736;368.88933320003997;392.6701570680628;401.80713257636353;406.597468506179;4857.591298523428;5743.425657434254;6208.2550093988675;6275.6548389838545;6461.903071453932;6122.969385153068;6184.907226391608;6243.407930977606;6248.218972628421;6260.718267677954;4442.289233722605;4532.229520981433;4532.4334458192725;4540.886477838064;4561.817527298904;3809.771413715172;4537.568930951555;4548.408706868119;4549.61328287096;4554.157480866051;4502.549745025488;4523.434570678665;4525.728456292616;4535.750533720644;4536.138133754576;3711.851525938986;4370.868873933771;4448.933266001012;4517.367568540407;4525.864224073283;3215;3844.480995065658;3859.517560304962;3865.7100717446024;3884.495015647976;6012.128241405016;6113.174721412597;6167.273163514178;6178.07328900664;6197.194364732556;5147.845564633029;5346.304980352568;5396.19057141428;5427.99582077313;5451.291332126798;6565.376289252514;6662.600243985328;6667;6674.129005176986;6682.916108924507;3599.784012959231;4225.767582782945;4368.973291276548;4381.3208952612395;4397.5602439756085;6896.931030689679;6959.791206263821;7098.041764361848;7136.357727804522;7187.3172048655815;3754.4180651998986;4463.910721785546;4470;4473.910521789546;4477.8432754854075;4544.840930567414;4545.886352841206;4549.203889319422;4564.84023059198;4600.3099535070005;4966.478519755446;5259.263703081571;5425.159100339453;5426.67439953604;5454.254638515717;5960.821175364746;6749.493787965922;6751.223609284995;6766.357196066366;6780.627065511444;1264.0330147437219;1325.7746183148888;1451.9128852268898;1542.7118356172498;1588.5234429671052;908.5003248213457;936.2603543200931;1006.2050979725934;1011.2971484818128;1022.1465076660922;5157.406898206679;5611.6352437091455;5616.859578510488;6130.540209484307;6382.92554919653;6373.757117362163;6702;6879.5184337095925;6957.686904089328;6997.195322537973;4737.502562230985;6275.395710779975;6371.39471750183;6371.980483122678;6372.299047104815;2927.0926012935633;3463.558191475004;3610.368185567515;3670.926581468409;3682.613325600827;5762.9914764915975;6937.826554336081;7104.751333703295;7183.814670579296;7204.3516083551685;6193.504519638508;6590.901136482957;6897.793066208022;7215.278472152848;7217.314355136254;5877.4122587740885;6125.326214116443;6736.797896063126;7254.746083887038;7294.650489659324;6519.663468988751;7072.504924655431;7103.893441598483;7136.3934065602925;7175.784726458214;6657.700403481758;6749.392554670103;6860.833658278205;6952.165740111219;6983.546069505466;5963.642181469126;6349.238091429058;7172.246914073947;7272.309130632581;7298.598577078312;4295.871123866289;4453.710508816916;4474.642028637766;5119.820806271762;5163.690178589297;6254.437100660963;6757.391834734897;7017.333353331425;7068.540544864567;7089.149302083577;6812.829679258059;6820.147481564804;6823.38589526945;6845.247022827385;6866.519343646097;6081.939180608314;6298.937010630018;6742.224644165984;6787;6862.519623626298;5863.677497737494;5988.401159883977;6294.181756371642;6673.799786006428;6694.698738556679;6419.325970773001;6427.389398007182;6451.935480645134;6806.319368063154;6824.897626535705;5810.622309549865;6579.703913323816;6639.834004149741;6659.733610655519;6660.302577456138 14:28:54 INFO - PID 1655 | |5;yui.html;808.316972158526;829.8174401631638;855.9258131045536;860.178529504323;876.736978906328;13645.976551758607;14194.36125374358;15035.699286014273;15686.21568921555;15707.607309817246;15737.291821868015;16111.83888161118;16443.91778041111;16480.67038659226;16593.087380194083;977.9755506112351;985.438300168904;989.45579931038;990.4898977026801;991.7570195302161;968.8982656821038;1017.7710015246585;1018.9235807314444;1022.6471867205839;1026.0406519903877;958.7603099225194;1004.14647549583;1082.2316155529584;1095.8575385199913;1096.8957948994832;1082.0532034469838;1086.2124959404448;1091.7434402915308;1093.923425360225;1098.8681358237022;799.2526987266924;807.0154411617817;810.793247721833;812.9675312353278;813.3168138763467;13158.210507369493;14089.013769036172;14361.71276574473;15877.841221587752;15968.281427335682;15578.454754083552;15579.766303505456;15832;15919.363225470966;15966.840331596652;13477.19136851792;13677.931610341984;13679.794803077963;13803.102798318178;13938.51215207473;3581.230035542371;3598.208394153282;3625.5649322081254;3625.6193099724414;3637.7453578249533;104515;108572.99999999921;120528.39735801265;123207;124642.37678811638;11386.316820990767;12002.939985300018;12399;12712.42794074269;12712.872871271355;22491.77508224897;22917.656235156486;23111.537769244856;23377.88311058434;23409.297721068397;7287.6356182190675;7574.772756817194;7602.923970760332;7604.353629941406;7607.391408687292;3844.75009124406;3923.097687531884;3923.097687531884;3924.2543916656314;3931.9213615727526;3395.2700169463683;4151.667866570667;4234;4285.935710964338;4304.80628371724;911.9589618467185;927.2396634759433;941.7410212191566;942.93399462037;944.8299306124964;647.4173244080365;664.2427632498955;667.2360147630935;667.7796327212009;687.4294335701356;9928;10402.687919362432;10860.45697715111;11040.503177357039;11474.598389056342;640.679660169915;647.2847503508649;653.4837478392112;657.1063353838775;669.6283562622791;5331.44019877915;5396.676199427969;5435.374931882805;5459.2629994950175;5517.862053448696;100576;103055;104149.47925260477;104229;106383.46808266063;97409;97799.17606591598;99066;99986.50006749919;100948;53900.65248368822;81527;84152.73770893441;85190.5740471306;85213;9990.800183996425;10660.987206215397;11166.162537809534;11288.322700637988;11329.71675708114;39947.80026099967;40151.79924100361;40488;40754.38868417036;40963.38554921738;50388.488345349695;50993.49006509887;51710.707232319495;52468.212976804614;52818.73590632176;13460.528881489101;13598.388072536562;13722.039457237897;13823.930880345533;13853.584392468241;15061.472848450252;15085.924570377518;15189.696206076038;15318.770218446954;15341.923290383476;15352.309146088675;16105.919470403041;16354.67290654204;16524.504264872074;17331;16708.41520546775;16861.409850655167;17584.472465825533;17693.734593981357;17703.822961770733;16250.837491624932;17392.478225652743;17434.21546030406;17462.21420036076;17509.474715758548;8529.616167272363;9335.439873607605;9400.905990940004;10585.52365143555;13059.085863989429;11166.665000050012;12907.483700651868;13182.60452186436;13388.665283367995;13531.661708456973 14:28:54 INFO - PID 1655 | __end_tp_report 14:28:54 INFO - PID 1655 | __start_cc_report 14:28:54 INFO - PID 1655 | _x_x_mozilla_cycle_collect,333 14:28:54 INFO - PID 1655 | __end_cc_report 14:28:54 INFO - PID 1655 | __startTimestamp1495402134008__endTimestamp 14:28:54 INFO - PID 1655 | 14:28:54 INFO - PID 1655 | ------- Summary: start ------- 14:28:54 INFO - PID 1655 | Number of tests: 6 14:28:54 INFO - PID 1655 | 14:28:54 INFO - PID 1655 | [#0] dojo.html Cycles:165 Average:6084.12 Median:6308.15 stddev:777.60 (12.3%) stddev-sans-first:752.09 14:28:54 INFO - PID 1655 | Values: 3453.0 3479.7 3492.8 3494.9 3502.9 6042.8 6090.7 6168.7 6168.8 6187.2 6111.1 6111.8 6128.6 6135.8 6143.3 5250.7 5278.6 5282.5 5301.9 5395.1 4776.1 5420.4 5438.0 5483.3 5487.5 4477.0 4756.1 5219.5 5418.9 5438.4 5424.7 5433.3 5436.4 5476.3 5480.6 4033.9 5169.4 5221.8 5282.8 5327.5 5919.2 6218.4 6231.2 6264.2 6267.6 6013.6 6539.1 6573.2 6574.6 6605.1 6523.0 6624.0 6704.2 6748.8 6752.1 6253.9 6271.2 6298.3 6318.5 6352.2 6835.4 6861.2 6861.9 6864.2 6877.6 6308.1 6469.9 6491.6 6495.4 6526.9 6558.1 6613.1 6673.6 6682.3 6711.5 6494.8 6571.8 6580.4 6609.0 6617.4 5671.6 6352.7 6538.4 6632.8 6679.8 4251.7 5242.9 5299.9 5369.4 5378.3 4727.8 4739.9 4760.2 4762.0 4766.4 6247.0 6282.0 6293.6 6304.9 6333.0 6509.6 6594.8 6683.5 6730.1 6731.5 5280.6 6206.5 6295.7 6307.0 6346.3 5873.4 5978.4 5999.2 6004.0 6012.0 6653.7 6744.4 6784.9 6903.2 6953.1 6783.1 6784.9 6805.8 6810.5 6814.2 6857.2 6919.2 6924.9 6927.8 6958.6 6388.5 6595.1 6627.6 6658.9 6662.1 6590.6 6652.8 6733.4 6769.2 6775.9 6513.5 6533.9 6547.7 6550.9 6554.1 6442.8 6492.6 6523.5 6542.1 6546.2 5630.3 6109.4 6168.3 6264.8 6291.2 6457.9 6477.4 6481.3 6491.9 6516.5 5209.8 6219.5 6261.5 6281.2 6325.6 14:28:54 INFO - PID 1655 | 14:28:54 INFO - PID 1655 | [#1] ext.html Cycles:180 Average:45213.28 Median:18939.56 stddev:78957.46 (416.9%) stddev-sans-first:79108.19 14:28:54 INFO - PID 1655 | Values: 691.7 758.3 828.1 1221.9 1276.0 38612.6 39528.0 39790.0 39797.2 39899.6 40407.8 40535.6 41120.4 41121.0 41144.2 6849.9 7814.3 7973.8 8512.8 8532.4 8456.5 8490.7 8551.0 8557.5 8576.2 8519.5 8581.1 8592.0 8603.0 8603.4 7894.0 8087.0 8539.1 8560.9 8572.3 8191.3 8238.2 8282.1 8309.7 8317.4 37849.8 38029.6 38073.9 38140.6 38251.0 3553.3 3593.7 3595.6 3596.4 3597.7 30258.5 30727.4 30742.5 30782.0 30824.7 3032.4 3111.7 3118.0 3125.8 3132.8 298067.0 301211.0 313592.0 316564.0 318054.0 48354.5 48525.5 48551.8 48669.8 48713.0 50022.0 50597.7 50673.4 50733.5 50751.7 14152.6 14233.9 14247.8 14254.3 14300.9 9658.5 11360.2 11594.0 11605.4 11652.4 13599.0 13605.7 13617.4 13645.8 13651.7 4913.7 5707.9 5877.1 5894.5 5948.5 846.3 1010.4 1012.6 1016.5 1043.3 22446.0 24182.0 24358.4 24394.9 24438.6 926.8 1112.2 1153.1 1156.1 1156.8 10551.2 11663.7 12359.0 12481.9 12487.6 319420.0 321207.8 321454.0 322016.0 322474.0 52799.7 53189.7 53255.2 53260.7 53277.0 1466.6 1503.4 1530.2 1537.6 1550.3 23800.9 23816.3 23947.3 24013.8 24033.2 37329.8 37348.6 37469.0 37544.7 37555.2 268648.0 269747.0 270037.0 270350.0 270421.0 33061.3 34325.8 35742.5 35889.7 36112.0 42780.0 43374.0 45023.5 45115.9 45141.0 29326.6 29827.1 30488.2 30555.5 30920.7 29914.9 30350.5 30621.8 30906.5 30967.5 38016.9 38124.0 38134.8 38136.8 38194.2 25081.9 25496.9 29513.7 30380.9 31363.5 22643.7 24370.0 24457.1 24768.3 24783.4 14:28:54 INFO - PID 1655 | 14:28:54 INFO - PID 1655 | [#2] jquery.html Cycles:180 Average:34164.11 Median:23018.10 stddev:84786.11 (368.3%) stddev-sans-first:84986.05 14:28:54 INFO - PID 1655 | Values: 397.3 425.5 429.3 435.1 457.9 21159.2 21412.9 21963.5 22640.5 22853.7 22659.7 22764.3 23182.5 23282.0 23348.2 5318.4 6031.7 6165.6 6246.7 6248.8 5902.3 6130.2 6175.9 6182.1 6193.5 5918.1 6024.4 6151.3 6156.3 6156.6 6087.0 6092.3 6158.9 6163.4 6166.9 5407.5 5422.6 5438.2 5439.9 5483.8 22673.1 27153.9 27940.9 28413.7 29095.7 2116.4 2197.5 2198.2 2209.1 2210.2 16359.5 16590.8 17629.3 18026.1 18403.8 2111.6 2123.0 2171.3 2173.3 2174.5 164019.0 166015.3 166581.2 167659.2 168218.0 12447.8 12691.9 12700.4 12791.0 12931.8 13573.4 16455.4 16457.8 16483.1 16505.3 8668.6 8957.6 8995.8 9084.9 9140.8 8508.8 8532.5 8557.7 8689.9 8749.2 6515.3 7812.7 7977.1 7978.5 8001.4 1857.3 2038.4 2202.2 2212.8 2218.6 919.9 959.8 962.4 963.6 975.7 20410.8 20630.0 20699.1 20770.9 21174.5 966.2 966.6 980.4 991.4 1004.5 9133.5 9345.9 10545.5 10605.7 10920.3 413771.9 506249.5 506322.5 510168.0 511872.0 104828.0 131171.0 134317.3 137371.0 137775.0 1054.2 1164.5 1186.1 1219.2 1220.4 21945.7 22116.3 22327.8 22364.4 22544.0 48349.8 48759.8 58161.7 59348.4 59725.7 54916.0 79610.2 80250.6 80368.6 80808.6 8841.8 8873.7 8938.0 9057.2 9384.7 12241.2 13444.3 14533.7 14558.1 14596.5 11757.3 11975.8 12063.5 12250.9 12269.4 10342.5 11346.9 11582.3 11628.9 11945.9 11453.9 11735.6 12026.3 12311.2 12362.6 8338.6 8462.0 8529.0 8604.1 8735.1 9642.9 11594.5 11996.0 12171.9 12252.7 14:28:54 INFO - PID 1655 | 14:28:54 INFO - PID 1655 | [#3] mootools.html Cycles:180 Average:6831.07 Median:4213.33 stddev:4299.65 (102.0%) stddev-sans-first:4292.04 14:28:54 INFO - PID 1655 | Values: 1357.7 1420.5 1431.3 1433.9 1438.4 11194.3 11559.5 11685.4 11758.1 11794.2 11700.5 11736.4 11905.4 11923.4 11968.8 2534.6 2571.3 2573.4 2578.1 2581.3 2488.2 2536.8 2540.7 2549.9 2553.8 2497.5 2517.1 2517.5 2542.5 2542.6 2146.4 2149.5 2506.9 2521.9 2537.9 2435.2 2502.9 2503.9 2543.7 2546.2 9439.3 11225.6 11315.2 11336.6 11352.5 5572.1 6375.4 6520.9 6583.8 6625.9 3932.6 4239.0 4266.3 4269.8 4271.3 1199.9 1208.8 1255.4 1258.4 1262.0 15715.6 15760.8 15800.7 15870.9 16115.5 5264.7 5310.8 5443.6 5455.9 5474.2 11089.4 11250.9 11262.7 11264.1 11277.3 6370.0 6383.4 6384.7 6396.2 6410.4 5849.1 6088.3 6119.7 6159.6 6182.2 4208.8 4252.2 4295.8 4317.4 4321.5 1214.1 1242.9 1246.1 1246.4 1254.1 801.8 804.2 830.3 844.3 863.1 5483.1 6063.5 6097.7 6123.8 6275.1 580.5 582.3 600.9 603.0 608.0 3812.9 4217.8 4235.0 4272.2 4298.8 11925.3 13989.3 14139.1 14226.3 14400.4 13902.8 14150.6 14364.6 14397.0 14594.7 6133.4 8308.7 8450.2 8453.1 8472.6 6295.3 6337.9 6461.2 6598.5 6631.6 12799.1 12832.0 12855.6 13118.2 13143.9 13103.7 13298.9 13342.6 13369.9 13457.3 6783.9 7926.5 8035.6 8099.8 8114.5 9062.7 10002.5 10167.5 10397.1 10491.0 8962.1 9005.6 9037.2 9042.7 9174.6 8843.0 8893.2 8940.8 8946.8 8952.0 7113.5 7460.6 8056.8 8136.0 8209.7 4902.5 5676.1 5874.9 6016.8 6128.0 5210.5 5490.2 5943.6 6132.6 6171.4 14:28:54 INFO - PID 1655 | 14:28:54 INFO - PID 1655 | [#4] prototype.html Cycles:180 Average:5386.39 Median:6127.93 stddev:1742.68 (28.4%) stddev-sans-first:1705.63 14:28:54 INFO - PID 1655 | Values: 323.4 368.9 392.7 401.8 406.6 4857.6 5743.4 6208.3 6275.7 6461.9 6123.0 6184.9 6243.4 6248.2 6260.7 4442.3 4532.2 4532.4 4540.9 4561.8 3809.8 4537.6 4548.4 4549.6 4554.2 4502.5 4523.4 4525.7 4535.8 4536.1 3711.9 4370.9 4448.9 4517.4 4525.9 3215.0 3844.5 3859.5 3865.7 3884.5 6012.1 6113.2 6167.3 6178.1 6197.2 5147.8 5346.3 5396.2 5428.0 5451.3 6565.4 6662.6 6667.0 6674.1 6682.9 3599.8 4225.8 4369.0 4381.3 4397.6 6896.9 6959.8 7098.0 7136.4 7187.3 3754.4 4463.9 4470.0 4473.9 4477.8 4544.8 4545.9 4549.2 4564.8 4600.3 4966.5 5259.3 5425.2 5426.7 5454.3 5960.8 6749.5 6751.2 6766.4 6780.6 1264.0 1325.8 1451.9 1542.7 1588.5 908.5 936.3 1006.2 1011.3 1022.1 5157.4 5611.6 5616.9 6130.5 6382.9 6373.8 6702.0 6879.5 6957.7 6997.2 4737.5 6275.4 6371.4 6372.0 6372.3 2927.1 3463.6 3610.4 3670.9 3682.6 5763.0 6937.8 7104.8 7183.8 7204.4 6193.5 6590.9 6897.8 7215.3 7217.3 5877.4 6125.3 6736.8 7254.7 7294.7 6519.7 7072.5 7103.9 7136.4 7175.8 6657.7 6749.4 6860.8 6952.2 6983.5 5963.6 6349.2 7172.2 7272.3 7298.6 4295.9 4453.7 4474.6 5119.8 5163.7 6254.4 6757.4 7017.3 7068.5 7089.1 6812.8 6820.1 6823.4 6845.2 6866.5 6081.9 6298.9 6742.2 6787.0 6862.5 5863.7 5988.4 6294.2 6673.8 6694.7 6419.3 6427.4 6451.9 6806.3 6824.9 5810.6 6579.7 6639.8 6659.7 6660.3 14:28:54 INFO - PID 1655 | 14:28:54 INFO - PID 1655 | [#5] yui.html Cycles:180 Average:21021.05 Median:17546.97 stddev:30146.25 (171.8%) stddev-sans-first:30192.61 14:28:54 INFO - PID 1655 | Values: 808.3 829.8 855.9 860.2 876.7 13646.0 14194.4 15035.7 15686.2 15707.6 15737.3 16111.8 16443.9 16480.7 16593.1 978.0 985.4 989.5 990.5 991.8 968.9 1017.8 1018.9 1022.6 1026.0 958.8 1004.1 1082.2 1095.9 1096.9 1082.1 1086.2 1091.7 1093.9 1098.9 799.3 807.0 810.8 813.0 813.3 13158.2 14089.0 14361.7 15877.8 15968.3 15578.5 15579.8 15832.0 15919.4 15966.8 13477.2 13677.9 13679.8 13803.1 13938.5 3581.2 3598.2 3625.6 3625.6 3637.7 104515.0 108573.0 120528.4 123207.0 124642.4 11386.3 12002.9 12399.0 12712.4 12712.9 22491.8 22917.7 23111.5 23377.9 23409.3 7287.6 7574.8 7602.9 7604.4 7607.4 3844.8 3923.1 3923.1 3924.3 3931.9 3395.3 4151.7 4234.0 4285.9 4304.8 912.0 927.2 941.7 942.9 944.8 647.4 664.2 667.2 667.8 687.4 9928.0 10402.7 10860.5 11040.5 11474.6 640.7 647.3 653.5 657.1 669.6 5331.4 5396.7 5435.4 5459.3 5517.9 100576.0 103055.0 104149.5 104229.0 106383.5 97409.0 97799.2 99066.0 99986.5 100948.0 53900.7 81527.0 84152.7 85190.6 85213.0 9990.8 10661.0 11166.2 11288.3 11329.7 39947.8 40151.8 40488.0 40754.4 40963.4 50388.5 50993.5 51710.7 52468.2 52818.7 13460.5 13598.4 13722.0 13823.9 13853.6 15061.5 15085.9 15189.7 15318.8 15341.9 15352.3 16105.9 16354.7 16524.5 17331.0 16708.4 16861.4 17584.5 17693.7 17703.8 16250.8 17392.5 17434.2 17462.2 17509.5 8529.6 9335.4 9400.9 10585.5 13059.1 11166.7 12907.5 13182.6 13388.7 13531.7 14:28:54 INFO - PID 1655 | -------- Summary: end -------- 14:28:54 INFO - PID 1655 | 14:28:54 INFO - TEST-INFO | 1655: exit 0 14:28:54 INFO - TEST-OK | dromaeo_css | took 1114085ms 14:28:54 INFO - TEST-START | kraken 14:28:54 INFO - operating with platform_type : mac_ 14:28:54 INFO - Initialising browser for kraken test... 14:28:54 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpae0TUJ/profile http://localhost:49226/getInfo.html) 14:28:55 INFO - PID 1666 | JavaScript warning: resource://gre/modules/addons/XPIProvider.jsm, line 2970: String.localeCompare is deprecated; use String.prototype.localeCompare instead 14:28:57 INFO - PID 1666 | 2017-05-21 14:28:57.006 plugin-container[1667:14925] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9943, name = 'com.apple.tsm.portname' 14:28:57 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:28:57 INFO - PID 1666 | 2017-05-21 14:28:57.010 plugin-container[1667:14925] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 14:28:57 INFO - PID 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:28:57 INFO - PID 1666 | 2017-05-21 14:28:57.010 plugin-container[1667:14925] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:28:57 INFO - PID 1666 | __metrics Screen width/height:1600/1200 14:28:57 INFO - PID 1666 | colorDepth:24 14:28:57 INFO - PID 1666 | Browser inner width/height: 1024/691 14:28:57 INFO - PID 1666 | __metrics 14:28:58 INFO - TEST-INFO | 1666: exit 0 14:28:58 INFO - Browser initialized. 14:28:58 INFO - Running cycle 1/1 for kraken test... 14:28:58 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/00/7tdhxz016pgck4583ks_0xj800000w/T/tmpae0TUJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:29:00 INFO - PID 1668 | 2017-05-21 14:29:00.074 plugin-container[1669:15035] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464b, name = 'com.apple.tsm.portname' 14:29:00 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:29:00 INFO - PID 1668 | 2017-05-21 14:29:00.075 plugin-container[1669:15035] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 14:29:00 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:29:00 INFO - PID 1668 | 2017-05-21 14:29:00.075 plugin-container[1669:15035] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:29:00 INFO - PID 1668 | 2017-05-21 14:29:00.826 plugin-container[1670:15081] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 14:29:00 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:29:00 INFO - PID 1668 | 2017-05-21 14:29:00.827 plugin-container[1670:15081] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 14:29:00 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:29:00 INFO - PID 1668 | 2017-05-21 14:29:00.827 plugin-container[1670:15081] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:29:10 INFO - PID 1668 | RSS: Main: 256446464 14:29:10 INFO - PID 1668 | 14:29:13 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 14:29:13 INFO - PID 1668 | RSS: Main: 262991872 14:29:13 INFO - PID 1668 | 14:29:16 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 14:29:17 INFO - PID 1668 | RSS: Main: 271085568 14:29:17 INFO - PID 1668 | 14:29:20 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 14:29:21 INFO - PID 1668 | RSS: Main: 267038720 14:29:21 INFO - PID 1668 | 14:29:23 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 14:29:23 INFO - PID 1668 | RSS: Main: 268808192 14:29:23 INFO - PID 1668 | 14:29:25 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:29:26 INFO - PID 1668 | RSS: Main: 269152256 14:29:26 INFO - PID 1668 | 14:29:29 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 14:29:29 INFO - PID 1668 | RSS: Main: 278245376 14:29:29 INFO - PID 1668 | 14:29:32 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 14:29:33 INFO - PID 1668 | RSS: Main: 278728704 14:29:33 INFO - PID 1668 | 14:29:36 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 14:29:37 INFO - PID 1668 | RSS: Main: 278044672 14:29:37 INFO - PID 1668 | 14:29:38 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:29:39 INFO - PID 1668 | RSS: Main: 277647360 14:29:39 INFO - PID 1668 | 14:29:41 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:29:41 INFO - PID 1668 | RSS: Main: 277630976 14:29:41 INFO - PID 1668 | 14:29:43 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:29:44 INFO - PID 1668 | RSS: Main: 277180416 14:29:44 INFO - PID 1668 | 14:29:47 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:29:48 INFO - PID 1668 | RSS: Main: 277463040 14:29:48 INFO - PID 1668 | 14:29:51 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:29:52 INFO - PID 1668 | RSS: Main: 277585920 14:29:52 INFO - PID 1668 | 14:29:53 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 14:29:54 INFO - PID 1668 | RSS: Main: 278970368 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | __start_tp_report 14:29:54 INFO - PID 1668 | _x_x_mozilla_page_load 14:29:54 INFO - PID 1668 | _x_x_mozilla_page_load_details 14:29:54 INFO - PID 1668 | |i|pagename|runs| 14:29:54 INFO - PID 1668 | |0;ai-astar;103;123;116;95;169;170;150;117;126;162 14:29:54 INFO - PID 1668 | |1;audio-beat-detection;180;146;96;186;154;101;151;154;160;111 14:29:54 INFO - PID 1668 | |2;audio-dft;211;202;204;232;195;201;200;201;198;197 14:29:54 INFO - PID 1668 | |3;audio-fft;84;56;58;90;75;77;58;53;89;50 14:29:54 INFO - PID 1668 | |4;audio-oscillator;86;71;81;72;71;70;87;71;71;90 14:29:54 INFO - PID 1668 | |5;imaging-gaussian-blur;88;89;92;88;90;89;88;89;88;94 14:29:54 INFO - PID 1668 | |6;imaging-darkroom;96;95;96;95;107;96;101;98;95;96 14:29:54 INFO - PID 1668 | |7;imaging-desaturate;94;94;93;94;120;79;144;139;92;114 14:29:54 INFO - PID 1668 | |8;json-parse-financial;52;49;49;50;50;51;51;50;49;48 14:29:54 INFO - PID 1668 | |9;json-stringify-tinderbox;59;58;69;63;70;80;84;88;60;56 14:29:54 INFO - PID 1668 | |10;stanford-crypto-aes;82;84;84;84;83;84;83;87;84;84 14:29:54 INFO - PID 1668 | |11;stanford-crypto-ccm;135;140;137;150;155;151;156;162;203;153 14:29:54 INFO - PID 1668 | |12;stanford-crypto-pbkdf2;186;185;186;188;199;190;182;194;245;197 14:29:54 INFO - PID 1668 | |13;stanford-crypto-sha256-iterative;73;66;70;64;70;69;70;69;68;92 14:29:54 INFO - PID 1668 | __end_tp_report 14:29:54 INFO - PID 1668 | __start_cc_report 14:29:54 INFO - PID 1668 | _x_x_mozilla_cycle_collect,916 14:29:54 INFO - PID 1668 | __end_cc_report 14:29:54 INFO - PID 1668 | __startTimestamp1495402194019__endTimestamp 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | ------- Summary: start ------- 14:29:54 INFO - PID 1668 | Number of tests: 14 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | [#0] ai-astar Cycles:10 Average:133.10 Median:156.00 stddev:27.55 (17.7%) stddev-sans-first:26.99 14:29:54 INFO - PID 1668 | Values: 103.0 123.0 116.0 95.0 169.0 170.0 150.0 117.0 126.0 162.0 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | [#1] audio-beat-detection Cycles:10 Average:143.90 Median:157.00 stddev:31.29 (19.9%) stddev-sans-first:30.34 14:29:54 INFO - PID 1668 | Values: 180.0 146.0 96.0 186.0 154.0 101.0 151.0 154.0 160.0 111.0 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | [#2] audio-dft Cycles:10 Average:204.10 Median:201.50 stddev:10.73 (5.3%) stddev-sans-first:11.09 14:29:54 INFO - PID 1668 | Values: 211.0 202.0 204.0 232.0 195.0 201.0 200.0 201.0 198.0 197.0 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | [#3] audio-fft Cycles:10 Average:69.00 Median:76.00 stddev:15.61 (20.5%) stddev-sans-first:15.59 14:29:54 INFO - PID 1668 | Values: 84.0 56.0 58.0 90.0 75.0 77.0 58.0 53.0 89.0 50.0 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:76.50 stddev:8.06 (10.5%) stddev-sans-first:7.86 14:29:54 INFO - PID 1668 | Values: 86.0 71.0 81.0 72.0 71.0 70.0 87.0 71.0 71.0 90.0 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:89.50 Median:89.00 stddev:2.01 (2.3%) stddev-sans-first:2.06 14:29:54 INFO - PID 1668 | Values: 88.0 89.0 92.0 88.0 90.0 89.0 88.0 89.0 88.0 94.0 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | [#6] imaging-darkroom Cycles:10 Average:97.50 Median:96.00 stddev:3.81 (4.0%) stddev-sans-first:4.00 14:29:54 INFO - PID 1668 | Values: 96.0 95.0 96.0 95.0 107.0 96.0 101.0 98.0 95.0 96.0 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | [#7] imaging-desaturate Cycles:10 Average:106.30 Median:92.50 stddev:21.90 (23.7%) stddev-sans-first:22.78 14:29:54 INFO - PID 1668 | Values: 94.0 94.0 93.0 94.0 120.0 79.0 144.0 139.0 92.0 114.0 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | [#8] json-parse-financial Cycles:10 Average:49.90 Median:50.00 stddev:1.20 (2.4%) stddev-sans-first:1.00 14:29:54 INFO - PID 1668 | Values: 52.0 49.0 49.0 50.0 50.0 51.0 51.0 50.0 49.0 48.0 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.50 stddev:11.61 (16.7%) stddev-sans-first:11.78 14:29:54 INFO - PID 1668 | Values: 59.0 58.0 69.0 63.0 70.0 80.0 84.0 88.0 60.0 56.0 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.00 stddev:1.29 (1.5%) stddev-sans-first:1.17 14:29:54 INFO - PID 1668 | Values: 82.0 84.0 84.0 84.0 83.0 84.0 83.0 87.0 84.0 84.0 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:154.20 Median:154.00 stddev:19.27 (12.5%) stddev-sans-first:19.14 14:29:54 INFO - PID 1668 | Values: 135.0 140.0 137.0 150.0 155.0 151.0 156.0 162.0 203.0 153.0 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.20 Median:192.00 stddev:18.34 (9.5%) stddev-sans-first:19.14 14:29:54 INFO - PID 1668 | Values: 186.0 185.0 186.0 188.0 199.0 190.0 182.0 194.0 245.0 197.0 14:29:54 INFO - PID 1668 | 14:29:54 INFO - PID 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:70.00 stddev:7.74 (11.1%) stddev-sans-first:8.18 14:29:54 INFO - PID 1668 | Values: 73.0 66.0 70.0 64.0 70.0 69.0 70.0 69.0 68.0 92.0 14:29:54 INFO - PID 1668 | -------- Summary: end -------- 14:29:54 INFO - PID 1668 | 14:29:54 INFO - TEST-INFO | 1668: exit 0 14:29:54 INFO - TEST-OK | kraken | took 60072ms 14:29:54 INFO - SUITE-END | took 1174s 14:29:55 INFO - Completed test suite (00:19:34) 14:29:55 INFO - javascript benchmark 14:29:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7777.299649940837, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3453.0, 3479.704225140863, 3492.807895565743, 3494.8602055917763, 3502.91242718932, 6042.818715438544, 6090.66501342426, 6168.691565421734, 6168.784092556762, 6187.1956645636, 6111.083337499377, 6111.755529778815, 6128.632282063079, 6135.81592552223, 6143.32423433422, 5250.684958902459, 5278.604104692145, 5282.471752824716, 5301.946980530184, 5395.082835917884, 4776.09254241693, 5420.403755586883, 5438.048341540234, 5483.314585676791, 5487.506124448799, 4476.9552304476865, 4756.096341695084, 5219.504147106038, 5418.891622167574, 5438.374586922495, 5424.701641409741, 5433.347998240237, 5436.374816896028, 5476.26070480484, 5480.588955828329, 4033.9394909076386, 5169.431362550116, 5221.817236396746, 5282.81510147143, 5327.46725327468, 5919.230500034968, 6218.378162183791, 6231.189945307126, 6264.1543391642, 6267.561270711052, 6013.579049466539, 6539.149910511651, 6573.1783527059115, 6574.605523668547, 6605.108310378086, 6522.967385163091, 6623.966880165616, 6704.229013663442, 6748.763793267212, 6752.088468056799, 6253.906191407133, 6271.153394291803, 6298.275698294663, 6318.462930650854, 6352.174217351715, 6835.384815366641, 6861.176658800976, 6861.897071543931, 6864.1762988440705, 6877.58734475933, 6308.148399965946, 6469.870602587922, 6491.642959637265, 6495.382938620824, 6526.902096468557, 6558.147440832661, 6613.14029176204, 6673.599584024974, 6682.298358672367, 6711.463082953352, 6494.772682956074, 6571.835704107436, 6580.440662543641, 6609.0, 6617.437517810944, 5671.574631902624, 6352.7141278642575, 6538.411542961157, 6632.834179145463, 6679.76620818269, 4251.6598672106775, 5242.9213561796605, 5299.867503312448, 5369.3556773187465, 5378.300820893259, 4727.834525791581, 4739.857804265877, 4760.238361862085, 4762.023785124111, 4766.380370551876, 6247.031710084944, 6282.0, 6293.590916590407, 6304.936950630527, 6332.968335158294, 6509.609423434609, 6594.769183078569, 6683.465322774166, 6730.125083739083, 6731.49513786468, 5280.6039547034125, 6206.534509911775, 6295.748170073146, 6307.0, 6346.30190679025, 5873.3832947539895, 5978.432048955342, 5999.160117583542, 6003.969980150071, 6012.0, 6653.700583473658, 6744.393004629607, 6784.86430271382, 6903.1716194055025, 6953.096097507393, 6783.05037294789, 6784.898226526508, 6805.795826125224, 6810.455163586802, 6814.2163651180745, 6857.245702972768, 6919.204291506541, 6924.861502770017, 6927.826804329932, 6958.582485050913, 6388.520860935356, 6595.114176855726, 6627.569208001464, 6658.866822663616, 6662.100616416916, 6590.5716128451495, 6652.800415987528, 6733.3939945405145, 6769.221539523018, 6775.86448271022, 6513.54405191632, 6533.869322613426, 6547.672616369257, 6550.934490655223, 6554.082428460115, 6442.774502892376, 6492.642904640101, 6523.478121750343, 6542.084108224757, 6546.214454265519, 5630.268065151504, 6109.43503133753, 6168.25980882297, 6264.812055638338, 6291.245050593958, 6457.903131453126, 6477.352264773485, 6481.287058423664, 6491.935080649133, 6516.478681705547, 5209.817656382009, 6219.502439804714, 6261.467775239155, 6281.152044473937, 6325.620462772248], "value": 6029.793142950007, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.7371398868429, 758.2909979169474, 828.0849661124455, 1221.8778122187782, 1275.993620031901, 38612.613873861286, 39528.01179970502, 39790.0, 39797.20405591886, 39899.60100398995, 40407.787766366986, 40535.59464405362, 41120.383194252114, 41121.0, 41144.17711645765, 6849.897251541231, 7814.296713295788, 7973.800654983643, 8512.78718032051, 8532.402731808776, 8456.492610443393, 8490.702825401113, 8551.016633087182, 8557.48655080694, 8576.228139467446, 8519.488830670149, 8581.141885811432, 8591.957040214791, 8602.956985215065, 8603.354748393864, 7894.0, 8086.989126359205, 8539.14608539141, 8560.871586926201, 8572.271356934669, 8191.303739182176, 8238.217369349903, 8282.13037631052, 8309.709160179425, 8317.376196785204, 37849.81075094607, 38029.61970380316, 38073.85778426652, 38140.61859381398, 38251.0, 3553.289342131584, 3593.694535964446, 3595.5505561804775, 3596.3706351388664, 3597.6762091411897, 30258.54612180797, 30727.38545229083, 30742.538861917088, 30782.0, 30824.691753082407, 3032.36320372722, 3111.6888311168927, 3117.9688203117908, 3125.796823206484, 3132.8120312781307, 298067.0, 301211.0, 313592.0, 316564.0, 318054.0, 48354.516454835706, 48525.5147448521, 48551.757241214276, 48669.756651216514, 48713.0, 50021.9995600086, 50597.747011264706, 50673.43939045504, 50733.492665073616, 50751.746241268556, 14152.646183845487, 14233.928830355782, 14247.78628320576, 14254.287285635677, 14300.85699143016, 9658.517074146264, 11360.20478566509, 11594.01450876668, 11605.419729013684, 11652.41737913101, 13599.0, 13605.659858503617, 13617.387217575233, 13645.795313070312, 13651.726965460635, 4913.680610760289, 5707.915496055736, 5877.118432235114, 5894.498967587803, 5948.464638182584, 846.3060290561681, 1010.3836659637615, 1012.584840215508, 1016.506994107857, 1043.2540733375522, 22446.0, 24182.0, 24358.39104022378, 24394.87802560976, 24438.63342049835, 926.7961048569304, 1112.238116889933, 1153.1293873125903, 1156.0866915136949, 1156.7975604269388, 10551.15590752738, 11663.708407289885, 12359.0, 12481.875181248071, 12487.562935297221, 319420.0, 321207.78792212246, 321454.0, 322016.0, 322474.0, 52799.73600131975, 53189.7340513295, 53255.20117198245, 53260.73369633127, 53277.0, 1466.5820241231197, 1503.421182844591, 1530.2042937672347, 1537.5848520899294, 1550.2791202090857, 23800.88099559491, 23816.285511434686, 23947.281581552583, 24013.75986240115, 24033.158839440537, 37329.81335093307, 37348.62651373452, 37469.0, 37544.74731516118, 37555.2488950214, 268647.97028044984, 269747.0, 270037.0, 270350.0, 270421.0, 33061.33877322392, 34325.828370857984, 35742.4638630426, 35889.74385896481, 36112.0, 42780.0, 43374.0, 45023.549764501935, 45115.87210319768, 45141.0, 29326.560101598065, 29827.10518684356, 30488.23779405444, 30555.54166687457, 30920.69079309178, 29914.850425747733, 30350.544741829333, 30621.846890765402, 30906.536401953537, 30967.535486968165, 38016.85949421522, 38124.0, 38134.809325953196, 38136.809315953244, 38194.236115278094, 25081.871315790468, 25496.872515637304, 29513.704862951956, 30380.93666721654, 31363.529547057267, 22643.660345094508, 24370.025198992553, 24457.10980468453, 24768.25695229146, 24783.380415489755], "value": 17487.23694200967, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.3086828917684, 425.5106627378515, 429.3130990415334, 435.1188842593749, 457.87363086806477, 21159.153633854647, 21412.89293553534, 21963.450913727163, 22640.547189056208, 22853.657195142085, 22659.660105098435, 22764.317070487912, 23182.536349273003, 23281.952312145953, 23348.18281360153, 5318.414974352818, 6031.69841507924, 6165.586638748014, 6246.718897649616, 6248.812535623916, 5902.2622172228475, 6130.172426722403, 6175.907361389583, 6182.1345011698395, 6193.504519638418, 5918.082697181922, 6024.397560243984, 6151.323354431009, 6156.261248650168, 6156.630602163885, 6086.969565152146, 6092.299385570672, 6158.876822463548, 6163.445289923929, 6166.907496387513, 5407.4732830874755, 5422.647527910713, 5438.15708565173, 5439.945600544023, 5483.7806487740445, 22673.093076276928, 27153.86423067892, 27940.860295698392, 28413.71586284152, 29095.709042909722, 2116.375669177596, 2197.4726065744267, 2198.1537108213292, 2209.06114901168, 2210.182232573958, 16359.509214723459, 16590.755693322928, 17629.294828206857, 18026.098695065193, 18403.803752756026, 2111.5987962287118, 2122.989385053065, 2171.3160354488446, 2173.347995601329, 2174.4781252499442, 164019.0, 166015.3398466024, 166581.17222380507, 167659.1617041919, 168218.0, 12447.75104497905, 12691.873081269254, 12700.364981750876, 12791.0, 12931.806022909665, 13573.389197486136, 16455.42406015784, 16457.835421645632, 16483.093429861474, 16505.33978640865, 8668.566571671392, 8957.59690813902, 8995.840998160395, 9084.8637270441, 9140.771480712903, 8508.829823403621, 8532.488050716853, 8557.743267701979, 8689.913100869036, 8749.16882896124, 6515.348465153542, 7812.687492500237, 7977.122516523178, 7978.481398709181, 8001.359891208689, 1857.3406440713622, 2038.4190505705803, 2202.1631779923528, 2212.7565967743535, 2218.589560931233, 919.8758167647281, 959.8098358035985, 962.3862396700346, 963.5760265483165, 975.7072878136672, 20410.795892041187, 20629.968501574862, 20699.06854191565, 20770.896145519477, 21174.47063823417, 966.2415004221652, 966.5891995901713, 980.352967041749, 991.3506653142204, 1004.457592899826, 9133.49765762876, 9345.859812102824, 10545.525451354708, 10605.681829545127, 10920.344779313267, 413771.9311403424, 506249.46875266125, 506322.4683876631, 510168.0, 511872.0, 104828.0, 131171.03243451356, 134317.32841335927, 137371.0, 137775.0, 1054.1619412567027, 1164.5225457562528, 1186.0570846177309, 1219.1587804414926, 1220.395904027512, 21945.670814937468, 22116.33650990473, 22327.77672223257, 22364.440888977904, 22543.98552065128, 48349.75825120852, 48759.781005475146, 58161.709191453774, 59348.40651593602, 59725.70137149286, 54916.0, 79610.20389796028, 80250.59874700589, 80368.59815700885, 80808.59595701983, 8841.823163536565, 8873.689420870496, 8938.0, 9057.230135438298, 9384.718458446258, 12241.20432171906, 13444.327783610584, 14533.709325813636, 14558.12651240929, 14596.489122880648, 11757.294562325946, 11975.820362694742, 12063.51745930188, 12250.938745306217, 12269.447874845475, 10342.5345859435, 11346.94326528362, 11582.305061696325, 11628.88371116278, 11945.924866762032, 11453.942730286295, 11735.647930562096, 12026.27842329463, 12311.19977201479, 12362.567310144103, 8338.62476188585, 8462.026866910384, 8528.957355213184, 8604.096569860074, 8735.126487351214, 9642.903570964481, 11594.47824847867, 11996.040316774812, 12171.939140304241, 12252.693682658004], "value": 10398.52455779166, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.6945187332851, 1420.4673247532176, 1431.3058166789112, 1433.8709516143538, 1438.388684808956, 11194.272372295805, 11559.537618495271, 11685.415729213548, 11758.118141139417, 11794.23337483063, 11700.531978720863, 11736.35450050247, 11905.40472976352, 11923.403829808496, 11968.820467692969, 2534.645149679042, 2571.2543362424967, 2573.369524466508, 2578.1105518596146, 2581.303048176991, 2488.191337815208, 2536.822422430431, 2540.720520742717, 2549.923502294934, 2553.80846436518, 2497.4505608766044, 2517.0812653381477, 2517.5216708825265, 2542.5042116787235, 2542.5550528657504, 2146.4097373222335, 2149.5163588192686, 2506.9373265668414, 2521.911733089333, 2537.8857951392233, 2435.2329016359968, 2502.8998840046374, 2503.8998440062373, 2543.6566063581363, 2546.1597672767944, 9439.339246252764, 11225.607103751412, 11315.151363647677, 11336.6032188873, 11352.48913798881, 5572.136318870582, 6375.362463753634, 6520.869582608369, 6583.769568065095, 6625.900611490832, 3932.6067393260732, 4238.957610423887, 4266.31738921771, 4269.807858646368, 4271.295236286041, 1199.8500187476566, 1208.800547909594, 1255.3974092435683, 1258.3771033338464, 1261.9936900315531, 15715.607109822231, 15760.76358854618, 15800.683986320324, 15870.920645396815, 16115.516534503866, 5264.736763161826, 5310.840674779763, 5443.618946673774, 5455.918161227585, 5474.151506516497, 11089.390083545482, 11250.887491125148, 11262.718432039264, 11264.098872090213, 11277.266977646426, 6370.044493326038, 6383.39357761012, 6384.7126879290545, 6396.232452105777, 6410.423061924356, 5849.093390524476, 6088.330283668793, 6119.694015299217, 6159.599626024294, 6182.165407669907, 4208.8316467340965, 4252.192083504125, 4295.763732994716, 4317.395564620955, 4321.459817522809, 1214.0954988533422, 1242.863285038645, 1246.1028059797113, 1246.4453318273281, 1254.0719867298133, 801.8155824160476, 804.155636581587, 830.3315830756178, 844.273924424994, 863.1196179896596, 5483.067878460672, 6063.454289114001, 6097.725602347905, 6123.8162855114415, 6275.121482992385, 580.5152697497637, 582.2634367525084, 600.9303439877026, 602.9849253768604, 608.0241212853396, 3812.8838494635743, 4217.831286748557, 4235.0, 4272.230998420252, 4298.76356800379, 11925.344106074075, 13989.300534973414, 14139.080959737585, 14226.288685565887, 14400.351984160738, 13902.791458128137, 14150.646233844236, 14364.640883977776, 14396.992210545372, 14594.708105837824, 6133.447989680861, 8308.6676532938, 8450.154984501502, 8453.112423195476, 8472.618732157067, 6295.338989406229, 6337.936620633919, 6461.160049193575, 6598.472122230114, 6631.568948018364, 12799.104062715893, 12832.0, 12855.614331569694, 13118.212907225598, 13143.934280328536, 13103.672408189872, 13298.933505332412, 13342.599722008355, 13369.933150334185, 13457.327133643083, 6783.86432271342, 7926.48477848938, 8035.55804430762, 8099.757007289791, 8114.513129212266, 9062.682806101755, 10002.499875006366, 10167.542460589144, 10397.11624511925, 10491.0, 8962.103789620985, 9005.63977440895, 9037.231835293811, 9042.683506077256, 9174.633014679339, 8843.0, 8893.155150260714, 8940.776480588038, 8946.776330591787, 8952.0, 7113.53762005468, 7460.552366858006, 8056.83886322282, 8136.0, 8209.671613135646, 4902.509749025212, 5676.148577713376, 5874.911876321944, 6016.789412370546, 6128.0, 5210.452902445189, 5490.176473529003, 5943.554233432596, 6132.641527284621, 6171.3828617139725], "value": 5166.783814325414, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.4436768757736, 368.88933320003997, 392.6701570680628, 401.80713257636353, 406.597468506179, 4857.591298523428, 5743.425657434254, 6208.2550093988675, 6275.6548389838545, 6461.903071453932, 6122.969385153068, 6184.907226391608, 6243.407930977606, 6248.218972628421, 6260.718267677954, 4442.289233722605, 4532.229520981433, 4532.4334458192725, 4540.886477838064, 4561.817527298904, 3809.771413715172, 4537.568930951555, 4548.408706868119, 4549.61328287096, 4554.157480866051, 4502.549745025488, 4523.434570678665, 4525.728456292616, 4535.750533720644, 4536.138133754576, 3711.851525938986, 4370.868873933771, 4448.933266001012, 4517.367568540407, 4525.864224073283, 3215.0, 3844.480995065658, 3859.517560304962, 3865.7100717446024, 3884.495015647976, 6012.128241405016, 6113.174721412597, 6167.273163514178, 6178.07328900664, 6197.194364732556, 5147.845564633029, 5346.304980352568, 5396.19057141428, 5427.99582077313, 5451.291332126798, 6565.376289252514, 6662.600243985328, 6667.0, 6674.129005176986, 6682.916108924507, 3599.784012959231, 4225.767582782945, 4368.973291276548, 4381.3208952612395, 4397.5602439756085, 6896.931030689679, 6959.791206263821, 7098.041764361848, 7136.357727804522, 7187.3172048655815, 3754.4180651998986, 4463.910721785546, 4470.0, 4473.910521789546, 4477.8432754854075, 4544.840930567414, 4545.886352841206, 4549.203889319422, 4564.84023059198, 4600.3099535070005, 4966.478519755446, 5259.263703081571, 5425.159100339453, 5426.67439953604, 5454.254638515717, 5960.821175364746, 6749.493787965922, 6751.223609284995, 6766.357196066366, 6780.627065511444, 1264.0330147437219, 1325.7746183148888, 1451.9128852268898, 1542.7118356172498, 1588.5234429671052, 908.5003248213457, 936.2603543200931, 1006.2050979725934, 1011.2971484818128, 1022.1465076660922, 5157.406898206679, 5611.6352437091455, 5616.859578510488, 6130.540209484307, 6382.92554919653, 6373.757117362163, 6702.0, 6879.5184337095925, 6957.686904089328, 6997.195322537973, 4737.502562230985, 6275.395710779975, 6371.39471750183, 6371.980483122678, 6372.299047104815, 2927.0926012935633, 3463.558191475004, 3610.368185567515, 3670.926581468409, 3682.613325600827, 5762.9914764915975, 6937.826554336081, 7104.751333703295, 7183.814670579296, 7204.3516083551685, 6193.504519638508, 6590.901136482957, 6897.793066208022, 7215.278472152848, 7217.314355136254, 5877.4122587740885, 6125.326214116443, 6736.797896063126, 7254.746083887038, 7294.650489659324, 6519.663468988751, 7072.504924655431, 7103.893441598483, 7136.3934065602925, 7175.784726458214, 6657.700403481758, 6749.392554670103, 6860.833658278205, 6952.165740111219, 6983.546069505466, 5963.642181469126, 6349.238091429058, 7172.246914073947, 7272.309130632581, 7298.598577078312, 4295.871123866289, 4453.710508816916, 4474.642028637766, 5119.820806271762, 5163.690178589297, 6254.437100660963, 6757.391834734897, 7017.333353331425, 7068.540544864567, 7089.149302083577, 6812.829679258059, 6820.147481564804, 6823.38589526945, 6845.247022827385, 6866.519343646097, 6081.939180608314, 6298.937010630018, 6742.224644165984, 6787.0, 6862.519623626298, 5863.677497737494, 5988.401159883977, 6294.181756371642, 6673.799786006428, 6694.698738556679, 6419.325970773001, 6427.389398007182, 6451.935480645134, 6806.319368063154, 6824.897626535705, 5810.622309549865, 6579.703913323816, 6639.834004149741, 6659.733610655519, 6660.302577456138], "value": 4826.126442021039, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [808.316972158526, 829.8174401631638, 855.9258131045536, 860.178529504323, 876.736978906328, 13645.976551758607, 14194.36125374358, 15035.699286014273, 15686.21568921555, 15707.607309817246, 15737.291821868015, 16111.83888161118, 16443.91778041111, 16480.67038659226, 16593.087380194083, 977.9755506112351, 985.438300168904, 989.45579931038, 990.4898977026801, 991.7570195302161, 968.8982656821038, 1017.7710015246585, 1018.9235807314444, 1022.6471867205839, 1026.0406519903877, 958.7603099225194, 1004.14647549583, 1082.2316155529584, 1095.8575385199913, 1096.8957948994832, 1082.0532034469838, 1086.2124959404448, 1091.7434402915308, 1093.923425360225, 1098.8681358237022, 799.2526987266924, 807.0154411617817, 810.793247721833, 812.9675312353278, 813.3168138763467, 13158.210507369493, 14089.013769036172, 14361.71276574473, 15877.841221587752, 15968.281427335682, 15578.454754083552, 15579.766303505456, 15832.0, 15919.363225470966, 15966.840331596652, 13477.19136851792, 13677.931610341984, 13679.794803077963, 13803.102798318178, 13938.51215207473, 3581.230035542371, 3598.208394153282, 3625.5649322081254, 3625.6193099724414, 3637.7453578249533, 104515.0, 108572.99999999921, 120528.39735801265, 123207.0, 124642.37678811638, 11386.316820990767, 12002.939985300018, 12399.0, 12712.42794074269, 12712.872871271355, 22491.77508224897, 22917.656235156486, 23111.537769244856, 23377.88311058434, 23409.297721068397, 7287.6356182190675, 7574.772756817194, 7602.923970760332, 7604.353629941406, 7607.391408687292, 3844.75009124406, 3923.097687531884, 3923.097687531884, 3924.2543916656314, 3931.9213615727526, 3395.2700169463683, 4151.667866570667, 4234.0, 4285.935710964338, 4304.80628371724, 911.9589618467185, 927.2396634759433, 941.7410212191566, 942.93399462037, 944.8299306124964, 647.4173244080365, 664.2427632498955, 667.2360147630935, 667.7796327212009, 687.4294335701356, 9928.0, 10402.687919362432, 10860.45697715111, 11040.503177357039, 11474.598389056342, 640.679660169915, 647.2847503508649, 653.4837478392112, 657.1063353838775, 669.6283562622791, 5331.44019877915, 5396.676199427969, 5435.374931882805, 5459.2629994950175, 5517.862053448696, 100576.0, 103055.0, 104149.47925260477, 104229.0, 106383.46808266063, 97409.0, 97799.17606591598, 99066.0, 99986.50006749919, 100948.0, 53900.65248368822, 81527.0, 84152.73770893441, 85190.5740471306, 85213.0, 9990.800183996425, 10660.987206215397, 11166.162537809534, 11288.322700637988, 11329.71675708114, 39947.80026099967, 40151.79924100361, 40488.0, 40754.38868417036, 40963.38554921738, 50388.488345349695, 50993.49006509887, 51710.707232319495, 52468.212976804614, 52818.73590632176, 13460.528881489101, 13598.388072536562, 13722.039457237897, 13823.930880345533, 13853.584392468241, 15061.472848450252, 15085.924570377518, 15189.696206076038, 15318.770218446954, 15341.923290383476, 15352.309146088675, 16105.919470403041, 16354.67290654204, 16524.504264872074, 17331.0, 16708.41520546775, 16861.409850655167, 17584.472465825533, 17693.734593981357, 17703.822961770733, 16250.837491624932, 17392.478225652743, 17434.21546030406, 17462.21420036076, 17509.474715758548, 8529.616167272363, 9335.439873607605, 9400.905990940004, 10585.52365143555, 13059.085863989429, 11166.665000050012, 12907.483700651868, 13182.60452186436, 13388.665283367995, 13531.661708456973], "value": 8093.332878490025, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1543.4, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 123.0, 116.0, 95.0, 169.0, 170.0, 150.0, 117.0, 126.0, 162.0], "value": 133.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 146.0, 96.0, 186.0, 154.0, 101.0, 151.0, 154.0, 160.0, 111.0], "value": 143.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [211.0, 202.0, 204.0, 232.0, 195.0, 201.0, 200.0, 201.0, 198.0, 197.0], "value": 204.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 56.0, 58.0, 90.0, 75.0, 77.0, 58.0, 53.0, 89.0, 50.0], "value": 69.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 81.0, 72.0, 71.0, 70.0, 87.0, 71.0, 71.0, 90.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 92.0, 88.0, 90.0, 89.0, 88.0, 89.0, 88.0, 94.0], "value": 89.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 107.0, 96.0, 101.0, 98.0, 95.0, 96.0], "value": 97.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 93.0, 94.0, 120.0, 79.0, 144.0, 139.0, 92.0, 114.0], "value": 106.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 49.0, 50.0, 50.0, 51.0, 51.0, 50.0, 49.0, 48.0], "value": 49.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 69.0, 63.0, 70.0, 80.0, 84.0, 88.0, 60.0, 56.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 84.0, 84.0, 83.0, 84.0, 83.0, 87.0, 84.0, 84.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 140.0, 137.0, 150.0, 155.0, 151.0, 156.0, 162.0, 203.0, 153.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 185.0, 186.0, 188.0, 199.0, 190.0, 182.0, 194.0, 245.0, 197.0], "value": 195.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 66.0, 70.0, 64.0, 70.0, 69.0, 70.0, 69.0, 68.0, 92.0], "value": 71.1, "unit": "score"}]}]} 14:29:55 INFO - Return code: 0 14:29:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:29:55 INFO - # TBPL SUCCESS # 14:29:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:29:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:29:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:29:55 INFO - 2017-05-21 14:29:55.311 screenresolution[1675:15474] starting screenresolution argv=screenresolution get 14:29:55 INFO - 2017-05-21 14:29:55.314 screenresolution[1675:15474] Display 0: 1600x1200x32@60 14:29:55 INFO - 2017-05-21 14:29:55.320 screenresolution[1676:15477] starting screenresolution argv=screenresolution list 14:29:55 INFO - Available Modes on Display 0 14:29:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:29:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:29:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:29:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:29:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:29:55 INFO - Intel Iris: 14:29:55 INFO - Chipset Model: Intel Iris 14:29:55 INFO - Type: GPU 14:29:55 INFO - Bus: Built-In 14:29:55 INFO - VRAM (Dynamic, Max): 1536 MB 14:29:55 INFO - Vendor: Intel (0x8086) 14:29:55 INFO - Device ID: 0x0a2e 14:29:55 INFO - Revision ID: 0x0009 14:29:55 INFO - Displays: 14:29:55 INFO - Display: 14:29:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:29:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:29:55 INFO - Display Serial Number: 1600x1200 60 14:29:55 INFO - Main Display: Yes 14:29:55 INFO - Mirror: Off 14:29:55 INFO - Online: Yes 14:29:55 INFO - Rotation: Supported 14:29:55 INFO - Return code: 0 14:29:55 INFO - Running post-action listener: _resource_record_post_action 14:29:55 INFO - [mozharness: 2017-05-21 21:29:55.556834Z] Finished run-tests step (success) 14:29:55 INFO - Running post-run listener: _resource_record_post_run 14:29:55 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 44869632; Write bytes: 577869824; Read time: 340; Write time: 1823 14:29:55 INFO - TinderboxPrint: CPU usage
54.3% 14:29:55 INFO - TinderboxPrint: I/O read bytes / time
44,869,632 / 340 14:29:55 INFO - TinderboxPrint: I/O write bytes / time
577,869,824 / 1,823 14:29:55 INFO - TinderboxPrint: CPU idle
2,185.9 (45.7%) 14:29:55 INFO - TinderboxPrint: CPU system
967.3 (20.2%) 14:29:55 INFO - TinderboxPrint: CPU user
1,632.4 (34.1%) 14:29:55 INFO - TinderboxPrint: Swap in / out
279,265,280 / 0 14:29:55 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 242622976; Write bytes: 234991616; Read time: 17149; Write time: 483 14:29:55 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 40749056; Write bytes: 330520576; Read time: 316; Write time: 1299 14:29:56 INFO - Running post-run listener: _upload_blobber_files 14:29:56 INFO - Blob upload gear active. 14:29:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:29:56 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 14:29:56 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:29:56 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:29:56 INFO - (blobuploader) - INFO - Open directory for files ... 14:29:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:29:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:29:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:29:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:29:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:29:57 INFO - (blobuploader) - INFO - Done attempting. 14:29:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:29:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:29:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:29:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:29:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:29:58 INFO - (blobuploader) - INFO - Done attempting. 14:29:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:29:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:29:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:29:59 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:29:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:29:59 INFO - (blobuploader) - INFO - Done attempting. 14:29:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:29:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:29:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:30:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:30:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:30:01 INFO - (blobuploader) - INFO - Done attempting. 14:30:01 INFO - (blobuploader) - INFO - Iteration through files over. 14:30:01 INFO - Return code: 0 14:30:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:30:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:30:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6ab8e4c7735dd2fa6cc971891cfa47322db4c22ffd4ee6467f07f4414e36efb5bf67ad1bd245fb8860a70d192137858964e5416828524d2415d5f531504028c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b44a16b535f485788d616d14fa6d6a367982d11546cfab4c8aee32fc14e79c1dd3df4b0808d907a5332907c9e372eab3dd2cd46368d04fd09b71b7e5ee11451", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2159866230bfeb204a2b02d09d3b22778305e27e3fa6663e7ca099f6c74169299e56c6b45e3a1ff0b1bf3544a47485cb8cbc233f08e522cab147bf708e13e1e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9d226ff181d303166bf756f1613c4e80dc63e477deb9b4127a8abf4fa7c1e75e7c23988e8fde030f4ff6d6783a61ae48a080901f8c6b3f9d200128e363689be"} 14:30:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:30:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:30:01 INFO - Contents: 14:30:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6ab8e4c7735dd2fa6cc971891cfa47322db4c22ffd4ee6467f07f4414e36efb5bf67ad1bd245fb8860a70d192137858964e5416828524d2415d5f531504028c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b44a16b535f485788d616d14fa6d6a367982d11546cfab4c8aee32fc14e79c1dd3df4b0808d907a5332907c9e372eab3dd2cd46368d04fd09b71b7e5ee11451", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2159866230bfeb204a2b02d09d3b22778305e27e3fa6663e7ca099f6c74169299e56c6b45e3a1ff0b1bf3544a47485cb8cbc233f08e522cab147bf708e13e1e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9d226ff181d303166bf756f1613c4e80dc63e477deb9b4127a8abf4fa7c1e75e7c23988e8fde030f4ff6d6783a61ae48a080901f8c6b3f9d200128e363689be"} 14:30:01 INFO - Running post-run listener: copy_logs_to_upload_dir 14:30:01 INFO - Copying logs to upload dir... 14:30:01 INFO - mkdir: /builds/slave/test/build/upload/logs 14:30:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.681485 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2017-05-21 14:30:01.290254) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-21 14:30:01.294304) ========= 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.wDquRS5ijJ/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.mfQbGwipg9/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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/Firefox/sha512/b6ab8e4c7735dd2fa6cc971891cfa47322db4c22ffd4ee6467f07f4414e36efb5bf67ad1bd245fb8860a70d192137858964e5416828524d2415d5f531504028c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b44a16b535f485788d616d14fa6d6a367982d11546cfab4c8aee32fc14e79c1dd3df4b0808d907a5332907c9e372eab3dd2cd46368d04fd09b71b7e5ee11451", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2159866230bfeb204a2b02d09d3b22778305e27e3fa6663e7ca099f6c74169299e56c6b45e3a1ff0b1bf3544a47485cb8cbc233f08e522cab147bf708e13e1e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9d226ff181d303166bf756f1613c4e80dc63e477deb9b4127a8abf4fa7c1e75e7c23988e8fde030f4ff6d6783a61ae48a080901f8c6b3f9d200128e363689be"} build_url:https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011014 build_url: 'https://queue.taskcluster.net/v1/task/OsmhPs-ORaymlRlfl7WpVA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6ab8e4c7735dd2fa6cc971891cfa47322db4c22ffd4ee6467f07f4414e36efb5bf67ad1bd245fb8860a70d192137858964e5416828524d2415d5f531504028c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6b44a16b535f485788d616d14fa6d6a367982d11546cfab4c8aee32fc14e79c1dd3df4b0808d907a5332907c9e372eab3dd2cd46368d04fd09b71b7e5ee11451", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d2159866230bfeb204a2b02d09d3b22778305e27e3fa6663e7ca099f6c74169299e56c6b45e3a1ff0b1bf3544a47485cb8cbc233f08e522cab147bf708e13e1e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9d226ff181d303166bf756f1613c4e80dc63e477deb9b4127a8abf4fa7c1e75e7c23988e8fde030f4ff6d6783a61ae48a080901f8c6b3f9d200128e363689be"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-21 14:30:01.332250) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 14:30:01.332788) ========= 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.wDquRS5ijJ/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.mfQbGwipg9/Listeners TMPDIR=/var/folders/00/7tdhxz016pgck4583ks_0xj800000w/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.005251 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-21 14:30:01.381759) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-21 14:30:01.382083) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-21 14:30:01.382413) ========= ========= Total master_lag: 0.26 =========