builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0301 starttime: 1496713924.61 results: success (0) revision: 2c6289f56812c30254acfdddabcfec1e149c0336 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 18:52:04.606379) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 18:52:04.606836) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 18:52:04.607105) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 18:52:04.630720) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:52:04.631339) ========= 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.pAB3T5hbOq/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.IG2uF4knSS/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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-06-05 18:52:04-- 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% 63.1M=0s 2017-06-05 18:52:04 (63.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.119056 ========= master_lag: 0.38 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:52:05.126806) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:52:05.127113) ========= 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.pAB3T5hbOq/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.IG2uF4knSS/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.022786 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:52:05.172960) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 18:52:05.173489) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2c6289f56812c30254acfdddabcfec1e149c0336 --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 2c6289f56812c30254acfdddabcfec1e149c0336 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pAB3T5hbOq/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.IG2uF4knSS/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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-06-05 18:52:05,268 truncating revision to first 12 chars 2017-06-05 18:52:05,268 Setting DEBUG logging. 2017-06-05 18:52:05,268 attempt 1/10 2017-06-05 18:52:05,268 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2c6289f56812?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 18:52:06,740 unpacking tar archive at: mozilla-central-2c6289f56812/testing/mozharness/ program finished with exit code 0 elapsedTime=1.739553 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 18:52:06.939933) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 18:52:06.940617) ========= script_repo_revision: 2c6289f56812c30254acfdddabcfec1e149c0336 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 18:52:06.941531) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 18:52:06.941991) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 18:52:06.961728) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-05 18:52:06.961993) ========= /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.pAB3T5hbOq/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.IG2uF4knSS/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:52:07 INFO - MultiFileLogger online at 20170605 18:52:07 in /builds/slave/test 18:52:07 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 18:52:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:52:07 INFO - {'append_to_log': False, 18:52:07 INFO - 'base_work_dir': '/builds/slave/test', 18:52:07 INFO - 'blob_upload_branch': 'Firefox', 18:52:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:52:07 INFO - 'branch': 'Firefox', 18:52:07 INFO - 'buildbot_json_path': 'buildprops.json', 18:52:07 INFO - 'config_files': ('talos/mac_config.py',), 18:52:07 INFO - 'default_actions': ('clobber', 18:52:07 INFO - 'read-buildbot-config', 18:52:07 INFO - 'download-and-extract', 18:52:07 INFO - 'populate-webroot', 18:52:07 INFO - 'create-virtualenv', 18:52:07 INFO - 'install', 18:52:07 INFO - 'setup-mitmproxy', 18:52:07 INFO - 'run-tests'), 18:52:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:52:07 INFO - 'download_minidump_stackwalk': True, 18:52:07 INFO - 'download_symbols': 'ondemand', 18:52:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:52:07 INFO - 'tooltool.py': '/tools/tooltool.py', 18:52:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:52:07 INFO - '/tools/misc-python/virtualenv.py')}, 18:52:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:52:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:52:07 INFO - 'gecko_profile': False, 18:52:07 INFO - 'gecko_profile_interval': 0, 18:52:07 INFO - 'installer_path': 'installer.exe', 18:52:07 INFO - 'log_level': 'info', 18:52:07 INFO - 'log_name': 'talos', 18:52:07 INFO - 'log_to_console': True, 18:52:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:52:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:52:07 INFO - 'opt_config_files': (), 18:52:07 INFO - 'pip_index': False, 18:52:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:52:07 INFO - 'cmd': ('bash', 18:52:07 INFO - '-c', 18:52:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:52:07 INFO - 'enabled': True, 18:52:07 INFO - 'halt_on_failure': False, 18:52:07 INFO - 'name': 'check_screen_resolution'},), 18:52:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:52:07 INFO - 'cmd': ('bash', 18:52:07 INFO - '-c', 18:52:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:52:07 INFO - 'enabled': True, 18:52:07 INFO - 'halt_on_failure': False, 18:52:07 INFO - 'name': 'check_screen_resolution'},), 18:52:07 INFO - 'run_cmd_checks_enabled': True, 18:52:07 INFO - 'suite': 'dromaeojs-e10s', 18:52:07 INFO - 'system_bits': '32', 18:52:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:52:07 INFO - 'title': 't-yosemite-r7-0301', 18:52:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:52:07 INFO - 'use_talos_json': True, 18:52:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:52:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:52:07 INFO - 'work_dir': 'build'} 18:52:07 INFO - [mozharness: 2017-06-06 01:52:07.177502Z] Running clobber step. 18:52:07 INFO - Running pre-action listener: _resource_record_pre_action 18:52:07 INFO - Running main action method: clobber 18:52:07 INFO - rmtree: /builds/slave/test/build 18:52:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:52:07 INFO - Running post-action listener: _resource_record_post_action 18:52:07 INFO - [mozharness: 2017-06-06 01:52:07.567247Z] Finished clobber step (success) 18:52:07 INFO - [mozharness: 2017-06-06 01:52:07.567411Z] Running read-buildbot-config step. 18:52:07 INFO - Running pre-action listener: _resource_record_pre_action 18:52:07 INFO - Running main action method: read_buildbot_config 18:52:07 INFO - Using buildbot properties: 18:52:07 INFO - { 18:52:07 INFO - "project": "", 18:52:07 INFO - "product": "firefox", 18:52:07 INFO - "who": "kwierso@gmail.com", 18:52:07 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:52:07 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:52:07 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:52:07 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:52:07 INFO - "stage_platform": "macosx64", 18:52:07 INFO - "basedir": "/builds/slave/test", 18:52:07 INFO - "buildnumber": 177, 18:52:07 INFO - "platform": "yosemite_r7", 18:52:07 INFO - "parent_task_id": "RnbBQyzoSlyDU2y2lq1StA", 18:52:07 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 18:52:07 INFO - "slavebuilddir": "test", 18:52:07 INFO - "taskId": "bYV1AIRUT9aSkGSxeV6Oag", 18:52:07 INFO - "branch": "mozilla-central", 18:52:07 INFO - "script_repo_revision": "production", 18:52:07 INFO - "revision": "2c6289f56812c30254acfdddabcfec1e149c0336", 18:52:07 INFO - "slavename": "t-yosemite-r7-0301", 18:52:07 INFO - "repo_path": "mozilla-central" 18:52:07 INFO - } 18:52:07 INFO - Finding installer, test and symbols from parent task. 18:52:07 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bYV1AIRUT9aSkGSxeV6Oag'}, attempt #1 18:52:08 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bYV1AIRUT9aSkGSxeV6Oag'}, attempt #1 18:52:09 INFO - Task dependencies: H6ljJEkZQd-MjzAwLn7ohQ RnbBQyzoSlyDU2y2lq1StA 18:52:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA'}, attempt #1 18:52:09 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:52:09 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/test_packages.json 18:52:09 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/target.crashreporter-symbols.zip 18:52:09 INFO - Running post-action listener: _resource_record_post_action 18:52:09 INFO - [mozharness: 2017-06-06 01:52:09.459810Z] Finished read-buildbot-config step (success) 18:52:09 INFO - [mozharness: 2017-06-06 01:52:09.459925Z] Running download-and-extract step. 18:52:09 INFO - Running pre-action listener: _resource_record_pre_action 18:52:09 INFO - Running main action method: download_and_extract 18:52:09 INFO - mkdir: /builds/slave/test/build/tests 18:52:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:09 INFO - trying https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:52:09 INFO - Downloading https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/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 18:52:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/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 18:52:10 INFO - Downloaded 1328 bytes. 18:52:10 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:52:10 INFO - Using the following test package requirements: 18:52:10 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:52:10 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:52:10 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:52:10 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:52:10 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:52:10 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:52:10 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:52:10 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:52:10 INFO - u'jsshell-mac.zip'], 18:52:10 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:52:10 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:52:10 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:52:10 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:52:10 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:52:10 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:52:10 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:52:10 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:52:10 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 18:52:10 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:52:10 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:52:10 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:52:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:52:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:52:10 INFO - Fetch https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:52:12 INFO - Content-Length response header: 57631633 18:52:12 INFO - Bytes received: 57631633 18:52:30 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 18:52:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:52:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:52:30 INFO - Fetch https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:52:32 INFO - Content-Length response header: 57631633 18:52:32 INFO - Bytes received: 57631633 18:52:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:52:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:52:49 INFO - Fetch https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:52:50 INFO - Content-Length response header: 14447425 18:52:50 INFO - Bytes received: 14447425 18:52: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')]} 18:52:50 INFO - trying https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:52:50 INFO - Downloading https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:52:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/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 18:52:52 INFO - Downloaded 67980546 bytes. 18:52:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:52:52 INFO - mkdir: /builds/slave/test/properties 18:52:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:52:52 INFO - Writing to file /builds/slave/test/properties/build_url 18:52:52 INFO - Contents: 18:52:52 INFO - build_url:https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:52:52 INFO - Running post-action listener: _resource_record_post_action 18:52:52 INFO - Running post-action listener: set_extra_try_arguments 18:52:52 INFO - [mozharness: 2017-06-06 01:52:52.474696Z] Finished download-and-extract step (success) 18:52:52 INFO - [mozharness: 2017-06-06 01:52:52.474815Z] Running populate-webroot step. 18:52:52 INFO - Running pre-action listener: _resource_record_pre_action 18:52:52 INFO - Running main action method: populate_webroot 18:52:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:52:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:52:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:52:52 INFO - '--tppagecycles', 18:52:52 INFO - '7'], 18:52:52 INFO - 'tests': ['tsvgm']}}, 18:52:52 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:52:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:52:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:52:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:52:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:52:52 INFO - 'talos_options': ['--disable-e10s'], 18:52:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:52:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:52:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:52:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:52:52 INFO - 'talos_options': ['--disable-e10s'], 18:52:52 INFO - 'tests': ['damp', 'tps']}, 18:52:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:52:52 INFO - 'tests': ['damp', 'tps']}, 18:52:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:52:52 INFO - 'tests': ['dromaeo_dom']}, 18:52:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:52:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:52:52 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:52:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:52:52 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:52:52 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:52:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:52:52 INFO - 'tests': ['a11yr', 18:52:52 INFO - 'ts_paint', 18:52:52 INFO - 'tpaint', 18:52:52 INFO - 'sessionrestore', 18:52:52 INFO - 'sessionrestore_no_auto_restore', 18:52:52 INFO - 'tabpaint']}, 18:52:52 INFO - 'other-e10s': {'tests': ['a11yr', 18:52:52 INFO - 'ts_paint', 18:52:52 INFO - 'tpaint', 18:52:52 INFO - 'sessionrestore', 18:52:52 INFO - 'sessionrestore_no_auto_restore', 18:52:52 INFO - 'tabpaint']}, 18:52:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:52:52 INFO - 'ts_paint', 18:52:52 INFO - 'tpaint', 18:52:52 INFO - 'sessionrestore', 18:52:52 INFO - 'sessionrestore_no_auto_restore', 18:52:52 INFO - 'tabpaint']}, 18:52:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:52:52 INFO - 'ts_paint', 18:52:52 INFO - 'tpaint', 18:52:52 INFO - 'sessionrestore', 18:52:52 INFO - 'sessionrestore_no_auto_restore', 18:52:52 INFO - 'tabpaint']}, 18:52:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:52:52 INFO - 'tests': ['a11yr', 18:52:52 INFO - 'ts_paint', 18:52:52 INFO - 'tpaint', 18:52:52 INFO - 'sessionrestore', 18:52:52 INFO - 'sessionrestore_no_auto_restore', 18:52:52 INFO - 'tabpaint']}, 18:52:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:52:52 INFO - 'tests': ['a11yr', 18:52:52 INFO - 'ts_paint', 18:52:52 INFO - 'tpaint', 18:52:52 INFO - 'sessionrestore', 18:52:52 INFO - 'sessionrestore_no_auto_restore', 18:52:52 INFO - 'tabpaint']}, 18:52:52 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:52:52 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:52:52 INFO - 'bloom_basic_ref']}, 18:52:52 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 18:52:52 INFO - 'talos_options': ['--mitmproxy', 18:52:52 INFO - 'mitmproxy-recording-1.mp', 18:52:52 INFO - '--firstNonBlankPaint'], 18:52:52 INFO - 'tests': ['Quantum_1']}, 18:52:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:52:52 INFO - 'tests': ['tsvgx', 18:52:52 INFO - 'tsvgr_opacity', 18:52:52 INFO - 'tart', 18:52:52 INFO - 'tscrollx', 18:52:52 INFO - 'cart', 18:52:52 INFO - 'tsvg_static']}, 18:52:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:52:52 INFO - 'tsvgr_opacity', 18:52:52 INFO - 'tart', 18:52:52 INFO - 'tscrollx', 18:52:52 INFO - 'cart', 18:52:52 INFO - 'tsvg_static']}, 18:52:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:52:52 INFO - 'talos_options': ['--disable-e10s'], 18:52:52 INFO - 'tests': ['tp5o']}, 18:52:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:52:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:52:52 INFO - 'talos_options': ['--disable-e10s', 18:52:52 INFO - '--xperf_path', 18:52:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:52:52 INFO - 'tests': ['tp5n']}, 18:52:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:52:52 INFO - 'talos_options': ['--xperf_path', 18:52:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:52:52 INFO - 'tests': ['tp5n']}}, 18:52:52 INFO - 'talos.zip': {'path': '', 18:52:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:52:52 INFO - Running post-action listener: _resource_record_post_action 18:52:52 INFO - [mozharness: 2017-06-06 01:52:52.496787Z] Finished populate-webroot step (success) 18:52:52 INFO - [mozharness: 2017-06-06 01:52:52.496954Z] Running create-virtualenv step. 18:52:52 INFO - Running pre-action listener: _resource_record_pre_action 18:52:52 INFO - Running main action method: create_virtualenv 18:52:52 INFO - Creating virtualenv /builds/slave/test/build/venv 18:52:52 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 18:52:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:52:52 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:52:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:52:52 INFO - Using real prefix '/tools/python27' 18:52:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:52:53 INFO - Installing distribute.............................................................................................................................................................................................done. 18:52:56 INFO - Installing pip.................done. 18:52:56 INFO - Return code: 0 18:52:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:52:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:52:56 INFO - Reading from file tmpfile_stdout 18:52:56 INFO - Output received: 18:52:56 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) 18:52:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:56 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 0x10db51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa850d72750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadac60>, '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 0x10d86fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d898be8>, '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.pAB3T5hbOq/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.IG2uF4knSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:52:56 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 18:52:56 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 18:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pAB3T5hbOq/Render', 18:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:56 INFO - 'HOME': '/Users/cltbld', 18:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:56 INFO - 'LOGNAME': 'cltbld', 18:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:56 INFO - 'MOZ_NO_REMOTE': '1', 18:52:56 INFO - 'NO_EM_RESTART': '1', 18:52:56 INFO - 'PAGER': '/bin/cat', 18:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:56 INFO - 'PWD': '/builds/slave/test', 18:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:56 INFO - 'SHELL': '/bin/bash', 18:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IG2uF4knSS/Listeners', 18:52:56 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:56 INFO - 'USER': 'cltbld', 18:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:56 INFO - 'XPC_FLAGS': '0x0', 18:52:56 INFO - 'XPC_SERVICE_NAME': '0', 18:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:56 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 18:52:56 INFO - Cleaning up... 18:52:56 INFO - Return code: 0 18:52:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:56 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 0x10db51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa850d72750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadac60>, '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 0x10d86fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d898be8>, '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.pAB3T5hbOq/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.IG2uF4knSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:52:56 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 18:52:56 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 18:52:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pAB3T5hbOq/Render', 18:52:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:56 INFO - 'HOME': '/Users/cltbld', 18:52:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:56 INFO - 'LOGNAME': 'cltbld', 18:52:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:56 INFO - 'MOZ_NO_REMOTE': '1', 18:52:56 INFO - 'NO_EM_RESTART': '1', 18:52:56 INFO - 'PAGER': '/bin/cat', 18:52:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:56 INFO - 'PWD': '/builds/slave/test', 18:52:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:56 INFO - 'SHELL': '/bin/bash', 18:52:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IG2uF4knSS/Listeners', 18:52:56 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:52:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:56 INFO - 'USER': 'cltbld', 18:52:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:56 INFO - 'XPC_FLAGS': '0x0', 18:52:56 INFO - 'XPC_SERVICE_NAME': '0', 18:52:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:56 INFO - Downloading/unpacking psutil>=3.1.1 18:52: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 18:52: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 18:52: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 18:52: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 18:52:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:52:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:52:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:52:58 INFO - Installing collected packages: psutil 18:52:58 INFO - Running setup.py install for psutil 18:52:59 INFO - building 'psutil._psutil_osx' extension 18:52:59 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 18:52:59 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 18:52:59 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 18:52:59 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 18:52:59 INFO - building 'psutil._psutil_posix' extension 18:52:59 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 18:52:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:52:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:52:59 INFO - ^ 18:52:59 INFO - 1 warning generated. 18:52:59 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 18:52:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:52:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:52:59 INFO - Successfully installed psutil 18:52:59 INFO - Cleaning up... 18:52:59 INFO - Return code: 0 18:52:59 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:52:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10db51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa850d72750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadac60>, '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 0x10d86fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d898be8>, '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.pAB3T5hbOq/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.IG2uF4knSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:53:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:53:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:53:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pAB3T5hbOq/Render', 18:53:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:00 INFO - 'HOME': '/Users/cltbld', 18:53:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:00 INFO - 'LOGNAME': 'cltbld', 18:53:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:00 INFO - 'MOZ_NO_REMOTE': '1', 18:53:00 INFO - 'NO_EM_RESTART': '1', 18:53:00 INFO - 'PAGER': '/bin/cat', 18:53:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:00 INFO - 'PWD': '/builds/slave/test', 18:53:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:00 INFO - 'SHELL': '/bin/bash', 18:53:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IG2uF4knSS/Listeners', 18:53:00 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:53:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:00 INFO - 'USER': 'cltbld', 18:53:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:00 INFO - 'XPC_FLAGS': '0x0', 18:53:00 INFO - 'XPC_SERVICE_NAME': '0', 18:53:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:53:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:53:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:53:02 INFO - Installing collected packages: mozsystemmonitor 18:53:02 INFO - Running setup.py install for mozsystemmonitor 18:53:03 INFO - Successfully installed mozsystemmonitor 18:53:03 INFO - Cleaning up... 18:53:03 INFO - Return code: 0 18:53:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10db51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa850d72750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadac60>, '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 0x10d86fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d898be8>, '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.pAB3T5hbOq/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.IG2uF4knSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:53:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:53:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:53:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pAB3T5hbOq/Render', 18:53:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:03 INFO - 'HOME': '/Users/cltbld', 18:53:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:03 INFO - 'LOGNAME': 'cltbld', 18:53:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:03 INFO - 'MOZ_NO_REMOTE': '1', 18:53:03 INFO - 'NO_EM_RESTART': '1', 18:53:03 INFO - 'PAGER': '/bin/cat', 18:53:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:03 INFO - 'PWD': '/builds/slave/test', 18:53:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:03 INFO - 'SHELL': '/bin/bash', 18:53:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IG2uF4knSS/Listeners', 18:53:03 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:53:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:03 INFO - 'USER': 'cltbld', 18:53:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:03 INFO - 'XPC_FLAGS': '0x0', 18:53:03 INFO - 'XPC_SERVICE_NAME': '0', 18:53:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:03 INFO - Downloading/unpacking jsonschema==2.5.1 18:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:53:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:53:04 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 18:53:04 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 18:53:04 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 18:53:04 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 18:53:05 INFO - Downloading functools32-3.2.3-2.tar.gz 18:53:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:53:05 INFO - warning: no files found matching '*.txt' 18:53:05 INFO - no previously-included directories found matching 'build' 18:53:05 INFO - no previously-included directories found matching 'dist' 18:53:05 INFO - no previously-included directories found matching '.git*' 18:53:05 INFO - Installing collected packages: jsonschema, functools32 18:53:05 INFO - Running setup.py install for functools32 18:53:05 INFO - warning: no files found matching '*.txt' 18:53:05 INFO - no previously-included directories found matching 'build' 18:53:05 INFO - no previously-included directories found matching 'dist' 18:53:05 INFO - no previously-included directories found matching '.git*' 18:53:05 INFO - Successfully installed jsonschema functools32 18:53:05 INFO - Cleaning up... 18:53:05 INFO - Return code: 0 18:53:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:53:05 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')]} 18:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:05 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')]} 18:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:05 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 0x10db51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa850d72750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadac60>, '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 0x10d86fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d898be8>, '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.pAB3T5hbOq/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.IG2uF4knSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:53:05 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 18:53:05 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 18:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pAB3T5hbOq/Render', 18:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:05 INFO - 'HOME': '/Users/cltbld', 18:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:05 INFO - 'LOGNAME': 'cltbld', 18:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:05 INFO - 'MOZ_NO_REMOTE': '1', 18:53:05 INFO - 'NO_EM_RESTART': '1', 18:53:05 INFO - 'PAGER': '/bin/cat', 18:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:05 INFO - 'PWD': '/builds/slave/test', 18:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:05 INFO - 'SHELL': '/bin/bash', 18:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IG2uF4knSS/Listeners', 18:53:05 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:05 INFO - 'USER': 'cltbld', 18:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:05 INFO - 'XPC_FLAGS': '0x0', 18:53:05 INFO - 'XPC_SERVICE_NAME': '0', 18:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:53:05 INFO - Cleaning up... 18:53:05 INFO - Return code: 0 18:53:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:53:05 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')]} 18:53:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:05 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')]} 18:53:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:05 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 0x10db51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa850d72750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadac60>, '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 0x10d86fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d898be8>, '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.pAB3T5hbOq/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.IG2uF4knSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:53:05 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 18:53:05 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 18:53:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pAB3T5hbOq/Render', 18:53:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:05 INFO - 'HOME': '/Users/cltbld', 18:53:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:05 INFO - 'LOGNAME': 'cltbld', 18:53:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:05 INFO - 'MOZ_NO_REMOTE': '1', 18:53:05 INFO - 'NO_EM_RESTART': '1', 18:53:05 INFO - 'PAGER': '/bin/cat', 18:53:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:05 INFO - 'PWD': '/builds/slave/test', 18:53:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:05 INFO - 'SHELL': '/bin/bash', 18:53:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IG2uF4knSS/Listeners', 18:53:05 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:53:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:05 INFO - 'USER': 'cltbld', 18:53:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:05 INFO - 'XPC_FLAGS': '0x0', 18:53:05 INFO - 'XPC_SERVICE_NAME': '0', 18:53:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:05 INFO - Downloading/unpacking blobuploader==1.2.4 18:53:05 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 18:53:05 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 18:53:05 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 18:53:05 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 18:53:07 INFO - Downloading blobuploader-1.2.4.tar.gz 18:53:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:53:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:53:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:08 INFO - Downloading docopt-0.6.1.tar.gz 18:53:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:53:08 INFO - Installing collected packages: blobuploader, requests, docopt 18:53:08 INFO - Running setup.py install for blobuploader 18:53:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:53:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:53:08 INFO - Running setup.py install for requests 18:53:08 INFO - Running setup.py install for docopt 18:53:09 INFO - Successfully installed blobuploader requests docopt 18:53:09 INFO - Cleaning up... 18:53:09 INFO - Return code: 0 18:53:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:09 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 0x10db51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa850d72750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadac60>, '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 0x10d86fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d898be8>, '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.pAB3T5hbOq/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.IG2uF4knSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:53:09 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 18:53:09 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 18:53:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pAB3T5hbOq/Render', 18:53:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:09 INFO - 'HOME': '/Users/cltbld', 18:53:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:09 INFO - 'LOGNAME': 'cltbld', 18:53:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:09 INFO - 'MOZ_NO_REMOTE': '1', 18:53:09 INFO - 'NO_EM_RESTART': '1', 18:53:09 INFO - 'PAGER': '/bin/cat', 18:53:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:09 INFO - 'PWD': '/builds/slave/test', 18:53:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:09 INFO - 'SHELL': '/bin/bash', 18:53:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IG2uF4knSS/Listeners', 18:53:09 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:53:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:09 INFO - 'USER': 'cltbld', 18:53:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:09 INFO - 'XPC_FLAGS': '0x0', 18:53:09 INFO - 'XPC_SERVICE_NAME': '0', 18:53:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:53:09 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-LnLltO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:53:09 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-V4KZGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:53:09 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-q4WZpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:53:09 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-RfxSUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:53:09 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-b4Wcjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:53:09 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-dBjhYA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:53:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:53:09 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-jSVVVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:53:10 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-_PLpkD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:53:10 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-0QWv1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:53:10 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-TL1wO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:53:10 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-pxF_PI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:53:10 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-9R5sIV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:53:10 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-PutYHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:53:10 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Rj1hwK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:53:10 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-l7q1VO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:53:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:53:10 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-O38H2J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:53:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:53:11 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-DPbY8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:53:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:53:11 INFO - Running setup.py install for manifestparser 18:53:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:53:11 INFO - Running setup.py install for mozcrash 18:53:11 INFO - Running setup.py install for mozdebug 18:53:11 INFO - Running setup.py install for mozdevice 18:53:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:53:11 INFO - Running setup.py install for mozfile 18:53:11 INFO - Running setup.py install for mozhttpd 18:53:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:53:11 INFO - Running setup.py install for mozinfo 18:53:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:53:12 INFO - Running setup.py install for mozInstall 18:53:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:53:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:53:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:53:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:53:12 INFO - Running setup.py install for mozleak 18:53:12 INFO - Running setup.py install for mozlog 18:53:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:53:12 INFO - Running setup.py install for moznetwork 18:53:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:53:12 INFO - Running setup.py install for mozprocess 18:53:12 INFO - Running setup.py install for mozprofile 18:53:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:53:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:53:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:53:13 INFO - Running setup.py install for mozrunner 18:53:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:53:13 INFO - Running setup.py install for mozscreenshot 18:53:13 INFO - Running setup.py install for moztest 18:53:13 INFO - Running setup.py install for mozversion 18:53:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:53:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:53:13 INFO - Cleaning up... 18:53:13 INFO - Return code: 0 18:53:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:53:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:13 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 0x10db51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa850d72750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadac60>, '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 0x10d86fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d898be8>, '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.pAB3T5hbOq/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.IG2uF4knSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:53:13 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 18:53:13 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 18:53:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pAB3T5hbOq/Render', 18:53:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:13 INFO - 'HOME': '/Users/cltbld', 18:53:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:13 INFO - 'LOGNAME': 'cltbld', 18:53:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:13 INFO - 'MOZ_NO_REMOTE': '1', 18:53:13 INFO - 'NO_EM_RESTART': '1', 18:53:13 INFO - 'PAGER': '/bin/cat', 18:53:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:13 INFO - 'PWD': '/builds/slave/test', 18:53:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:13 INFO - 'SHELL': '/bin/bash', 18:53:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IG2uF4knSS/Listeners', 18:53:13 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:53:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:13 INFO - 'USER': 'cltbld', 18:53:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:13 INFO - 'XPC_FLAGS': '0x0', 18:53:13 INFO - 'XPC_SERVICE_NAME': '0', 18:53:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:53:13 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-uPG8a5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:53:14 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)) 18:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:53:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-WLfBad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:53:14 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)) 18:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:53:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-LWWN79-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:53:14 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)) 18:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:53:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-FEfss8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:53:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 18:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:53:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-9jLpbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:53:14 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)) 18:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:53:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-OeQkVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:53:14 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)) 18:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:53:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-32Ktn9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:53:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 18:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:53:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-mBuJGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:53:14 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)) 18:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:53:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-ZgEYi2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:53:14 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)) 18:53:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:53:14 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-WdDeHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:53:15 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)) 18:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:53:15 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-OGSV9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:53:15 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)) 18:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:53:15 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-LVvEAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:53:15 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)) 18:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:53:15 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-H9nDW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:53:15 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)) 18:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:53:15 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-capL8K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:53:15 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)) 18:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:53:15 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-fKCqbv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:53:15 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)) 18:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:53:15 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-SDjL9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:53:15 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)) 18:53:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:53:15 INFO - Running setup.py (path:/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/pip-Ev7mtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:53:15 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)) 18:53:15 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)) 18:53:15 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)) 18:53:15 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)) 18:53:15 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:53:15 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.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:53:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:17 INFO - Downloading blessings-1.6.tar.gz 18:53:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:53:17 INFO - Installing collected packages: blessings 18:53:17 INFO - Running setup.py install for blessings 18:53:17 INFO - Successfully installed blessings 18:53:17 INFO - Cleaning up... 18:53:17 INFO - Return code: 0 18:53:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:53:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:53:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:53:17 INFO - Reading from file tmpfile_stdout 18:53:17 INFO - Current package versions: 18:53:17 INFO - blessings == 1.6 18:53:17 INFO - blobuploader == 1.2.4 18:53:17 INFO - docopt == 0.6.1 18:53:17 INFO - functools32 == 3.2.3-2 18:53:17 INFO - jsonschema == 2.5.1 18:53:17 INFO - manifestparser == 1.1 18:53:17 INFO - mozInstall == 1.12 18:53:17 INFO - mozcrash == 1.0 18:53:17 INFO - mozdebug == 0.1 18:53:17 INFO - mozdevice == 0.51 18:53:17 INFO - mozfile == 1.2 18:53:17 INFO - mozhttpd == 0.7 18:53:17 INFO - mozinfo == 0.10 18:53:17 INFO - mozleak == 0.1 18:53:17 INFO - mozlog == 3.4 18:53:17 INFO - moznetwork == 0.27 18:53:17 INFO - mozprocess == 0.25 18:53:17 INFO - mozprofile == 0.28 18:53:17 INFO - mozrunner == 6.13 18:53:17 INFO - mozscreenshot == 0.1 18:53:17 INFO - mozsystemmonitor == 0.3 18:53:17 INFO - moztest == 0.8 18:53:17 INFO - mozversion == 1.4 18:53:17 INFO - psutil == 3.1.1 18:53:17 INFO - requests == 1.2.3 18:53:17 INFO - wsgiref == 0.1.2 18:53:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:53:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:17 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 0x10db51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa850d72750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadac60>, '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 0x10d86fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d898be8>, '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.pAB3T5hbOq/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.IG2uF4knSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:53:17 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 18:53:17 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 18:53:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pAB3T5hbOq/Render', 18:53:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:17 INFO - 'HOME': '/Users/cltbld', 18:53:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:17 INFO - 'LOGNAME': 'cltbld', 18:53:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:17 INFO - 'MOZ_NO_REMOTE': '1', 18:53:17 INFO - 'NO_EM_RESTART': '1', 18:53:17 INFO - 'PAGER': '/bin/cat', 18:53:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:17 INFO - 'PWD': '/builds/slave/test', 18:53:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:17 INFO - 'SHELL': '/bin/bash', 18:53:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IG2uF4knSS/Listeners', 18:53:17 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:53:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:17 INFO - 'USER': 'cltbld', 18:53:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:17 INFO - 'XPC_FLAGS': '0x0', 18:53:17 INFO - 'XPC_SERVICE_NAME': '0', 18:53:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:17 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)) 18:53:17 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)) 18:53:17 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)) 18:53:17 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)) 18:53:17 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)) 18:53:17 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)) 18:53:17 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)) 18:53:17 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)) 18:53:17 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)) 18:53:18 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:53:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:53:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:53:19 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:53:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:53:19 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)) 18:53:19 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)) 18:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:53:19 INFO - Installing collected packages: marionette-driver 18:53:19 INFO - Running setup.py install for marionette-driver 18:53:19 INFO - Successfully installed marionette-driver 18:53:19 INFO - Cleaning up... 18:53:19 INFO - Return code: 0 18:53:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:53:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:53:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:53:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:53:19 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 0x10db51c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10db55768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa850d72750>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadac60>, '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 0x10d86fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d898be8>, '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.pAB3T5hbOq/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.IG2uF4knSS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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 18:53:19 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 18:53:19 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 18:53:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pAB3T5hbOq/Render', 18:53:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:19 INFO - 'HOME': '/Users/cltbld', 18:53:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:19 INFO - 'LOGNAME': 'cltbld', 18:53:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:19 INFO - 'MOZ_NO_REMOTE': '1', 18:53:19 INFO - 'NO_EM_RESTART': '1', 18:53:19 INFO - 'PAGER': '/bin/cat', 18:53:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:19 INFO - 'PWD': '/builds/slave/test', 18:53:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:19 INFO - 'SHELL': '/bin/bash', 18:53:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IG2uF4knSS/Listeners', 18:53:19 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:53:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:19 INFO - 'USER': 'cltbld', 18:53:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:19 INFO - 'XPC_FLAGS': '0x0', 18:53:19 INFO - 'XPC_SERVICE_NAME': '0', 18:53:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:53:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:53:19 INFO - Cleaning up... 18:53:19 INFO - Return code: 0 18:53:19 INFO - Running post-action listener: _resource_record_post_action 18:53:19 INFO - Running post-action listener: _start_resource_monitoring 18:53:20 INFO - Starting resource monitoring. 18:53:20 INFO - [mozharness: 2017-06-06 01:53:20.021342Z] Finished create-virtualenv step (success) 18:53:20 INFO - [mozharness: 2017-06-06 01:53:20.022393Z] Running install step. 18:53:20 INFO - Running pre-action listener: _resource_record_pre_action 18:53:20 INFO - Running main action method: install 18:53:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:53:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:53:20 INFO - Reading from file tmpfile_stdout 18:53:20 INFO - Detecting whether we're running mozinstall >=1.0... 18:53:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:53:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:53:20 INFO - Reading from file tmpfile_stdout 18:53:20 INFO - Output received: 18:53:20 INFO - Usage: mozinstall [options] installer 18:53:20 INFO - Options: 18:53:20 INFO - -h, --help show this help message and exit 18:53:20 INFO - -d DEST, --destination=DEST 18:53:20 INFO - Directory to install application into. [default: 18:53:20 INFO - "/builds/slave/test"] 18:53:20 INFO - --app=APP Application being installed. [default: firefox] 18:53:20 INFO - mkdir: /builds/slave/test/build/application 18:53:20 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'] 18:53:20 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 18:53:41 INFO - Reading from file tmpfile_stdout 18:53:41 INFO - Output received: 18:53:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:53:41 INFO - Running post-action listener: _resource_record_post_action 18:53:41 INFO - [mozharness: 2017-06-06 01:53:41.625263Z] Finished install step (success) 18:53:41 INFO - [mozharness: 2017-06-06 01:53:41.625400Z] Running setup-mitmproxy step. 18:53:41 INFO - Running pre-action listener: _resource_record_pre_action 18:53:41 INFO - Running main action method: setup_mitmproxy 18:53:41 INFO - Skipping: mitmproxy is not required 18:53:41 INFO - Running post-action listener: _resource_record_post_action 18:53:41 INFO - [mozharness: 2017-06-06 01:53:41.626004Z] Finished setup-mitmproxy step (success) 18:53:41 INFO - [mozharness: 2017-06-06 01:53:41.626169Z] Running run-tests step. 18:53:41 INFO - Running pre-action listener: _resource_record_pre_action 18:53:41 INFO - Running main action method: run_tests 18:53:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:53:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:53:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:53:41 INFO - 2017-06-05 18:53:41.641 screenresolution[85235:714848] starting screenresolution argv=screenresolution get 18:53:41 INFO - 2017-06-05 18:53:41.643 screenresolution[85235:714848] Display 0: 1600x1200x32@60 18:53:41 INFO - 2017-06-05 18:53:41.649 screenresolution[85236:714851] starting screenresolution argv=screenresolution list 18:53:41 INFO - Available Modes on Display 0 18:53:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:53:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:53:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:53:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:53:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:53:41 INFO - Intel Iris: 18:53:41 INFO - Chipset Model: Intel Iris 18:53:41 INFO - Type: GPU 18:53:41 INFO - Bus: Built-In 18:53:41 INFO - VRAM (Dynamic, Max): 1536 MB 18:53:41 INFO - Vendor: Intel (0x8086) 18:53:41 INFO - Device ID: 0x0a2e 18:53:41 INFO - Revision ID: 0x0009 18:53:41 INFO - Displays: 18:53:41 INFO - Display: 18:53:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:53:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:53:41 INFO - Display Serial Number: 1600x1200 60 18:53:41 INFO - Main Display: Yes 18:53:41 INFO - Mirror: Off 18:53:41 INFO - Online: Yes 18:53:41 INFO - Rotation: Supported 18:53:41 INFO - Return code: 0 18:53:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:53:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:53:41 INFO - Python 2.7.3 18:53:41 INFO - Return code: 0 18:53:41 INFO - grabbing minidump binary from tooltool 18:53: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')]} 18:53:41 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dadac60>, '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 0x10d86fcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d898be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:53:41 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:53:41 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:53:41 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:53:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:53:42 INFO - Return code: 0 18:53:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:53:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:53:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:53:42 INFO - ENV: RUST_BACKTRACE is now 1 18:53:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:53:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:53:42 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/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--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 18:53:42 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/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0301 --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 18:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pAB3T5hbOq/Render', 18:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:53:42 INFO - 'HOME': '/Users/cltbld', 18:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:53:42 INFO - 'LOGNAME': 'cltbld', 18:53:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:53:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:53:42 INFO - 'MOZ_NO_REMOTE': '1', 18:53:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:53:42 INFO - 'NO_EM_RESTART': '1', 18:53:42 INFO - 'PAGER': '/bin/cat', 18:53:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:53:42 INFO - 'PWD': '/builds/slave/test', 18:53:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:53:42 INFO - 'RUST_BACKTRACE': '1', 18:53:42 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:53:42 INFO - 'SHELL': '/bin/bash', 18:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IG2uF4knSS/Listeners', 18:53:42 INFO - 'TMPDIR': '/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/', 18:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:53:42 INFO - 'USER': 'cltbld', 18:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:53:42 INFO - 'XPC_FLAGS': '0x0', 18:53:42 INFO - 'XPC_SERVICE_NAME': '0', 18:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:53:42 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/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0301', '--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 18:53:42 INFO - mozversion application_buildid: 20170605173745 18:53:42 INFO - mozversion application_changeset: 2c6289f56812c30254acfdddabcfec1e149c0336 18:53:42 INFO - mozversion application_display_name: Nightly 18:53:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:53:42 INFO - mozversion application_name: Firefox 18:53:42 INFO - mozversion application_remotingname: firefox 18:53:42 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:53:42 INFO - mozversion application_vendor: Mozilla 18:53:42 INFO - mozversion application_version: 55.0a1 18:53:42 INFO - mozversion platform_buildid: 20170605173745 18:53:42 INFO - mozversion platform_changeset: 2c6289f56812c30254acfdddabcfec1e149c0336 18:53:42 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:53:42 INFO - mozversion platform_version: 55.0a1 18:53:42 INFO - using testdate: 1496714022 18:53:42 INFO - actual date: 1496714022 18:53:42 INFO - starting webserver on 'localhost:49286' 18:53:42 INFO - SUITE-START | Running 2 tests 18:53:42 INFO - TEST-START | dromaeo_css 18:53:42 INFO - operating with platform_type : mac_ 18:53:42 INFO - Initialising browser for dromaeo_css test... 18:53:42 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49286/getInfo.html -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpJ5vT_I/profile 18:53:42 INFO - TEST-INFO | started process 85246 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49286/getInfo.html -foreground) 18:54:16 INFO - TEST-INFO | 85246: killed by SIGTERM 18:54:16 INFO - Browser initialized. 18:54:16 INFO - Running cycle 1/1 for dromaeo_css test... 18:54:16 INFO - TEST-INFO | started process 85255 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpJ5vT_I/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:54:18 INFO - PID 85255 | 2017-06-05 18:54:18.496 plugin-container[85256:715352] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 18:54:18 INFO - PID 85255 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:54:18 INFO - PID 85255 | 2017-06-05 18:54:18.496 plugin-container[85256:715352] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4807, name = 'com.apple.CFPasteboardClient' 18:54:18 INFO - PID 85255 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:54:18 INFO - PID 85255 | 2017-06-05 18:54:18.496 plugin-container[85256:715352] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:54:18 INFO - PID 85255 | 2017-06-05 18:54:18.862 plugin-container[85257:715401] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 18:54:18 INFO - PID 85255 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:54:18 INFO - PID 85255 | 2017-06-05 18:54:18.862 plugin-container[85257:715401] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460f, name = 'com.apple.CFPasteboardClient' 18:54:18 INFO - PID 85255 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:54:18 INFO - PID 85255 | 2017-06-05 18:54:18.862 plugin-container[85257:715401] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:54:28 INFO - PID 85255 | RSS: Main: 268505088 18:54:28 INFO - PID 85255 | 18:57:30 INFO - PID 85255 | Cycle 1(1): loaded http://localhost:49286/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49286/tests/dromaeo/cssquery-ext.html) 18:57:31 INFO - PID 85255 | RSS: Main: 266743808 18:57:31 INFO - PID 85255 | 19:00:33 INFO - PID 85255 | Cycle 1(1): loaded http://localhost:49286/tests/dromaeo/cssquery-ext.html (next: http://localhost:49286/tests/dromaeo/cssquery-jquery.html) 19:00:34 INFO - PID 85255 | RSS: Main: 281444352 19:00:34 INFO - PID 85255 | 19:03:35 INFO - PID 85255 | Cycle 1(1): loaded http://localhost:49286/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49286/tests/dromaeo/cssquery-mootools.html) 19:03:36 INFO - PID 85255 | RSS: Main: 282017792 19:03:36 INFO - PID 85255 | 19:06:38 INFO - PID 85255 | Cycle 1(1): loaded http://localhost:49286/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49286/tests/dromaeo/cssquery-prototype.html) 19:06:38 INFO - PID 85255 | RSS: Main: 285773824 19:06:38 INFO - PID 85255 | 19:09:41 INFO - PID 85255 | Cycle 1(1): loaded http://localhost:49286/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49286/tests/dromaeo/cssquery-yui.html) 19:09:42 INFO - PID 85255 | RSS: Main: 287981568 19:09:42 INFO - PID 85255 | 19:12:44 INFO - PID 85255 | Cycle 1(1): loaded http://localhost:49286/tests/dromaeo/cssquery-yui.html (next: http://localhost:49286/tests/dromaeo/cssquery-dojo.html) 19:12:44 INFO - PID 85255 | RSS: Main: 289492992 19:12:44 INFO - PID 85255 | 19:12:44 INFO - PID 85255 | __start_tp_report 19:12:44 INFO - PID 85255 | _x_x_mozilla_page_load 19:12:44 INFO - PID 85255 | _x_x_mozilla_page_load_details 19:12:44 INFO - PID 85255 | |i|pagename|runs| 19:12:44 INFO - PID 85255 | |0;dojo.html;3007.488726916424;3029.651590067143;3592.1019745063736;3652.1052342176154;3663.5054267673863;4602.493725690171;4762.499937506564;6112.144299798032;6241.469475094616;6297.149884765556;4475.373447717322;4752.859313764698;6007.429294217053;6104.633721976674;6125.479643195305;5435.945640543583;5488.835334939949;5534.75093620788;5547.972260138694;5588.720563971806;5060.266261392096;5556.083246264361;5606.719664016803;5629.8592535186535;5631.1271752878365;5576.832695019136;5612;5631.0708733059;5647.463490968352;5653.604247702642;5375.193720941851;5443.673379597237;5503.9174412383845;5512.4211957744665;5520.558355331564;5421.539169170625;5428.9185662215095;5496.340439147288;5501.697406642633;5507.366652834895;6193.1329613854095;6225.221847269091;6257.937420625826;6269.310375858652;6273.121762953145;6532.804015879531;6589.670516474157;6732.899006514906;6743.966280168617;6770.32296770324;6788.185417749902;6872.450203983669;6887.139107611549;6890.276520965276;6904.585724856524;6345.492360611186;6361.236651601745;6361.300256971683;6364.586301890362;6368.808935731936;6692.364225398676;7016.772064888624;7046.330598593126;7054.506184567031;7081.5042946994245;6483.7730679425995;6519.348065193538;6628.83427914306;6665.266820649725;6667.966660166668;6518.511111666644;6707.49693772969;6780.38976492118;6834.931650683529;6842.144731908512;6696.0960270362875;6722.9663851680425;6726.260111387744;6743.527953043338;6753.662316884136;6735.49483788708;6757.087793147961;6764.763233286812;6769.492288078413;6769.526133170631;4416.4258646375765;4523.638108951276;4615.653825963066;5279.366476022825;5346.518813306821;4766.523347665206;4836.298736683161;4855.295982082578;4887.29134275528;4899.412070551557;4999.625028122905;5506.834794956158;6266.686665666698;6351.396617321347;6396.136521569621;6670.332966703388;6713.26154123046;6841.521093523505;6844.041834143224;6879.552829066095;6266.122742816009;6288.52836037299;6326.367363263729;6341.8097457076365;6362.491000719931;6003.489703375175;6008.459238668541;6033.3664965178905;6045.818625441244;6070.908936365958;6671.46628269747;7019.333163349474;7043.084399028094;7059.258777828357;7072.71709131629;5722.856928576819;6700.095487109373;6761.290064543349;6821.624810635462;6865.244823069558;5572.526335261548;5632.831015069554;6844.828879278058;6973.79078627642;7007.789766306815;6715.4627629790475;6719.160104986877;6730.226024007302;6734.9326506734305;6761.763338283136;5635.718214089361;6607.207135143622;6744.325567443217;6815.556988795713;6889.104416425833;6649.534532582673;6651.866962660622;6667.733290668319;6672.266050734513;6689.46484281266;6411.455026322815;6432.774852880127;6619.867602648016;6630.83422914431;6653;6316.810495685137;6394.2646595640235;6447;6451.806445806634;6500.959846424549;6644.667766611554;6661.700223490052;6662.500312476681;6663.067170596218;6694.096296999843;6285.465735412541;6471.223453185459;6472.676366181766;6475.190601174853;6480.675966201577 19:12:44 INFO - PID 85255 | |1;ext.html;796.7171654062807;819.1153554161506;847.5296210603115;1124.775044991002;1127.2729089737115;36634.45048324274;37364.62635373646;37468.250634987286;37505.81247093764;37552.37133400663;38960.610393896124;39321.01697457558;39345;39548;39550;7954.2841144297;8121.106678265401;8167.264946154844;8289.212524810133;8298.294644955186;8226.588670566478;8318.292945099643;8352.624131914079;8362.58187090646;8378.078411374743;8328.042275138376;8367;8378.497290162579;8388.580570971457;8391.664333426656;8309;8315;8334.12491688376;8345.207205315486;8358.205970432798;8092.919070809276;8139.796505087361;8146.592670366517;8156.021277446685;8165.346772258205;35970.92087237387;36135.63864361349;36136.63863361359;36185.81907090474;36249.81875090607;3435.5362026126404;3455.1707590178426;3479.7738147020364;3482.9825850870584;3483.7387195960405;29107.85446072756;29382;29524;29554;29617.55573666375;3028.197527655173;3053.114596766935;3067.4938635125177;3075.815451072943;3093.706097920694;232131.8393408039;235291;237310.8134459334;254673;255373;45806.62580122535;45861.541384585726;46061.30908036315;46089.76955115203;46175.07649846984;47697.76151119292;47742;47825.282620760714;47851;47873.521264786905;12226.449809758582;13929.094608850392;13954.58136255914;13956.860431395557;14053;11355.659330220107;11535.134864885167;11536.25014374063;11546.59586914454;11560.537578496765;13166.73666526664;13239.338033098307;13268.203907765565;13293.870021048318;13382.93308533451;4949;5845.123231515222;5850.795222167204;5892.085072351503;5927.407259274038;973.6056896956602;985.7781999050156;1023.4064242739194;1028.290479569095;1048.7902419516126;18795.530111747317;22066.558668826856;22506.437339066328;22863.19978800734;24154.79226038726;1084.6203828659907;1086.8369744538381;1123.601121601836;1130.1862658885589;1158.7682463507333;11381.54473821038;11561.706991069801;11711.180217384872;12344.938275308745;12359.567415140427;301919;304930;305464.94535054365;305762;306153;51754.70613234624;52090;52217.2167417489;52262;52277.90888364499;1481.1631428243008;1485.0644094220572;1517.2944580769995;1520.8098987626547;1531.4027529263374;22153.55692886101;22165.77834221637;22906.54186916286;23320.416989575395;23974;37108;37291.44062839114;37294.44058339073;37359;37386.43920341143;265191;265570.67214663804;266327;266861;266927;32139.35721285608;33161.50257746184;33274;33766.66233337635;33906.321873561894;41806.581934181486;42701.35947960845;43016;43023.56976430196;43172.70481885548;28820;28937.855310723313;29586;30139;30674.846625767623;28018.579721303788;28473.00344487933;30083.097507074825;30093.94671186498;30606.387872242874;35458.54145854146;35631;35665.28669426649;36563.451548226265;36737.26525469529;28867.5669864948;29011.419771604873;30471.08586742401;30694.539581905843;30906.381872361977;18060.90969545144;21640.783592163876;22506.887465562566;24020;24671.753282466947 19:12:44 INFO - PID 85255 | |2;jquery.html;408.6934798900824;430.5092194897815;432.7663061946549;444.75982969196633;459.12536617743194;21596;22209.444763880892;22391.440213994658;23232.303030909057;24061.51876962464;24120.517589648196;24503.509929801392;24775.628365574532;24862.378440538996;24878.253652390413;5995.640261584297;6088.726007329681;6117.877642447148;6123.142760013601;6146.108814221935;5850.73671684773;6126.479249263818;6158.199434073565;6161.260648722159;6179.876402471948;6064.9090263646085;6118.877622447549;6122;6135.846603834895;6140.8464788380425;6108.816735497942;6109.236345456818;6114.755409783604;6115.999999999978;6127.2953610334935;5456.454354564512;5459.416769136946;5462;5484.478974497456;5494.230807686927;28620.427591448053;29430.558541621893;29824.701752982626;29920.8503957481;30140.8492957536;2143.2712877621684;2174.967375489369;2177.880216588087;2186.7922547357975;2187.3109970359287;18922.62154756911;19525.9023704882;19537.120829562704;19558.70661940072;19580.706289405527;2128.3508529898377;2201.2405720026727;2208.757036725975;2223.3663405929233;2230.308604332662;154774;165748.17125914415;173585;195316;197867;13276.73446531064;13520.32398380077;13530.255835929118;13686.247256400993;13691.589252322447;17216.397426090025;17286.827131728773;17304.134793260288;17314.307427703003;17314.91342543279;7740.806479838049;8060.790881367841;9298.581563829644;9378.671746488977;9519.381240219363;8714.782130446789;9056.864147037799;9077.727668169966;9116.49859257734;9151.222146117654;8461.365397595206;8467;8539.530325832138;8544.957275213585;8570;1985.622731680976;2241.331263187208;2246.449619843149;2249.1902914950606;2421.455172586154;906.646407900906;909.7589138878203;946.8721722567503;999.1557134221571;1003.4527943719064;18989.525261868563;20318.28885988983;20532.58934821295;21909.233176838734;22287.554248915254;960.6637676813199;997.2969056814928;1037.725002874239;1037.896210378956;1061.2942393308363;10541.683749487527;10814.78370432587;10892.782144357068;11270.492827822767;11431;575568;576605;577408;577935;582973;146153;147320;148866.51133488744;149768;150687;996.8554559588964;1044.12815299226;1125.2292179856825;1180.15029178991;1184.603157942099;22897;24143.758562414852;24356.26931192067;24437.02251909904;24449.266522004367;63553;64598.677006614664;64741.55472120766;64927.67536162289;65294;89961;90134;90163.54918225629;90484.64273036041;90713.54643227006;10407.58369665205;10434.321769084985;10548.947255263674;10594.152467802403;10603.257771955889;18027.63944721072;18357.898526088477;18365.612470378765;18489.53776155607;18560.164792584095;12710.809337859755;14659.70680586361;14743.262836858328;14968.925155374154;15091.849081509044;12892.935535322264;13308.667283317995;13541.390637421142;13664.93167534156;13677.521286754916;15029.849701502844;15104.169270690218;15266.160361180242;15297.6175595611;15305;10336.121429678562;10368.274220804471;10457.215708821717;10457.581696732046;10505.68482945513;12163;14744.483943061941;14855.628609284855;14984;14996.850031499982 19:12:44 INFO - PID 85255 | |3;mootools.html;1231.6366671831809;1455.672473693419;1456.7669172932333;1466.97799533007;1469.933852976616;11727.941360293207;12118.939405302961;12237.204581702194;12265.509379624815;12462.688432789184;12015.459304331303;12096.274223546572;12210.572629957953;12246;12249.693757656063;2550.885210165547;2589.9870500647567;2593.949450472552;2595.195489398283;2600.622909678105;2526.381036646015;2568.2808813532242;2570.717221105677;2581.8321809082445;2581.8579978101197;2538.6445897574354;2543.5930251159816;2556.4248044190003;2560.0271896679237;2563.077292174825;2500.9374765630914;2521.4326776475236;2530.63305820655;2536.771690547841;2538.162406405891;2426.3085020768995;2510.686164229471;2514.1326242446326;2521.571332873416;2528.734482879308;11822.64532064031;11851.170418070738;11947.820782688266;11973.042156627449;11983.520659173622;6537.869242615168;6710.496712746563;6792.830179245509;6792.932070679329;6806.353396427331;4060.6345428911245;4253.3194688849635;4312.870613881589;4327.502337231227;4345.283028300327;1240.8076748104058;1247.1394737631006;1247.1830950727287;1271.319843883527;1271.9109662323642;15546.378144874192;15971.44099956496;16604.750928736077;16784.160791960476;16921.492355229362;5178.741062946913;5261.605379596545;5386.946130538645;5398.838034858961;5400.081986062379;12192.207506512048;12361.814572781415;12384.690382740324;12393.62819115428;12405.503779848885;5528.700755322496;6462.612243265419;6566.047923051131;6608.471322294205;6610.0745895574655;5996.940030599725;6144.5084393248435;6243.219597550306;6252.062190671436;6262.09199666055;3824.732268741217;3887.3391523441082;4248.150369926027;4437.689361744711;4461.126326942627;1081.3565928272665;1301.3493253373313;1304.6281809684194;1314.2180402660401;1321.2427471462931;816.5794615772763;817.8650522663694;833.3874602167402;847.1570787066908;872.232435456794;4907.975460122677;5484.451554844563;6386.968065159737;6772.762953296611;6936.375726184567;466.002754106213;601.4406601860225;605.2343785111836;606.9211002569638;626.0577830365271;3998.980005099956;4060.614241647039;4151.7924103794685;4247.44783178191;4648.767561621906;14951.551453456414;15208.935374523677;15358.308876100602;15511.767323490156;15531.5340539784;15180.392784288504;15306.38774449032;15408.691826163638;15413.614659633375;15435.15106669144;8256.711015114442;8562.315014798802;8708.477491350413;8765.605547750367;8788;6712.83217919556;7101.928980710127;7244.4566657501955;7579.886301705589;7608.581528016012;13138.014648901179;13166.934165329112;13275.867241327463;13509.121907076009;13572.389242483914;11965.341906195243;13487.595372138852;13564.457421703417;13690;13757.449702012207;8603.870941935751;9822.901770982198;9839.60641574329;9843.360181588174;9865.753356165866;10806.675799726021;10866.239363244496;10987;10995.065419439437;11025.558977641125;9307.953460232655;9311.44131352121;9317.950050988771;9385.249180065442;9419.340646154704;9160.587773550073;9168.954155229181;9173.174414302744;9207.447553146832;9211.585478653611;6830.521863469509;8079.474834135763;8386.580670966305;8432;8455.915440845758;6006.396704071171;6222.688865556795;6441.903371449338;6493.707783149864;6607.339266073354;6281.780137695158;6351.237851457855;6570.8028759137305;6728.461723062242;6760.391564759196 19:12:44 INFO - PID 85255 | |4;prototype.html;354.6985062696708;385.67410538095316;404.9915709882392;409.55563213912905;411.4918076175923;4833.613310935126;5988.401159884015;6364.649944253064;6484.610923344597;6486.61080335179;5508.834734957958;5642.166295953821;6153.692315384235;6332.435363677718;6371.968140159304;3864.574896761354;4688.484266730674;4693.20215563355;4695.225287827504;4699.624030077603;4094.2425651254466;4694.061187762444;4695.947634037869;4698.107359601665;4715.528447155291;4644.954885150831;4662.487126416108;4664.883377915562;4666.696664716783;4680.789364478606;3765.964359801063;4372.2785740352965;4603.286490593963;4638.536146385368;4641.976790116028;3497.265574229415;3542.3800834854055;3980.4626375439234;3984.382420724792;3991.44119823225;5256.474352564751;5413.188021796723;6286.402791734778;6312.905306420407;6316.431521163117;4637.397138372024;5100.0929470202;5535.529479994206;5549.19536667185;5567.777288908439;5454.34547854256;5855.473007429309;6825.55633883801;6865.828354291133;6866.759663411756;4197.286461301586;4503.504614492404;4546.795394207235;4559.133764584718;4560.954390456086;6619.834504137387;6890.896636550456;6907.274736152732;7156.92843071573;7340.486165968385;4600.35595016698;4639.9536004640195;4641.814327426932;4642.442906851132;4647.953520464752;4499.010217752089;4724.71651700899;4728.976355118202;4734.124187025412;4743.905121897543;5062.417821950449;5347.51872331492;5512.33851937762;5528.612997090246;5552.250446189793;6815.625140617214;6818.261273454665;6833.829154271084;6840.623765692835;6923.446124310043;1373.3957058894055;1597.6804639072232;1599.9920000399923;1602.2549514475825;1613.693398254328;1009.9798004040025;1034.2294990232326;1066.973325666849;1073.2272763610188;1074.2963358999868;6284.937150628435;6575.473962083021;6590.242122155918;6592.901106483407;6614.338566143443;5656.614129538207;6695.296993815677;7126.893096603555;7162.534435261691;7190.245024272481;5246.3704355477585;5839.649621022752;6191.845203869849;6537.803865884031;6576.8819300719;3367.5921715973195;3554.7156227501737;3708.2398108387715;3718.3492888744363;3738.1776009277914;6919.619420931897;6936.826579335456;7166.211716711157;7353.558786472829;7375.557466552024;6330.765500727314;6545.574537655132;7340.192578816326;7344.025669603819;7352.63236838156;6892.448604111759;6993.440524757905;7103.92896071043;7254.383377412873;7273.709051637981;5584.553235741212;6208.534359923115;6860.451163906975;7093;7163.820904477429;5274.76263568148;6902.895536714102;6902.964555316743;6993.4754893384215;7038.826462550124;6401.42387185137;6690.83272918181;6799.286074962058;7167.749128780468;7388.556686598821;4255.6169944705125;4907.214845624683;5088.949110508847;5162.922556161736;5204.505571970657;6927.861442771015;7093.503454758324;7123.287671232835;7139.32176443237;7162.677679504539;5492.945070549244;6697.564658297195;6791.505868708876;6793.932060679527;6802.8639427212165;6454.967205247138;6796;6798.286179951034;6821.420179284816;6847.383735463832;5529.502344788988;6002.579819412599;6613.768518101843;6671.5997040177745;6687.933120668926;6795.422389096784;6811.7275308989065;6829.897551536632;6832.487563432862;6848.417884479875;5772.336181339199;6094.20775299226;6130.233720784902;6630.900536492053;6634.834129146617 19:12:44 INFO - PID 85255 | |5;yui.html;672.9663516824161;836.3727204596553;847.7795773098993;853.2619284319062;865.9307255419567;14739.705205895876;15267.618309542268;16971;17451.73822392665;17463;17662.116894155304;17924.28302867887;18232.088395580235;18316.725249121242;18417.631647367078;970.7961328121086;980.6616717232544;1008.2135933971514;1008.2236677758121;1009.2481101579334;939.0281059103829;1031.6234574380335;1045.5974449836792;1046.5290619221344;1046.6179844356832;1077.3966578715906;1077.520503375998;1078.5901357484145;1078.7734575739105;1080.9729756756064;1040.8334666453406;1058.1640504001907;1064.0157853985072;1072.3512275073547;1075.5213929801203;785.8192615698422;803.5941849366092;804.3203493048364;807.8303556253194;814.2549567146054;16588.58528536784;17951.461456156205;18097.728534072;18121.63756724858;18339;17763.555911102198;17802.287908483646;17876.821231787646;18262.0868956553;18276.634467310578;14728;14902.403903843833;14975.775363369557;14976.47582334624;15010.774838377432;3571.357155711971;3616.710663146942;3646.762960407565;3689.262147570497;3740.4015357542935;125837;150547;170521.18481451643;170894.1455292728;171650;13749.518766843112;13854.238016909167;14012.509562165274;14150.575482735534;14196.219207943455;26563.01554766762;26969.325766855985;27000.189994300203;27061.323466913484;27064.05275815337;6658.700358483879;6933.618650974144;7996.840852969665;8028.43800933929;8118.391120665974;3982;4045.8179381927885;4065.0243941453837;4067.877963661095;4072.1041370898356;4190.706650534495;4245.172191422643;4259.424977628042;4298.312270036779;4354;919.1406035356963;923.5797712041006;937.4141161773891;938.2775263047415;943.2736792669606;644.3170239546096;650.1612919334007;662.3244290823333;671.332024635481;677.7526202935893;9650.95174524123;10386.324888882198;12391.814122788166;12649.367531623382;12842.893389628603;636.726207730671;639.4404895716249;652.103357882911;656.2092678322609;672.8284287506655;5681.659100453986;5707.600467967202;5761.88476230473;5841.328247251536;5876.735546900314;143932.35643722527;144072;144783;145809;148533.25733371265;130969;133783.65550353986;137257;139267;139726.30136849455;113560;115617.59441650292;117152.8284717142;118160;118576;9817.03659268135;10331;10622.309549879234;11091.722706932069;11733.706657333634;33475.665243347255;47320.763396182796;47723.761381192875;48750;49606.50393496019;42116.578834211265;61178;61287.69356153191;61382.69308653428;61548;14331.355089021228;14469.638259043608;14725.116493010453;14797.852021479648;14809.111453312837;16012.67974640524;16208.67582648364;16487.258073386485;16619.584510387336;16641;14952.775708364166;14991;15300.005499642488;15471.922640386727;15486.845131548991;14759.483418080315;14778;15107.54677359681;15164.317605707549;15228.619284517976;14720.263986800403;14847.33187006609;15071.718903893292;15154.166520841018;15168.165750883365;10312.896871031193;10472.738181545523;11308.378039207922;11574.18980671345;11640.068794496588;11955.880441195477;12370.938145309216;14123.2938353084;14581;14598.854011459749 19:12:44 INFO - PID 85255 | __end_tp_report 19:12:44 INFO - PID 85255 | __start_cc_report 19:12:44 INFO - PID 85255 | _x_x_mozilla_cycle_collect,455 19:12:44 INFO - PID 85255 | __end_cc_report 19:12:44 INFO - PID 85255 | __startTimestamp1496715164404__endTimestamp 19:12:44 INFO - PID 85255 | 19:12:44 INFO - PID 85255 | ------- Summary: start ------- 19:12:44 INFO - PID 85255 | Number of tests: 6 19:12:44 INFO - PID 85255 | 19:12:44 INFO - PID 85255 | [#0] dojo.html Cycles:165 Average:6142.53 Median:6411.46 stddev:818.81 (12.8%) stddev-sans-first:783.52 19:12:44 INFO - PID 85255 | Values: 3007.5 3029.7 3592.1 3652.1 3663.5 4602.5 4762.5 6112.1 6241.5 6297.1 4475.4 4752.9 6007.4 6104.6 6125.5 5435.9 5488.8 5534.8 5548.0 5588.7 5060.3 5556.1 5606.7 5629.9 5631.1 5576.8 5612.0 5631.1 5647.5 5653.6 5375.2 5443.7 5503.9 5512.4 5520.6 5421.5 5428.9 5496.3 5501.7 5507.4 6193.1 6225.2 6257.9 6269.3 6273.1 6532.8 6589.7 6732.9 6744.0 6770.3 6788.2 6872.5 6887.1 6890.3 6904.6 6345.5 6361.2 6361.3 6364.6 6368.8 6692.4 7016.8 7046.3 7054.5 7081.5 6483.8 6519.3 6628.8 6665.3 6668.0 6518.5 6707.5 6780.4 6834.9 6842.1 6696.1 6723.0 6726.3 6743.5 6753.7 6735.5 6757.1 6764.8 6769.5 6769.5 4416.4 4523.6 4615.7 5279.4 5346.5 4766.5 4836.3 4855.3 4887.3 4899.4 4999.6 5506.8 6266.7 6351.4 6396.1 6670.3 6713.3 6841.5 6844.0 6879.6 6266.1 6288.5 6326.4 6341.8 6362.5 6003.5 6008.5 6033.4 6045.8 6070.9 6671.5 7019.3 7043.1 7059.3 7072.7 5722.9 6700.1 6761.3 6821.6 6865.2 5572.5 5632.8 6844.8 6973.8 7007.8 6715.5 6719.2 6730.2 6734.9 6761.8 5635.7 6607.2 6744.3 6815.6 6889.1 6649.5 6651.9 6667.7 6672.3 6689.5 6411.5 6432.8 6619.9 6630.8 6653.0 6316.8 6394.3 6447.0 6451.8 6501.0 6644.7 6661.7 6662.5 6663.1 6694.1 6285.5 6471.2 6472.7 6475.2 6480.7 19:12:44 INFO - PID 85255 | 19:12:44 INFO - PID 85255 | [#1] ext.html Cycles:180 Average:42112.79 Median:32650.43 stddev:71322.75 (218.4%) stddev-sans-first:71455.37 19:12:44 INFO - PID 85255 | Values: 796.7 819.1 847.5 1124.8 1127.3 36634.5 37364.6 37468.3 37505.8 37552.4 38960.6 39321.0 39345.0 39548.0 39550.0 7954.3 8121.1 8167.3 8289.2 8298.3 8226.6 8318.3 8352.6 8362.6 8378.1 8328.0 8367.0 8378.5 8388.6 8391.7 8309.0 8315.0 8334.1 8345.2 8358.2 8092.9 8139.8 8146.6 8156.0 8165.3 35970.9 36135.6 36136.6 36185.8 36249.8 3435.5 3455.2 3479.8 3483.0 3483.7 29107.9 29382.0 29524.0 29554.0 29617.6 3028.2 3053.1 3067.5 3075.8 3093.7 232131.8 235291.0 237310.8 254673.0 255373.0 45806.6 45861.5 46061.3 46089.8 46175.1 47697.8 47742.0 47825.3 47851.0 47873.5 12226.4 13929.1 13954.6 13956.9 14053.0 11355.7 11535.1 11536.3 11546.6 11560.5 13166.7 13239.3 13268.2 13293.9 13382.9 4949.0 5845.1 5850.8 5892.1 5927.4 973.6 985.8 1023.4 1028.3 1048.8 18795.5 22066.6 22506.4 22863.2 24154.8 1084.6 1086.8 1123.6 1130.2 1158.8 11381.5 11561.7 11711.2 12344.9 12359.6 301919.0 304930.0 305464.9 305762.0 306153.0 51754.7 52090.0 52217.2 52262.0 52277.9 1481.2 1485.1 1517.3 1520.8 1531.4 22153.6 22165.8 22906.5 23320.4 23974.0 37108.0 37291.4 37294.4 37359.0 37386.4 265191.0 265570.7 266327.0 266861.0 266927.0 32139.4 33161.5 33274.0 33766.7 33906.3 41806.6 42701.4 43016.0 43023.6 43172.7 28820.0 28937.9 29586.0 30139.0 30674.8 28018.6 28473.0 30083.1 30093.9 30606.4 35458.5 35631.0 35665.3 36563.5 36737.3 28867.6 29011.4 30471.1 30694.5 30906.4 18060.9 21640.8 22506.9 24020.0 24671.8 19:12:44 INFO - PID 85255 | 19:12:44 INFO - PID 85255 | [#2] jquery.html Cycles:180 Average:39030.60 Median:12310.87 stddev:99065.46 (804.7%) stddev-sans-first:99300.92 19:12:44 INFO - PID 85255 | Values: 408.7 430.5 432.8 444.8 459.1 21596.0 22209.4 22391.4 23232.3 24061.5 24120.5 24503.5 24775.6 24862.4 24878.3 5995.6 6088.7 6117.9 6123.1 6146.1 5850.7 6126.5 6158.2 6161.3 6179.9 6064.9 6118.9 6122.0 6135.8 6140.8 6108.8 6109.2 6114.8 6116.0 6127.3 5456.5 5459.4 5462.0 5484.5 5494.2 28620.4 29430.6 29824.7 29920.9 30140.8 2143.3 2175.0 2177.9 2186.8 2187.3 18922.6 19525.9 19537.1 19558.7 19580.7 2128.4 2201.2 2208.8 2223.4 2230.3 154774.0 165748.2 173585.0 195316.0 197867.0 13276.7 13520.3 13530.3 13686.2 13691.6 17216.4 17286.8 17304.1 17314.3 17314.9 7740.8 8060.8 9298.6 9378.7 9519.4 8714.8 9056.9 9077.7 9116.5 9151.2 8461.4 8467.0 8539.5 8545.0 8570.0 1985.6 2241.3 2246.4 2249.2 2421.5 906.6 909.8 946.9 999.2 1003.5 18989.5 20318.3 20532.6 21909.2 22287.6 960.7 997.3 1037.7 1037.9 1061.3 10541.7 10814.8 10892.8 11270.5 11431.0 575568.0 576605.0 577408.0 577935.0 582973.0 146153.0 147320.0 148866.5 149768.0 150687.0 996.9 1044.1 1125.2 1180.2 1184.6 22897.0 24143.8 24356.3 24437.0 24449.3 63553.0 64598.7 64741.6 64927.7 65294.0 89961.0 90134.0 90163.5 90484.6 90713.5 10407.6 10434.3 10548.9 10594.2 10603.3 18027.6 18357.9 18365.6 18489.5 18560.2 12710.8 14659.7 14743.3 14968.9 15091.8 12892.9 13308.7 13541.4 13664.9 13677.5 15029.8 15104.2 15266.2 15297.6 15305.0 10336.1 10368.3 10457.2 10457.6 10505.7 12163.0 14744.5 14855.6 14984.0 14996.9 19:12:44 INFO - PID 85255 | 19:12:44 INFO - PID 85255 | [#3] mootools.html Cycles:180 Average:7209.04 Median:3856.04 stddev:4588.63 (119.0%) stddev-sans-first:4579.52 19:12:44 INFO - PID 85255 | Values: 1231.6 1455.7 1456.8 1467.0 1469.9 11727.9 12118.9 12237.2 12265.5 12462.7 12015.5 12096.3 12210.6 12246.0 12249.7 2550.9 2590.0 2593.9 2595.2 2600.6 2526.4 2568.3 2570.7 2581.8 2581.9 2538.6 2543.6 2556.4 2560.0 2563.1 2500.9 2521.4 2530.6 2536.8 2538.2 2426.3 2510.7 2514.1 2521.6 2528.7 11822.6 11851.2 11947.8 11973.0 11983.5 6537.9 6710.5 6792.8 6792.9 6806.4 4060.6 4253.3 4312.9 4327.5 4345.3 1240.8 1247.1 1247.2 1271.3 1271.9 15546.4 15971.4 16604.8 16784.2 16921.5 5178.7 5261.6 5386.9 5398.8 5400.1 12192.2 12361.8 12384.7 12393.6 12405.5 5528.7 6462.6 6566.0 6608.5 6610.1 5996.9 6144.5 6243.2 6252.1 6262.1 3824.7 3887.3 4248.2 4437.7 4461.1 1081.4 1301.3 1304.6 1314.2 1321.2 816.6 817.9 833.4 847.2 872.2 4908.0 5484.5 6387.0 6772.8 6936.4 466.0 601.4 605.2 606.9 626.1 3999.0 4060.6 4151.8 4247.4 4648.8 14951.6 15208.9 15358.3 15511.8 15531.5 15180.4 15306.4 15408.7 15413.6 15435.2 8256.7 8562.3 8708.5 8765.6 8788.0 6712.8 7101.9 7244.5 7579.9 7608.6 13138.0 13166.9 13275.9 13509.1 13572.4 11965.3 13487.6 13564.5 13690.0 13757.4 8603.9 9822.9 9839.6 9843.4 9865.8 10806.7 10866.2 10987.0 10995.1 11025.6 9308.0 9311.4 9318.0 9385.2 9419.3 9160.6 9169.0 9173.2 9207.4 9211.6 6830.5 8079.5 8386.6 8432.0 8455.9 6006.4 6222.7 6441.9 6493.7 6607.3 6281.8 6351.2 6570.8 6728.5 6760.4 19:12:44 INFO - PID 85255 | 19:12:44 INFO - PID 85255 | [#4] prototype.html Cycles:180 Average:5460.51 Median:5995.49 stddev:1728.96 (28.8%) stddev-sans-first:1690.80 19:12:44 INFO - PID 85255 | Values: 354.7 385.7 405.0 409.6 411.5 4833.6 5988.4 6364.6 6484.6 6486.6 5508.8 5642.2 6153.7 6332.4 6372.0 3864.6 4688.5 4693.2 4695.2 4699.6 4094.2 4694.1 4695.9 4698.1 4715.5 4645.0 4662.5 4664.9 4666.7 4680.8 3766.0 4372.3 4603.3 4638.5 4642.0 3497.3 3542.4 3980.5 3984.4 3991.4 5256.5 5413.2 6286.4 6312.9 6316.4 4637.4 5100.1 5535.5 5549.2 5567.8 5454.3 5855.5 6825.6 6865.8 6866.8 4197.3 4503.5 4546.8 4559.1 4561.0 6619.8 6890.9 6907.3 7156.9 7340.5 4600.4 4640.0 4641.8 4642.4 4648.0 4499.0 4724.7 4729.0 4734.1 4743.9 5062.4 5347.5 5512.3 5528.6 5552.3 6815.6 6818.3 6833.8 6840.6 6923.4 1373.4 1597.7 1600.0 1602.3 1613.7 1010.0 1034.2 1067.0 1073.2 1074.3 6284.9 6575.5 6590.2 6592.9 6614.3 5656.6 6695.3 7126.9 7162.5 7190.2 5246.4 5839.6 6191.8 6537.8 6576.9 3367.6 3554.7 3708.2 3718.3 3738.2 6919.6 6936.8 7166.2 7353.6 7375.6 6330.8 6545.6 7340.2 7344.0 7352.6 6892.4 6993.4 7103.9 7254.4 7273.7 5584.6 6208.5 6860.5 7093.0 7163.8 5274.8 6902.9 6903.0 6993.5 7038.8 6401.4 6690.8 6799.3 7167.7 7388.6 4255.6 4907.2 5088.9 5162.9 5204.5 6927.9 7093.5 7123.3 7139.3 7162.7 5492.9 6697.6 6791.5 6793.9 6802.9 6455.0 6796.0 6798.3 6821.4 6847.4 5529.5 6002.6 6613.8 6671.6 6687.9 6795.4 6811.7 6829.9 6832.5 6848.4 5772.3 6094.2 6130.2 6630.9 6634.8 19:12:44 INFO - PID 85255 | 19:12:44 INFO - PID 85255 | [#5] yui.html Cycles:180 Average:26156.09 Median:93746.09 stddev:42165.12 (45.0%) stddev-sans-first:42239.99 19:12:44 INFO - PID 85255 | Values: 673.0 836.4 847.8 853.3 865.9 14739.7 15267.6 16971.0 17451.7 17463.0 17662.1 17924.3 18232.1 18316.7 18417.6 970.8 980.7 1008.2 1008.2 1009.2 939.0 1031.6 1045.6 1046.5 1046.6 1077.4 1077.5 1078.6 1078.8 1081.0 1040.8 1058.2 1064.0 1072.4 1075.5 785.8 803.6 804.3 807.8 814.3 16588.6 17951.5 18097.7 18121.6 18339.0 17763.6 17802.3 17876.8 18262.1 18276.6 14728.0 14902.4 14975.8 14976.5 15010.8 3571.4 3616.7 3646.8 3689.3 3740.4 125837.0 150547.0 170521.2 170894.1 171650.0 13749.5 13854.2 14012.5 14150.6 14196.2 26563.0 26969.3 27000.2 27061.3 27064.1 6658.7 6933.6 7996.8 8028.4 8118.4 3982.0 4045.8 4065.0 4067.9 4072.1 4190.7 4245.2 4259.4 4298.3 4354.0 919.1 923.6 937.4 938.3 943.3 644.3 650.2 662.3 671.3 677.8 9651.0 10386.3 12391.8 12649.4 12842.9 636.7 639.4 652.1 656.2 672.8 5681.7 5707.6 5761.9 5841.3 5876.7 143932.4 144072.0 144783.0 145809.0 148533.3 130969.0 133783.7 137257.0 139267.0 139726.3 113560.0 115617.6 117152.8 118160.0 118576.0 9817.0 10331.0 10622.3 11091.7 11733.7 33475.7 47320.8 47723.8 48750.0 49606.5 42116.6 61178.0 61287.7 61382.7 61548.0 14331.4 14469.6 14725.1 14797.9 14809.1 16012.7 16208.7 16487.3 16619.6 16641.0 14952.8 14991.0 15300.0 15471.9 15486.8 14759.5 14778.0 15107.5 15164.3 15228.6 14720.3 14847.3 15071.7 15154.2 15168.2 10312.9 10472.7 11308.4 11574.2 11640.1 11955.9 12370.9 14123.3 14581.0 14598.9 19:12:44 INFO - PID 85255 | -------- Summary: end -------- 19:12:44 INFO - PID 85255 | 19:12:45 INFO - TEST-INFO | 85255: exit 0 19:12:45 INFO - TEST-OK | dromaeo_css | took 1142825ms 19:12:45 INFO - TEST-START | kraken 19:12:45 INFO - operating with platform_type : mac_ 19:12:45 INFO - Initialising browser for kraken test... 19:12:45 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49286/getInfo.html -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpQMbb5C/profile 19:12:45 INFO - TEST-INFO | started process 85261 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49286/getInfo.html -foreground) 19:13:18 INFO - TEST-INFO | 85261: killed by SIGTERM 19:13:18 INFO - Browser initialized. 19:13:18 INFO - Running cycle 1/1 for kraken test... 19:13:18 INFO - TEST-INFO | started process 85265 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/T/tmpQMbb5C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:13:20 INFO - PID 85265 | 2017-06-05 19:13:20.800 plugin-container[85266:717815] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b43, name = 'com.apple.tsm.portname' 19:13:20 INFO - PID 85265 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:13:20 INFO - PID 85265 | 2017-06-05 19:13:20.800 plugin-container[85266:717815] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa00b, name = 'com.apple.CFPasteboardClient' 19:13:20 INFO - PID 85265 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:13:20 INFO - PID 85265 | 2017-06-05 19:13:20.800 plugin-container[85266:717815] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:13:21 INFO - PID 85265 | 2017-06-05 19:13:21.577 plugin-container[85267:717860] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 19:13:21 INFO - PID 85265 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:13:21 INFO - PID 85265 | 2017-06-05 19:13:21.577 plugin-container[85267:717860] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4817, name = 'com.apple.CFPasteboardClient' 19:13:21 INFO - PID 85265 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:13:21 INFO - PID 85265 | 2017-06-05 19:13:21.577 plugin-container[85267:717860] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:13:30 INFO - PID 85265 | RSS: Main: 259837952 19:13:30 INFO - PID 85265 | 19:13:33 INFO - PID 85265 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49286/tests/kraken/driver.html?testName=audio-beat-detection) 19:13:34 INFO - PID 85265 | RSS: Main: 270557184 19:13:34 INFO - PID 85265 | 19:13:37 INFO - PID 85265 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49286/tests/kraken/driver.html?testName=audio-dft) 19:13:37 INFO - PID 85265 | RSS: Main: 279396352 19:13:37 INFO - PID 85265 | 19:13:41 INFO - PID 85265 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49286/tests/kraken/driver.html?testName=audio-fft) 19:13:41 INFO - PID 85265 | RSS: Main: 282513408 19:13:41 INFO - PID 85265 | 19:13:43 INFO - PID 85265 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49286/tests/kraken/driver.html?testName=audio-oscillator) 19:13:44 INFO - PID 85265 | RSS: Main: 283365376 19:13:44 INFO - PID 85265 | 19:13:46 INFO - PID 85265 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49286/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:13:46 INFO - PID 85265 | RSS: Main: 285065216 19:13:46 INFO - PID 85265 | 19:13:50 INFO - PID 85265 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49286/tests/kraken/driver.html?testName=imaging-darkroom) 19:13:50 INFO - PID 85265 | RSS: Main: 290574336 19:13:50 INFO - PID 85265 | 19:13:53 INFO - PID 85265 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49286/tests/kraken/driver.html?testName=imaging-desaturate) 19:13:54 INFO - PID 85265 | RSS: Main: 294076416 19:13:54 INFO - PID 85265 | 19:13:57 INFO - PID 85265 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49286/tests/kraken/driver.html?testName=json-parse-financial) 19:13:58 INFO - PID 85265 | RSS: Main: 292888576 19:13:58 INFO - PID 85265 | 19:13:59 INFO - PID 85265 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49286/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:14:00 INFO - PID 85265 | RSS: Main: 292704256 19:14:00 INFO - PID 85265 | 19:14:02 INFO - PID 85265 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:14:03 INFO - PID 85265 | RSS: Main: 292503552 19:14:03 INFO - PID 85265 | 19:14:05 INFO - PID 85265 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:14:06 INFO - PID 85265 | RSS: Main: 292196352 19:14:06 INFO - PID 85265 | 19:14:08 INFO - PID 85265 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:14:09 INFO - PID 85265 | RSS: Main: 292597760 19:14:09 INFO - PID 85265 | 19:14:12 INFO - PID 85265 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:14:13 INFO - PID 85265 | RSS: Main: 292360192 19:14:13 INFO - PID 85265 | 19:14:14 INFO - PID 85265 | Cycle 1(1): loaded http://localhost:49286/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49286/tests/kraken/driver.html?testName=ai-astar) 19:14:15 INFO - PID 85265 | RSS: Main: 294248448 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | __start_tp_report 19:14:15 INFO - PID 85265 | _x_x_mozilla_page_load 19:14:15 INFO - PID 85265 | _x_x_mozilla_page_load_details 19:14:15 INFO - PID 85265 | |i|pagename|runs| 19:14:15 INFO - PID 85265 | |0;ai-astar;103;116;114;93;165;170;168;114;114;111 19:14:15 INFO - PID 85265 | |1;audio-beat-detection;130;109;111;154;112;115;93;139;160;107 19:14:15 INFO - PID 85265 | |2;audio-dft;200;200;202;221;208;218;230;197;197;198 19:14:15 INFO - PID 85265 | |3;audio-fft;69;58;59;93;82;88;86;57;77;58 19:14:15 INFO - PID 85265 | |4;audio-oscillator;74;67;83;69;69;69;87;73;70;92 19:14:15 INFO - PID 85265 | |5;imaging-gaussian-blur;88;89;87;88;101;82;135;122;91;87 19:14:15 INFO - PID 85265 | |6;imaging-darkroom;97;135;120;97;96;95;97;94;95;97 19:14:15 INFO - PID 85265 | |7;imaging-desaturate;114;87;139;145;138;149;91;127;138;92 19:14:15 INFO - PID 85265 | |8;json-parse-financial;50;48;53;52;48;51;67;46;44;67 19:14:15 INFO - PID 85265 | |9;json-stringify-tinderbox;55;57;60;59;59;61;55;55;58;56 19:14:15 INFO - PID 85265 | |10;stanford-crypto-aes;86;85;82;86;87;94;114;99;114;81 19:14:15 INFO - PID 85265 | |11;stanford-crypto-ccm;139;136;156;158;158;135;137;142;137;142 19:14:15 INFO - PID 85265 | |12;stanford-crypto-pbkdf2;168;213;233;178;180;187;186;187;183;185 19:14:15 INFO - PID 85265 | |13;stanford-crypto-sha256-iterative;69;66;68;70;74;79;99;102;88;70 19:14:15 INFO - PID 85265 | __end_tp_report 19:14:15 INFO - PID 85265 | __start_cc_report 19:14:15 INFO - PID 85265 | _x_x_mozilla_cycle_collect,1191 19:14:15 INFO - PID 85265 | __end_cc_report 19:14:15 INFO - PID 85265 | __startTimestamp1496715255247__endTimestamp 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | ------- Summary: start ------- 19:14:15 INFO - PID 85265 | Number of tests: 14 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | [#0] ai-astar Cycles:10 Average:126.80 Median:140.50 stddev:29.04 (20.7%) stddev-sans-first:29.50 19:14:15 INFO - PID 85265 | Values: 103.0 116.0 114.0 93.0 165.0 170.0 168.0 114.0 114.0 111.0 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | [#1] audio-beat-detection Cycles:10 Average:123.00 Median:134.50 stddev:21.90 (16.3%) stddev-sans-first:23.08 19:14:15 INFO - PID 85265 | Values: 130.0 109.0 111.0 154.0 112.0 115.0 93.0 139.0 160.0 107.0 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | [#2] audio-dft Cycles:10 Average:207.10 Median:205.00 stddev:11.79 (5.8%) stddev-sans-first:12.22 19:14:15 INFO - PID 85265 | Values: 200.0 200.0 202.0 221.0 208.0 218.0 230.0 197.0 197.0 198.0 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | [#3] audio-fft Cycles:10 Average:72.70 Median:79.50 stddev:14.17 (17.8%) stddev-sans-first:14.97 19:14:15 INFO - PID 85265 | Values: 69.0 58.0 59.0 93.0 82.0 88.0 86.0 57.0 77.0 58.0 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | [#4] audio-oscillator Cycles:10 Average:75.30 Median:73.50 stddev:8.81 (12.0%) stddev-sans-first:9.33 19:14:15 INFO - PID 85265 | Values: 74.0 67.0 83.0 69.0 69.0 69.0 87.0 73.0 70.0 92.0 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | [#5] imaging-gaussian-blur Cycles:10 Average:97.00 Median:87.50 stddev:17.55 (20.1%) stddev-sans-first:18.31 19:14:15 INFO - PID 85265 | Values: 88.0 89.0 87.0 88.0 101.0 82.0 135.0 122.0 91.0 87.0 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | [#6] imaging-darkroom Cycles:10 Average:102.30 Median:96.50 stddev:13.78 (14.3%) stddev-sans-first:14.49 19:14:15 INFO - PID 85265 | Values: 97.0 135.0 120.0 97.0 96.0 95.0 97.0 94.0 95.0 97.0 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | [#7] imaging-desaturate Cycles:10 Average:122.00 Median:147.00 stddev:24.12 (16.4%) stddev-sans-first:25.40 19:14:15 INFO - PID 85265 | Values: 114.0 87.0 139.0 145.0 138.0 149.0 91.0 127.0 138.0 92.0 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:51.50 stddev:8.06 (15.6%) stddev-sans-first:8.49 19:14:15 INFO - PID 85265 | Values: 50.0 48.0 53.0 52.0 48.0 51.0 67.0 46.0 44.0 67.0 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | [#9] json-stringify-tinderbox Cycles:10 Average:57.50 Median:58.50 stddev:2.22 (3.8%) stddev-sans-first:2.17 19:14:15 INFO - PID 85265 | Values: 55.0 57.0 60.0 59.0 59.0 61.0 55.0 55.0 58.0 56.0 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | [#10] stanford-crypto-aes Cycles:10 Average:92.80 Median:86.00 stddev:12.39 (14.4%) stddev-sans-first:12.89 19:14:15 INFO - PID 85265 | Values: 86.0 85.0 82.0 86.0 87.0 94.0 114.0 99.0 114.0 81.0 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | [#11] stanford-crypto-ccm Cycles:10 Average:144.00 Median:142.00 stddev:9.50 (6.7%) stddev-sans-first:9.90 19:14:15 INFO - PID 85265 | Values: 139.0 136.0 156.0 158.0 158.0 135.0 137.0 142.0 137.0 142.0 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:190.00 Median:186.50 stddev:18.90 (10.1%) stddev-sans-first:18.29 19:14:15 INFO - PID 85265 | Values: 168.0 213.0 233.0 178.0 180.0 187.0 186.0 187.0 183.0 185.0 19:14:15 INFO - PID 85265 | 19:14:15 INFO - PID 85265 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.50 Median:72.00 stddev:13.27 (18.4%) stddev-sans-first:13.62 19:14:15 INFO - PID 85265 | Values: 69.0 66.0 68.0 70.0 74.0 79.0 99.0 102.0 88.0 70.0 19:14:15 INFO - PID 85265 | -------- Summary: end -------- 19:14:15 INFO - PID 85265 | 19:14:16 INFO - TEST-INFO | 85265: exit 0 19:14:16 INFO - TEST-OK | kraken | took 91000ms 19:14:16 INFO - SUITE-END | took 1233s 19:14:16 INFO - Completed test suite (00:20:34) 19:14:16 INFO - javascript benchmark 19:14:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8033.268567407103, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3007.488726916424, 3029.651590067143, 3592.1019745063736, 3652.1052342176154, 3663.5054267673863, 4602.493725690171, 4762.499937506564, 6112.144299798032, 6241.469475094616, 6297.149884765556, 4475.373447717322, 4752.859313764698, 6007.429294217053, 6104.633721976674, 6125.479643195305, 5435.945640543583, 5488.835334939949, 5534.75093620788, 5547.972260138694, 5588.720563971806, 5060.266261392096, 5556.083246264361, 5606.719664016803, 5629.8592535186535, 5631.1271752878365, 5576.832695019136, 5612.0, 5631.0708733059, 5647.463490968352, 5653.604247702642, 5375.193720941851, 5443.673379597237, 5503.9174412383845, 5512.4211957744665, 5520.558355331564, 5421.539169170625, 5428.9185662215095, 5496.340439147288, 5501.697406642633, 5507.366652834895, 6193.1329613854095, 6225.221847269091, 6257.937420625826, 6269.310375858652, 6273.121762953145, 6532.804015879531, 6589.670516474157, 6732.899006514906, 6743.966280168617, 6770.32296770324, 6788.185417749902, 6872.450203983669, 6887.139107611549, 6890.276520965276, 6904.585724856524, 6345.492360611186, 6361.236651601745, 6361.300256971683, 6364.586301890362, 6368.808935731936, 6692.364225398676, 7016.772064888624, 7046.330598593126, 7054.506184567031, 7081.5042946994245, 6483.7730679425995, 6519.348065193538, 6628.83427914306, 6665.266820649725, 6667.966660166668, 6518.511111666644, 6707.49693772969, 6780.38976492118, 6834.931650683529, 6842.144731908512, 6696.0960270362875, 6722.9663851680425, 6726.260111387744, 6743.527953043338, 6753.662316884136, 6735.49483788708, 6757.087793147961, 6764.763233286812, 6769.492288078413, 6769.526133170631, 4416.4258646375765, 4523.638108951276, 4615.653825963066, 5279.366476022825, 5346.518813306821, 4766.523347665206, 4836.298736683161, 4855.295982082578, 4887.29134275528, 4899.412070551557, 4999.625028122905, 5506.834794956158, 6266.686665666698, 6351.396617321347, 6396.136521569621, 6670.332966703388, 6713.26154123046, 6841.521093523505, 6844.041834143224, 6879.552829066095, 6266.122742816009, 6288.52836037299, 6326.367363263729, 6341.8097457076365, 6362.491000719931, 6003.489703375175, 6008.459238668541, 6033.3664965178905, 6045.818625441244, 6070.908936365958, 6671.46628269747, 7019.333163349474, 7043.084399028094, 7059.258777828357, 7072.71709131629, 5722.856928576819, 6700.095487109373, 6761.290064543349, 6821.624810635462, 6865.244823069558, 5572.526335261548, 5632.831015069554, 6844.828879278058, 6973.79078627642, 7007.789766306815, 6715.4627629790475, 6719.160104986877, 6730.226024007302, 6734.9326506734305, 6761.763338283136, 5635.718214089361, 6607.207135143622, 6744.325567443217, 6815.556988795713, 6889.104416425833, 6649.534532582673, 6651.866962660622, 6667.733290668319, 6672.266050734513, 6689.46484281266, 6411.455026322815, 6432.774852880127, 6619.867602648016, 6630.83422914431, 6653.0, 6316.810495685137, 6394.2646595640235, 6447.0, 6451.806445806634, 6500.959846424549, 6644.667766611554, 6661.700223490052, 6662.500312476681, 6663.067170596218, 6694.096296999843, 6285.465735412541, 6471.223453185459, 6472.676366181766, 6475.190601174853, 6480.675966201577], "value": 6085.509330115763, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [796.7171654062807, 819.1153554161506, 847.5296210603115, 1124.775044991002, 1127.2729089737115, 36634.45048324274, 37364.62635373646, 37468.250634987286, 37505.81247093764, 37552.37133400663, 38960.610393896124, 39321.01697457558, 39345.0, 39548.0, 39550.0, 7954.2841144297, 8121.106678265401, 8167.264946154844, 8289.212524810133, 8298.294644955186, 8226.588670566478, 8318.292945099643, 8352.624131914079, 8362.58187090646, 8378.078411374743, 8328.042275138376, 8367.0, 8378.497290162579, 8388.580570971457, 8391.664333426656, 8309.0, 8315.0, 8334.12491688376, 8345.207205315486, 8358.205970432798, 8092.919070809276, 8139.796505087361, 8146.592670366517, 8156.021277446685, 8165.346772258205, 35970.92087237387, 36135.63864361349, 36136.63863361359, 36185.81907090474, 36249.81875090607, 3435.5362026126404, 3455.1707590178426, 3479.7738147020364, 3482.9825850870584, 3483.7387195960405, 29107.85446072756, 29382.0, 29524.0, 29554.0, 29617.55573666375, 3028.197527655173, 3053.114596766935, 3067.4938635125177, 3075.815451072943, 3093.706097920694, 232131.8393408039, 235291.0, 237310.8134459334, 254673.0, 255373.0, 45806.62580122535, 45861.541384585726, 46061.30908036315, 46089.76955115203, 46175.07649846984, 47697.76151119292, 47742.0, 47825.282620760714, 47851.0, 47873.521264786905, 12226.449809758582, 13929.094608850392, 13954.58136255914, 13956.860431395557, 14053.0, 11355.659330220107, 11535.134864885167, 11536.25014374063, 11546.59586914454, 11560.537578496765, 13166.73666526664, 13239.338033098307, 13268.203907765565, 13293.870021048318, 13382.93308533451, 4949.0, 5845.123231515222, 5850.795222167204, 5892.085072351503, 5927.407259274038, 973.6056896956602, 985.7781999050156, 1023.4064242739194, 1028.290479569095, 1048.7902419516126, 18795.530111747317, 22066.558668826856, 22506.437339066328, 22863.19978800734, 24154.79226038726, 1084.6203828659907, 1086.8369744538381, 1123.601121601836, 1130.1862658885589, 1158.7682463507333, 11381.54473821038, 11561.706991069801, 11711.180217384872, 12344.938275308745, 12359.567415140427, 301919.0, 304930.0, 305464.94535054365, 305762.0, 306153.0, 51754.70613234624, 52090.0, 52217.2167417489, 52262.0, 52277.90888364499, 1481.1631428243008, 1485.0644094220572, 1517.2944580769995, 1520.8098987626547, 1531.4027529263374, 22153.55692886101, 22165.77834221637, 22906.54186916286, 23320.416989575395, 23974.0, 37108.0, 37291.44062839114, 37294.44058339073, 37359.0, 37386.43920341143, 265191.0, 265570.67214663804, 266327.0, 266861.0, 266927.0, 32139.35721285608, 33161.50257746184, 33274.0, 33766.66233337635, 33906.321873561894, 41806.581934181486, 42701.35947960845, 43016.0, 43023.56976430196, 43172.70481885548, 28820.0, 28937.855310723313, 29586.0, 30139.0, 30674.846625767623, 28018.579721303788, 28473.00344487933, 30083.097507074825, 30093.94671186498, 30606.387872242874, 35458.54145854146, 35631.0, 35665.28669426649, 36563.451548226265, 36737.26525469529, 28867.5669864948, 29011.419771604873, 30471.08586742401, 30694.539581905843, 30906.381872361977, 18060.90969545144, 21640.783592163876, 22506.887465562566, 24020.0, 24671.753282466947], "value": 16965.29334713052, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [408.6934798900824, 430.5092194897815, 432.7663061946549, 444.75982969196633, 459.12536617743194, 21596.0, 22209.444763880892, 22391.440213994658, 23232.303030909057, 24061.51876962464, 24120.517589648196, 24503.509929801392, 24775.628365574532, 24862.378440538996, 24878.253652390413, 5995.640261584297, 6088.726007329681, 6117.877642447148, 6123.142760013601, 6146.108814221935, 5850.73671684773, 6126.479249263818, 6158.199434073565, 6161.260648722159, 6179.876402471948, 6064.9090263646085, 6118.877622447549, 6122.0, 6135.846603834895, 6140.8464788380425, 6108.816735497942, 6109.236345456818, 6114.755409783604, 6115.999999999978, 6127.2953610334935, 5456.454354564512, 5459.416769136946, 5462.0, 5484.478974497456, 5494.230807686927, 28620.427591448053, 29430.558541621893, 29824.701752982626, 29920.8503957481, 30140.8492957536, 2143.2712877621684, 2174.967375489369, 2177.880216588087, 2186.7922547357975, 2187.3109970359287, 18922.62154756911, 19525.9023704882, 19537.120829562704, 19558.70661940072, 19580.706289405527, 2128.3508529898377, 2201.2405720026727, 2208.757036725975, 2223.3663405929233, 2230.308604332662, 154774.0, 165748.17125914415, 173585.0, 195316.0, 197867.0, 13276.73446531064, 13520.32398380077, 13530.255835929118, 13686.247256400993, 13691.589252322447, 17216.397426090025, 17286.827131728773, 17304.134793260288, 17314.307427703003, 17314.91342543279, 7740.806479838049, 8060.790881367841, 9298.581563829644, 9378.671746488977, 9519.381240219363, 8714.782130446789, 9056.864147037799, 9077.727668169966, 9116.49859257734, 9151.222146117654, 8461.365397595206, 8467.0, 8539.530325832138, 8544.957275213585, 8570.0, 1985.622731680976, 2241.331263187208, 2246.449619843149, 2249.1902914950606, 2421.455172586154, 906.646407900906, 909.7589138878203, 946.8721722567503, 999.1557134221571, 1003.4527943719064, 18989.525261868563, 20318.28885988983, 20532.58934821295, 21909.233176838734, 22287.554248915254, 960.6637676813199, 997.2969056814928, 1037.725002874239, 1037.896210378956, 1061.2942393308363, 10541.683749487527, 10814.78370432587, 10892.782144357068, 11270.492827822767, 11431.0, 575568.0, 576605.0, 577408.0, 577935.0, 582973.0, 146153.0, 147320.0, 148866.51133488744, 149768.0, 150687.0, 996.8554559588964, 1044.12815299226, 1125.2292179856825, 1180.15029178991, 1184.603157942099, 22897.0, 24143.758562414852, 24356.26931192067, 24437.02251909904, 24449.266522004367, 63553.0, 64598.677006614664, 64741.55472120766, 64927.67536162289, 65294.0, 89961.0, 90134.0, 90163.54918225629, 90484.64273036041, 90713.54643227006, 10407.58369665205, 10434.321769084985, 10548.947255263674, 10594.152467802403, 10603.257771955889, 18027.63944721072, 18357.898526088477, 18365.612470378765, 18489.53776155607, 18560.164792584095, 12710.809337859755, 14659.70680586361, 14743.262836858328, 14968.925155374154, 15091.849081509044, 12892.935535322264, 13308.667283317995, 13541.390637421142, 13664.93167534156, 13677.521286754916, 15029.849701502844, 15104.169270690218, 15266.160361180242, 15297.6175595611, 15305.0, 10336.121429678562, 10368.274220804471, 10457.215708821717, 10457.581696732046, 10505.68482945513, 12163.0, 14744.483943061941, 14855.628609284855, 14984.0, 14996.850031499982], "value": 11296.603410894411, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1231.6366671831809, 1455.672473693419, 1456.7669172932333, 1466.97799533007, 1469.933852976616, 11727.941360293207, 12118.939405302961, 12237.204581702194, 12265.509379624815, 12462.688432789184, 12015.459304331303, 12096.274223546572, 12210.572629957953, 12246.0, 12249.693757656063, 2550.885210165547, 2589.9870500647567, 2593.949450472552, 2595.195489398283, 2600.622909678105, 2526.381036646015, 2568.2808813532242, 2570.717221105677, 2581.8321809082445, 2581.8579978101197, 2538.6445897574354, 2543.5930251159816, 2556.4248044190003, 2560.0271896679237, 2563.077292174825, 2500.9374765630914, 2521.4326776475236, 2530.63305820655, 2536.771690547841, 2538.162406405891, 2426.3085020768995, 2510.686164229471, 2514.1326242446326, 2521.571332873416, 2528.734482879308, 11822.64532064031, 11851.170418070738, 11947.820782688266, 11973.042156627449, 11983.520659173622, 6537.869242615168, 6710.496712746563, 6792.830179245509, 6792.932070679329, 6806.353396427331, 4060.6345428911245, 4253.3194688849635, 4312.870613881589, 4327.502337231227, 4345.283028300327, 1240.8076748104058, 1247.1394737631006, 1247.1830950727287, 1271.319843883527, 1271.9109662323642, 15546.378144874192, 15971.44099956496, 16604.750928736077, 16784.160791960476, 16921.492355229362, 5178.741062946913, 5261.605379596545, 5386.946130538645, 5398.838034858961, 5400.081986062379, 12192.207506512048, 12361.814572781415, 12384.690382740324, 12393.62819115428, 12405.503779848885, 5528.700755322496, 6462.612243265419, 6566.047923051131, 6608.471322294205, 6610.0745895574655, 5996.940030599725, 6144.5084393248435, 6243.219597550306, 6252.062190671436, 6262.09199666055, 3824.732268741217, 3887.3391523441082, 4248.150369926027, 4437.689361744711, 4461.126326942627, 1081.3565928272665, 1301.3493253373313, 1304.6281809684194, 1314.2180402660401, 1321.2427471462931, 816.5794615772763, 817.8650522663694, 833.3874602167402, 847.1570787066908, 872.232435456794, 4907.975460122677, 5484.451554844563, 6386.968065159737, 6772.762953296611, 6936.375726184567, 466.002754106213, 601.4406601860225, 605.2343785111836, 606.9211002569638, 626.0577830365271, 3998.980005099956, 4060.614241647039, 4151.7924103794685, 4247.44783178191, 4648.767561621906, 14951.551453456414, 15208.935374523677, 15358.308876100602, 15511.767323490156, 15531.5340539784, 15180.392784288504, 15306.38774449032, 15408.691826163638, 15413.614659633375, 15435.15106669144, 8256.711015114442, 8562.315014798802, 8708.477491350413, 8765.605547750367, 8788.0, 6712.83217919556, 7101.928980710127, 7244.4566657501955, 7579.886301705589, 7608.581528016012, 13138.014648901179, 13166.934165329112, 13275.867241327463, 13509.121907076009, 13572.389242483914, 11965.341906195243, 13487.595372138852, 13564.457421703417, 13690.0, 13757.449702012207, 8603.870941935751, 9822.901770982198, 9839.60641574329, 9843.360181588174, 9865.753356165866, 10806.675799726021, 10866.239363244496, 10987.0, 10995.065419439437, 11025.558977641125, 9307.953460232655, 9311.44131352121, 9317.950050988771, 9385.249180065442, 9419.340646154704, 9160.587773550073, 9168.954155229181, 9173.174414302744, 9207.447553146832, 9211.585478653611, 6830.521863469509, 8079.474834135763, 8386.580670966305, 8432.0, 8455.915440845758, 6006.396704071171, 6222.688865556795, 6441.903371449338, 6493.707783149864, 6607.339266073354, 6281.780137695158, 6351.237851457855, 6570.8028759137305, 6728.461723062242, 6760.391564759196], "value": 5385.4565316740145, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.6985062696708, 385.67410538095316, 404.9915709882392, 409.55563213912905, 411.4918076175923, 4833.613310935126, 5988.401159884015, 6364.649944253064, 6484.610923344597, 6486.61080335179, 5508.834734957958, 5642.166295953821, 6153.692315384235, 6332.435363677718, 6371.968140159304, 3864.574896761354, 4688.484266730674, 4693.20215563355, 4695.225287827504, 4699.624030077603, 4094.2425651254466, 4694.061187762444, 4695.947634037869, 4698.107359601665, 4715.528447155291, 4644.954885150831, 4662.487126416108, 4664.883377915562, 4666.696664716783, 4680.789364478606, 3765.964359801063, 4372.2785740352965, 4603.286490593963, 4638.536146385368, 4641.976790116028, 3497.265574229415, 3542.3800834854055, 3980.4626375439234, 3984.382420724792, 3991.44119823225, 5256.474352564751, 5413.188021796723, 6286.402791734778, 6312.905306420407, 6316.431521163117, 4637.397138372024, 5100.0929470202, 5535.529479994206, 5549.19536667185, 5567.777288908439, 5454.34547854256, 5855.473007429309, 6825.55633883801, 6865.828354291133, 6866.759663411756, 4197.286461301586, 4503.504614492404, 4546.795394207235, 4559.133764584718, 4560.954390456086, 6619.834504137387, 6890.896636550456, 6907.274736152732, 7156.92843071573, 7340.486165968385, 4600.35595016698, 4639.9536004640195, 4641.814327426932, 4642.442906851132, 4647.953520464752, 4499.010217752089, 4724.71651700899, 4728.976355118202, 4734.124187025412, 4743.905121897543, 5062.417821950449, 5347.51872331492, 5512.33851937762, 5528.612997090246, 5552.250446189793, 6815.625140617214, 6818.261273454665, 6833.829154271084, 6840.623765692835, 6923.446124310043, 1373.3957058894055, 1597.6804639072232, 1599.9920000399923, 1602.2549514475825, 1613.693398254328, 1009.9798004040025, 1034.2294990232326, 1066.973325666849, 1073.2272763610188, 1074.2963358999868, 6284.937150628435, 6575.473962083021, 6590.242122155918, 6592.901106483407, 6614.338566143443, 5656.614129538207, 6695.296993815677, 7126.893096603555, 7162.534435261691, 7190.245024272481, 5246.3704355477585, 5839.649621022752, 6191.845203869849, 6537.803865884031, 6576.8819300719, 3367.5921715973195, 3554.7156227501737, 3708.2398108387715, 3718.3492888744363, 3738.1776009277914, 6919.619420931897, 6936.826579335456, 7166.211716711157, 7353.558786472829, 7375.557466552024, 6330.765500727314, 6545.574537655132, 7340.192578816326, 7344.025669603819, 7352.63236838156, 6892.448604111759, 6993.440524757905, 7103.92896071043, 7254.383377412873, 7273.709051637981, 5584.553235741212, 6208.534359923115, 6860.451163906975, 7093.0, 7163.820904477429, 5274.76263568148, 6902.895536714102, 6902.964555316743, 6993.4754893384215, 7038.826462550124, 6401.42387185137, 6690.83272918181, 6799.286074962058, 7167.749128780468, 7388.556686598821, 4255.6169944705125, 4907.214845624683, 5088.949110508847, 5162.922556161736, 5204.505571970657, 6927.861442771015, 7093.503454758324, 7123.287671232835, 7139.32176443237, 7162.677679504539, 5492.945070549244, 6697.564658297195, 6791.505868708876, 6793.932060679527, 6802.8639427212165, 6454.967205247138, 6796.0, 6798.286179951034, 6821.420179284816, 6847.383735463832, 5529.502344788988, 6002.579819412599, 6613.768518101843, 6671.5997040177745, 6687.933120668926, 6795.422389096784, 6811.7275308989065, 6829.897551536632, 6832.487563432862, 6848.417884479875, 5772.336181339199, 6094.20775299226, 6130.233720784902, 6630.900536492053, 6634.834129146617], "value": 4918.687327226147, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [672.9663516824161, 836.3727204596553, 847.7795773098993, 853.2619284319062, 865.9307255419567, 14739.705205895876, 15267.618309542268, 16971.0, 17451.73822392665, 17463.0, 17662.116894155304, 17924.28302867887, 18232.088395580235, 18316.725249121242, 18417.631647367078, 970.7961328121086, 980.6616717232544, 1008.2135933971514, 1008.2236677758121, 1009.2481101579334, 939.0281059103829, 1031.6234574380335, 1045.5974449836792, 1046.5290619221344, 1046.6179844356832, 1077.3966578715906, 1077.520503375998, 1078.5901357484145, 1078.7734575739105, 1080.9729756756064, 1040.8334666453406, 1058.1640504001907, 1064.0157853985072, 1072.3512275073547, 1075.5213929801203, 785.8192615698422, 803.5941849366092, 804.3203493048364, 807.8303556253194, 814.2549567146054, 16588.58528536784, 17951.461456156205, 18097.728534072, 18121.63756724858, 18339.0, 17763.555911102198, 17802.287908483646, 17876.821231787646, 18262.0868956553, 18276.634467310578, 14728.0, 14902.403903843833, 14975.775363369557, 14976.47582334624, 15010.774838377432, 3571.357155711971, 3616.710663146942, 3646.762960407565, 3689.262147570497, 3740.4015357542935, 125837.0, 150547.0, 170521.18481451643, 170894.1455292728, 171650.0, 13749.518766843112, 13854.238016909167, 14012.509562165274, 14150.575482735534, 14196.219207943455, 26563.01554766762, 26969.325766855985, 27000.189994300203, 27061.323466913484, 27064.05275815337, 6658.700358483879, 6933.618650974144, 7996.840852969665, 8028.43800933929, 8118.391120665974, 3982.0, 4045.8179381927885, 4065.0243941453837, 4067.877963661095, 4072.1041370898356, 4190.706650534495, 4245.172191422643, 4259.424977628042, 4298.312270036779, 4354.0, 919.1406035356963, 923.5797712041006, 937.4141161773891, 938.2775263047415, 943.2736792669606, 644.3170239546096, 650.1612919334007, 662.3244290823333, 671.332024635481, 677.7526202935893, 9650.95174524123, 10386.324888882198, 12391.814122788166, 12649.367531623382, 12842.893389628603, 636.726207730671, 639.4404895716249, 652.103357882911, 656.2092678322609, 672.8284287506655, 5681.659100453986, 5707.600467967202, 5761.88476230473, 5841.328247251536, 5876.735546900314, 143932.35643722527, 144072.0, 144783.0, 145809.0, 148533.25733371265, 130969.0, 133783.65550353986, 137257.0, 139267.0, 139726.30136849455, 113560.0, 115617.59441650292, 117152.8284717142, 118160.0, 118576.0, 9817.03659268135, 10331.0, 10622.309549879234, 11091.722706932069, 11733.706657333634, 33475.665243347255, 47320.763396182796, 47723.761381192875, 48750.0, 49606.50393496019, 42116.578834211265, 61178.0, 61287.69356153191, 61382.69308653428, 61548.0, 14331.355089021228, 14469.638259043608, 14725.116493010453, 14797.852021479648, 14809.111453312837, 16012.67974640524, 16208.67582648364, 16487.258073386485, 16619.584510387336, 16641.0, 14952.775708364166, 14991.0, 15300.005499642488, 15471.922640386727, 15486.845131548991, 14759.483418080315, 14778.0, 15107.54677359681, 15164.317605707549, 15228.619284517976, 14720.263986800403, 14847.33187006609, 15071.718903893292, 15154.166520841018, 15168.165750883365, 10312.896871031193, 10472.738181545523, 11308.378039207922, 11574.18980671345, 11640.068794496588, 11955.880441195477, 12370.938145309216, 14123.2938353084, 14581.0, 14598.854011459749], "value": 8698.530605529173, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 116.0, 114.0, 93.0, 165.0, 170.0, 168.0, 114.0, 114.0, 111.0], "value": 126.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 109.0, 111.0, 154.0, 112.0, 115.0, 93.0, 139.0, 160.0, 107.0], "value": 123.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 200.0, 202.0, 221.0, 208.0, 218.0, 230.0, 197.0, 197.0, 198.0], "value": 207.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 58.0, 59.0, 93.0, 82.0, 88.0, 86.0, 57.0, 77.0, 58.0], "value": 72.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 67.0, 83.0, 69.0, 69.0, 69.0, 87.0, 73.0, 70.0, 92.0], "value": 75.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 87.0, 88.0, 101.0, 82.0, 135.0, 122.0, 91.0, 87.0], "value": 97.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 135.0, 120.0, 97.0, 96.0, 95.0, 97.0, 94.0, 95.0, 97.0], "value": 102.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 87.0, 139.0, 145.0, 138.0, 149.0, 91.0, 127.0, 138.0, 92.0], "value": 122.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 48.0, 53.0, 52.0, 48.0, 51.0, 67.0, 46.0, 44.0, 67.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 60.0, 59.0, 59.0, 61.0, 55.0, 55.0, 58.0, 56.0], "value": 57.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 82.0, 86.0, 87.0, 94.0, 114.0, 99.0, 114.0, 81.0], "value": 92.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 136.0, 156.0, 158.0, 158.0, 135.0, 137.0, 142.0, 137.0, 142.0], "value": 144.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 213.0, 233.0, 178.0, 180.0, 187.0, 186.0, 187.0, 183.0, 185.0], "value": 190.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 68.0, 70.0, 74.0, 79.0, 99.0, 102.0, 88.0, 70.0], "value": 78.5, "unit": "score"}]}]} 19:14:16 INFO - Return code: 0 19:14:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:14:16 INFO - # TBPL SUCCESS # 19:14:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:14:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:14:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:14:16 INFO - 2017-06-05 19:14:16.643 screenresolution[85273:718245] starting screenresolution argv=screenresolution get 19:14:16 INFO - 2017-06-05 19:14:16.646 screenresolution[85273:718245] Display 0: 1600x1200x32@60 19:14:16 INFO - 2017-06-05 19:14:16.651 screenresolution[85274:718249] starting screenresolution argv=screenresolution list 19:14:16 INFO - Available Modes on Display 0 19:14:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:14:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:14:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:14:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:14:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:14:16 INFO - Intel Iris: 19:14:16 INFO - Chipset Model: Intel Iris 19:14:16 INFO - Type: GPU 19:14:16 INFO - Bus: Built-In 19:14:16 INFO - VRAM (Dynamic, Max): 1536 MB 19:14:16 INFO - Vendor: Intel (0x8086) 19:14:16 INFO - Device ID: 0x0a2e 19:14:16 INFO - Revision ID: 0x0009 19:14:16 INFO - Displays: 19:14:16 INFO - Display: 19:14:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:14:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:14:16 INFO - Display Serial Number: 1600x1200 60 19:14:16 INFO - Main Display: Yes 19:14:16 INFO - Mirror: Off 19:14:16 INFO - Online: Yes 19:14:16 INFO - Rotation: Supported 19:14:16 INFO - Return code: 0 19:14:16 INFO - Running post-action listener: _resource_record_post_action 19:14:16 INFO - [mozharness: 2017-06-06 02:14:16.887814Z] Finished run-tests step (success) 19:14:16 INFO - Running post-run listener: _resource_record_post_run 19:14:16 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 2597888; Write bytes: 570307584; Read time: 28; Write time: 1748 19:14:16 INFO - TinderboxPrint: CPU usage
53.4% 19:14:16 INFO - TinderboxPrint: I/O read bytes / time
2,597,888 / 28 19:14:16 INFO - TinderboxPrint: I/O write bytes / time
570,307,584 / 1,748 19:14:16 INFO - TinderboxPrint: CPU idle
2,343.2 (46.6%) 19:14:16 INFO - TinderboxPrint: CPU system
1,020.2 (20.3%) 19:14:16 INFO - TinderboxPrint: CPU user
1,662.4 (33.1%) 19:14:16 INFO - TinderboxPrint: Swap in / out
264,290,304 / 0 19:14:16 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 233548288; Write bytes: 229224448; Read time: 16780; Write time: 467 19:14:16 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:14:16 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 2597888; Write bytes: 321778688; Read time: 28; Write time: 1231 19:14:17 INFO - Running post-run listener: _upload_blobber_files 19:14:17 INFO - Blob upload gear active. 19:14:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:14:17 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:14:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:14:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:14:17 INFO - (blobuploader) - INFO - Open directory for files ... 19:14:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:14:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:14:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:14:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:14:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:14:18 INFO - (blobuploader) - INFO - Done attempting. 19:14:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:14:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:14:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:14:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:14:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:14:19 INFO - (blobuploader) - INFO - Done attempting. 19:14:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:14:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:14:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:14:20 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:14:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:14:20 INFO - (blobuploader) - INFO - Done attempting. 19:14:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:14:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:14:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:14:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:14:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:14:23 INFO - (blobuploader) - INFO - Done attempting. 19:14:23 INFO - (blobuploader) - INFO - Iteration through files over. 19:14:23 INFO - Return code: 0 19:14:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:14:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:14:23 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fdb0d31153d3e2c3bf20b3d92b57af5bcb659752a0191cc96fa378be9c53e3713a5f01d53d885457bea65e0b5c0ec1900b8a04f05262421488bd08138713c3b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/976b253ab378bc05aea36b4e1a4caa22199b5601b587fe8bb71ac6727af8fdd7bb80eb44dd9bcb927f83cceff68bffab6de3b308be1f67e5494bfe6f5685c3d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de81693fe5107f3a780633a3c149d98655d2401a873fd5ac5c6d4181d8797dca9429f314faeac525c2b6b057f5584ddb0933ab903459f8825f01cafe4d649a87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44ffaeca53f56c386c218de7c46c66f123e92c15e1bccc53e34888db75ada990a6ff3ea1e20fa7041757b3d8275986f71bf3c989655108194a74a070be83ecbd"} 19:14:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:14:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:14:23 INFO - Contents: 19:14:23 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4fdb0d31153d3e2c3bf20b3d92b57af5bcb659752a0191cc96fa378be9c53e3713a5f01d53d885457bea65e0b5c0ec1900b8a04f05262421488bd08138713c3b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/976b253ab378bc05aea36b4e1a4caa22199b5601b587fe8bb71ac6727af8fdd7bb80eb44dd9bcb927f83cceff68bffab6de3b308be1f67e5494bfe6f5685c3d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de81693fe5107f3a780633a3c149d98655d2401a873fd5ac5c6d4181d8797dca9429f314faeac525c2b6b057f5584ddb0933ab903459f8825f01cafe4d649a87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44ffaeca53f56c386c218de7c46c66f123e92c15e1bccc53e34888db75ada990a6ff3ea1e20fa7041757b3d8275986f71bf3c989655108194a74a070be83ecbd"} 19:14:23 INFO - Running post-run listener: copy_logs_to_upload_dir 19:14:23 INFO - Copying logs to upload dir... 19:14:23 INFO - mkdir: /builds/slave/test/build/upload/logs 19:14:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.105619 ========= master_lag: 1.10 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 17 secs) (at 2017-06-05 19:14:24.166133) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 19:14:24.173756) ========= 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.pAB3T5hbOq/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.IG2uF4knSS/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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/4fdb0d31153d3e2c3bf20b3d92b57af5bcb659752a0191cc96fa378be9c53e3713a5f01d53d885457bea65e0b5c0ec1900b8a04f05262421488bd08138713c3b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/976b253ab378bc05aea36b4e1a4caa22199b5601b587fe8bb71ac6727af8fdd7bb80eb44dd9bcb927f83cceff68bffab6de3b308be1f67e5494bfe6f5685c3d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de81693fe5107f3a780633a3c149d98655d2401a873fd5ac5c6d4181d8797dca9429f314faeac525c2b6b057f5584ddb0933ab903459f8825f01cafe4d649a87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44ffaeca53f56c386c218de7c46c66f123e92c15e1bccc53e34888db75ada990a6ff3ea1e20fa7041757b3d8275986f71bf3c989655108194a74a070be83ecbd"} build_url:https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011396 build_url: 'https://queue.taskcluster.net/v1/task/RnbBQyzoSlyDU2y2lq1StA/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/4fdb0d31153d3e2c3bf20b3d92b57af5bcb659752a0191cc96fa378be9c53e3713a5f01d53d885457bea65e0b5c0ec1900b8a04f05262421488bd08138713c3b", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/976b253ab378bc05aea36b4e1a4caa22199b5601b587fe8bb71ac6727af8fdd7bb80eb44dd9bcb927f83cceff68bffab6de3b308be1f67e5494bfe6f5685c3d8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de81693fe5107f3a780633a3c149d98655d2401a873fd5ac5c6d4181d8797dca9429f314faeac525c2b6b057f5584ddb0933ab903459f8825f01cafe4d649a87", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44ffaeca53f56c386c218de7c46c66f123e92c15e1bccc53e34888db75ada990a6ff3ea1e20fa7041757b3d8275986f71bf3c989655108194a74a070be83ecbd"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 19:14:24.215794) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 19:14:24.216165) ========= 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.pAB3T5hbOq/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.IG2uF4knSS/Listeners TMPDIR=/var/folders/vn/28yn3b3s6nq12pmzj1bqp29w00000w/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.005157 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 19:14:24.265204) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 19:14:24.265577) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 19:14:24.265948) ========= ========= Total master_lag: 1.60 =========