Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

UIA in Console and WSL: crashes in some contexts #11428

Closed
aaclause opened this issue Jul 27, 2020 · 47 comments
Closed

UIA in Console and WSL: crashes in some contexts #11428

aaclause opened this issue Jul 27, 2020 · 47 comments

Comments

@aaclause
Copy link
Contributor

Steps to reproduce:

Unfortunately I don't have a precise scenario. The bug is random but it sometimes occurs when I want to edit a file (with nano for instance).

Actual behavior:

The following traceback is raised in the log and WSL/cmd sometimes crash completely (window is closed):

ERROR - eventHandler.executeEvent (08:57:52.084) - MainThread (14160):
error executing event: caret on <NVDAObjects.Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA object at 0x045C7670> with extra args of {}
Traceback (most recent call last):
  File "eventHandler.pyc", line 214, in executeEvent
  File "eventHandler.pyc", line 94, in __init__
  File "eventHandler.pyc", line 102, in next
  File "NVDAObjects\behaviors.pyc", line 191, in event_caret
  File "editableText.pyc", line 343, in detectPossibleSelectionChange
  File "editableText.pyc", line 350, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 776, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, ''Unspecified error', (None, None, None, 0, None))

Expected behavior:

No such crash

System configuration

NVDA installed/portable/running from source:

Installed

NVDA version:

alpha-20601,3a9484fb

Windows version:

10 Insider (64-bit) build 20175.1000

Name and version of other software in use when reproducing the issue:

WSL

Other information about your system:

Other questions

Does the issue still occur after restarting your computer?

Yes

Have you tried any other versions of NVDA? If so, please report their behaviors.

Yes, the bug is present since UIA was introduced in Windows Console. I don't usually use UIA in Windows Console because I can't accept such bugs, I've just retested it.

If addons are disabled, is your problem still occuring?

Yes

Did you try to run the COM registry fixing tool in NVDA menu / tools?

Yes

CC @codeofdusk

@codeofdusk
Copy link
Contributor

Probably microsoft/terminal#6986

@Neurrone
Copy link

I encountered something similar today, but I'm not sure if the underlying cause is the same because my keyboard and NVDA locked up so badly that I was forced to do a hard reboot, losing unsaved files in the process.

I was interacting with the console via WSL 1 and the command was just a curl which did not produce a lot of output. Unfortunately, NVDA just stopped speaking and most keyboard commands wouldn't work so I couldn't recover at all. This occured right after I pressed enter to execute the command so I'm fairly sure this is a problem with NVDA.

@codeofdusk
Copy link
Contributor

Inbox console recently received some UIA fixes (notably microsoft/terminal#7530). Could you please re-test on the latest insider dev build (or download OpenConsole.exe from the terminal repo by extracting it from the msix)?

@aaclause
Copy link
Contributor Author

@codeofdusk thanks for the information. Unfortunately my WSL is currently broken due to microsoft/WSL#5902
I'll retry ASAP.

@codeofdusk
Copy link
Contributor

I suspect that microsoft/terminal#7677 will fix many of the remaining crashes.

@codeofdusk
Copy link
Contributor

Could users experiencing this issue please test the OpenConsole.exe in this build with UIA console enabled and report back? On my system, I'm running OpenConsole as the default (follow the steps in microsoft/terminal#1817, and note that you'll also need to take ownership of conhost.exe in advanced security properties before adding/changing permissions), but you can also just run this build for testing while leaving your default console untouched.

@codeofdusk
Copy link
Contributor

Also CC @carlos-zamora (Microsoft dev), @DHowett (Microsoft dev lead), and @Simon818 (user who reported some crashes to me directly).

@Adriani90
Copy link
Collaborator

cc: @derekriemer, @josephsl

@aaclause
Copy link
Contributor Author

aaclause commented Oct 4, 2020

I get the following traceback with NVDA version alpha-21096,c61eb42d and Windows 10 Insider (64-bit) build 20226.1000 when I open a file with nano (addons disabled):

Traceback (most recent call last):
  File "eventHandler.pyc", line 220, in executeEvent
  File "eventHandler.pyc", line 96, in __init__
  File "eventHandler.pyc", line 105, in next
  File "NVDAObjects\behaviors.pyc", line 197, in event_caret
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 776, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))

@codeofdusk
Copy link
Contributor

I'm assuming that NVDA/the console continues working normally though? If so, this will be fixed in #11039.

@aaclause
Copy link
Contributor Author

aaclause commented Oct 4, 2020

@codeofdusk yes apart this traceback, no problem.

@codeofdusk
Copy link
Contributor

Assuming the crash has been resolved, could this issue please be closed?

@aaclause aaclause closed this as completed Oct 4, 2020
@aaclause
Copy link
Contributor Author

aaclause commented Oct 8, 2020

I've just reproduced the original issue (one of my instance of cmd crashed completely during a compilation through a SSH connection). So the issue doesn't seem fully solved yet.
However I used UIA in Windows Consoles intensely these last days and it's the first crash.
(alpha-21126,7df2560a, Windows 10 Insider (64-bit) build 20231.1000)

@aaclause aaclause reopened this Oct 8, 2020
@codeofdusk
Copy link
Contributor

codeofdusk commented Oct 8, 2020

That's not good. CC @carlos-zamora.

@codeofdusk
Copy link
Contributor

@andre9642 Could you please respond to this issue? The Windows bug deadline is fast approaching and Microsoft would like to fix any remaining crash bugs. Thanks.

@aaclause
Copy link
Contributor Author

aaclause commented Oct 12, 2020

@codeofdusk unfortunately the bug is very rarely (only occurred 2 times last week with an intensive usage of cmd). So I'm unable to reproduce it on request. I remember that I used SSH, Emacs and nano during these 'cmd.exe' instances. Besides these instances were open for several hours (at least 5).
Sorry, I can't give more detail for now.

@codeofdusk
Copy link
Contributor

OK, so you were using the cmd included with Windows? Or my test build?

@aaclause
Copy link
Contributor Author

Yes, the cmd included with Windows. I'll try with your test build soon.

@codeofdusk
Copy link
Contributor

OK, thanks for letting me know.

Inbox is a little behind, there were a few more crashes solved since the latest console in Windows, so I suspect it has been fixed. It's just a matter of waiting for you to receive the fix in a future build.

@aaclause
Copy link
Contributor Author

@codeofdusk Thanks for this precision. I'm sorry for not testing OpenConsole before. So I consider that this issue is solved. Thanks for your great job with @carlos-zamora! :-)

@codeofdusk
Copy link
Contributor

Windows insider build 20236 contains many additional crash fixes!

@akash07k
Copy link

akash07k commented Oct 15, 2020 via email

@DHowett
Copy link

DHowett commented Oct 15, 2020

@akash07k if we put every team's changes in the changelog, people would stop reading after the first 500! I integrated these changes from our open-source version myself. They're definitely there! 😄

@akash07k
Copy link

Yes bro, you are absolutely right.
So, is there any way/path where we can read all the change log of every time/component of every latest insider windows release?

@akash07k if we put every team's changes in the changelog, people would stop reading after the first 500! I integrated these changes from our open-source version myself. They're definitely there! 😄

@josephsl
Copy link
Collaborator

josephsl commented Oct 15, 2020 via email

@akash07k
Copy link

akash07k commented Oct 15, 2020 via email

@aaclause
Copy link
Contributor Author

@codeofdusk Today I was able to reproduce the crash 2 times. The second time, I enabled debug mode with UIA events. Not tested with add-ons disabled. I used cmd directly (not openconsole). Here's the log after pressing enter (my command was emacs <file>):

Log (excerpt)
Input: kb(laptop):enter
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.361) - MainThread (1268):
_getTextWithFieldsForUIARange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.361) - MainThread (1268):
rootElement: document
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.361) - MainThread (1268):
full text: 
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.362) - MainThread (1268):
Fetching parents starting from enclosingElement
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.364) - MainThread (1268):
Hit root
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.364) - MainThread (1268):
Done fetching parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.364) - MainThread (1268):
Generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.364) - MainThread (1268):
Done generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.364) - MainThread (1268):
Yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.365) - MainThread (1268):
Done yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.365) - MainThread (1268):
Yielding balanced fields for textRange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.365) - MainThread (1268):
no children
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.365) - MainThread (1268):
Yielding text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.366) - MainThread (1268):
_getTextWithFields_text start
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.366) - MainThread (1268):
Walking by unit None, with further units of: [1, 2, 0]
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.366) - MainThread (1268):
Done _getTextWithFields_text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.367) - MainThread (1268):
_getTextWithFieldsForUIARange end
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.367) - MainThread (1268):
_getTextWithFieldsForUIARange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.367) - MainThread (1268):
rootElement: document
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.367) - MainThread (1268):
full text: 
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.369) - MainThread (1268):
Fetching parents starting from enclosingElement
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.370) - MainThread (1268):
Hit root
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.370) - MainThread (1268):
Done fetching parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.371) - MainThread (1268):
Generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.371) - MainThread (1268):
Done generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.371) - MainThread (1268):
Yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.371) - MainThread (1268):
Done yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.372) - MainThread (1268):
Yielding balanced fields for textRange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.372) - MainThread (1268):
no children
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.372) - MainThread (1268):
Yielding text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.372) - MainThread (1268):
_getTextWithFields_text start
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.373) - MainThread (1268):
Walking by unit None, with further units of: [1, 2, 0]
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.373) - MainThread (1268):
Done _getTextWithFields_text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.373) - MainThread (1268):
_getTextWithFieldsForUIARange end
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.374) - MainThread (1268):
_getTextWithFieldsForUIARange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.375) - MainThread (1268):
rootElement: document
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.376) - MainThread (1268):
full text: 
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.376) - MainThread (1268):
Fetching parents starting from enclosingElement
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.378) - MainThread (1268):
Hit root
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.379) - MainThread (1268):
Done fetching parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.379) - MainThread (1268):
Generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.379) - MainThread (1268):
Done generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.379) - MainThread (1268):
Yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.379) - MainThread (1268):
Done yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.379) - MainThread (1268):
Yielding balanced fields for textRange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.379) - MainThread (1268):
no children
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.379) - MainThread (1268):
Yielding text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.380) - MainThread (1268):
_getTextWithFields_text start
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.380) - MainThread (1268):
Walking by unit None, with further units of: [1, 2, 0]
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.381) - MainThread (1268):
Done _getTextWithFields_text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.381) - MainThread (1268):
_getTextWithFieldsForUIARange end
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.381) - MainThread (1268):
_getTextWithFieldsForUIARange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.382) - MainThread (1268):
rootElement: document
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.382) - MainThread (1268):
full text: 
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.383) - MainThread (1268):
Fetching parents starting from enclosingElement
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.385) - MainThread (1268):
Hit root
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.385) - MainThread (1268):
Done fetching parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.385) - MainThread (1268):
Generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.385) - MainThread (1268):
Done generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.385) - MainThread (1268):
Yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.386) - MainThread (1268):
Done yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.386) - MainThread (1268):
Yielding balanced fields for textRange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.386) - MainThread (1268):
no children
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.386) - MainThread (1268):
Yielding text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.387) - MainThread (1268):
_getTextWithFields_text start
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.387) - MainThread (1268):
Walking by unit None, with further units of: [1, 2, 0]
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.387) - MainThread (1268):
Done _getTextWithFields_text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.387) - MainThread (1268):
_getTextWithFieldsForUIARange end
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.390) - MainThread (1268):
_getTextWithFieldsForUIARange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.390) - MainThread (1268):
rootElement: document
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.390) - MainThread (1268):
full text:                                                                                                                         
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.392) - MainThread (1268):
Fetching parents starting from enclosingElement
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.393) - MainThread (1268):
Hit root
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.393) - MainThread (1268):
Done fetching parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.394) - MainThread (1268):
Generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.394) - MainThread (1268):
Done generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.394) - MainThread (1268):
Yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.394) - MainThread (1268):
Done yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.395) - MainThread (1268):
Yielding balanced fields for textRange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.395) - MainThread (1268):
no children
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.395) - MainThread (1268):
Yielding text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.395) - MainThread (1268):
_getTextWithFields_text start
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.396) - MainThread (1268):
Walking by unit None, with further units of: [1, 2, 0]
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.396) - MainThread (1268):
Chunk has text. Fetching formatting
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.397) - MainThread (1268):
Yielding formatting and text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.397) - MainThread (1268):
Done _getTextWithFields_text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.397) - MainThread (1268):
_getTextWithFieldsForUIARange end
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.398) - MainThread (1268):
_getTextWithFieldsForUIARange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.399) - MainThread (1268):
rootElement: document
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.399) - MainThread (1268):
full text:                                                                                                                         
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.400) - MainThread (1268):
Fetching parents starting from enclosingElement
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.402) - MainThread (1268):
Hit root
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.402) - MainThread (1268):
Done fetching parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.402) - MainThread (1268):
Generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.402) - MainThread (1268):
Done generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.403) - MainThread (1268):
Yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.403) - MainThread (1268):
Done yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.403) - MainThread (1268):
Yielding balanced fields for textRange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.403) - MainThread (1268):
no children
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.404) - MainThread (1268):
Yielding text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.404) - MainThread (1268):
_getTextWithFields_text start
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.404) - MainThread (1268):
Walking by unit None, with further units of: [1, 2, 0]
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.405) - MainThread (1268):
Chunk has text. Fetching formatting
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.405) - MainThread (1268):
Yielding formatting and text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.406) - MainThread (1268):
Done _getTextWithFields_text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.406) - MainThread (1268):
_getTextWithFieldsForUIARange end
IO - braille.BrailleBuffer.update (20:39:25.423) - MainThread (1268):
Braille regions text: ['                                                                                                                         ']
IO - braille.BrailleHandler.update (20:39:25.424) - MainThread (1268):
Braille window dots: - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - -
IO - braille.BrailleHandler.update (20:39:25.425) - MainThread (1268):
Braille window dots: - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - - -
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.430) - MainThread (1268):
_getTextWithFieldsForUIARange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.431) - MainThread (1268):
rootElement: document
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.431) - MainThread (1268):
full text: 
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.432) - MainThread (1268):
Fetching parents starting from enclosingElement
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.434) - MainThread (1268):
Hit root
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.434) - MainThread (1268):
Done fetching parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.434) - MainThread (1268):
Generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.435) - MainThread (1268):
Done generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.435) - MainThread (1268):
Yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.435) - MainThread (1268):
Done yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.435) - MainThread (1268):
Yielding balanced fields for textRange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.435) - MainThread (1268):
no children
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.435) - MainThread (1268):
Yielding text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.435) - MainThread (1268):
_getTextWithFields_text start
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.435) - MainThread (1268):
Walking by unit None, with further units of: [1, 2, 0]
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.436) - MainThread (1268):
Done _getTextWithFields_text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.436) - MainThread (1268):
_getTextWithFieldsForUIARange end
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.437) - MainThread (1268):
_getTextWithFieldsForUIARange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.437) - MainThread (1268):
rootElement: document
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.438) - MainThread (1268):
full text: 
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.438) - MainThread (1268):
Fetching parents starting from enclosingElement
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.440) - MainThread (1268):
Hit root
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.440) - MainThread (1268):
Done fetching parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.441) - MainThread (1268):
Generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.441) - MainThread (1268):
Done generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.441) - MainThread (1268):
Yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.441) - MainThread (1268):
Done yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.441) - MainThread (1268):
Yielding balanced fields for textRange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.441) - MainThread (1268):
no children
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.441) - MainThread (1268):
Yielding text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.441) - MainThread (1268):
_getTextWithFields_text start
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.442) - MainThread (1268):
Walking by unit None, with further units of: [1, 2, 0]
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.442) - MainThread (1268):
Done _getTextWithFields_text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.442) - MainThread (1268):
_getTextWithFieldsForUIARange end
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.444) - MainThread (1268):
_getTextWithFieldsForUIARange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.444) - MainThread (1268):
rootElement: document
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.444) - MainThread (1268):
full text: 
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.445) - MainThread (1268):
Fetching parents starting from enclosingElement
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.447) - MainThread (1268):
Hit root
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.447) - MainThread (1268):
Done fetching parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.447) - MainThread (1268):
Generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.447) - MainThread (1268):
Done generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.447) - MainThread (1268):
Yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.447) - MainThread (1268):
Done yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.447) - MainThread (1268):
Yielding balanced fields for textRange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.447) - MainThread (1268):
no children
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.448) - MainThread (1268):
Yielding text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.448) - MainThread (1268):
_getTextWithFields_text start
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.448) - MainThread (1268):
Walking by unit None, with further units of: [1, 2, 0]
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.449) - MainThread (1268):
Done _getTextWithFields_text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.449) - MainThread (1268):
_getTextWithFieldsForUIARange end
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.449) - MainThread (1268):
_getTextWithFieldsForUIARange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.450) - MainThread (1268):
rootElement: document
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.450) - MainThread (1268):
full text: 
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.451) - MainThread (1268):
Fetching parents starting from enclosingElement
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.453) - MainThread (1268):
Hit root
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.453) - MainThread (1268):
Done fetching parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.453) - MainThread (1268):
Generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.453) - MainThread (1268):
Done generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.453) - MainThread (1268):
Yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.453) - MainThread (1268):
Done yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.453) - MainThread (1268):
Yielding balanced fields for textRange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.453) - MainThread (1268):
no children
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.453) - MainThread (1268):
Yielding text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.454) - MainThread (1268):
_getTextWithFields_text start
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.454) - MainThread (1268):
Walking by unit None, with further units of: [1, 2, 0]
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.455) - MainThread (1268):
Done _getTextWithFields_text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.455) - MainThread (1268):
_getTextWithFieldsForUIARange end
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.456) - MainThread (1268):
_getTextWithFieldsForUIARange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.456) - MainThread (1268):
rootElement: document
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.457) - MainThread (1268):
full text:                                                                                                                         
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.459) - MainThread (1268):
Fetching parents starting from enclosingElement
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.459) - MainThread (1268):
Hit root
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.460) - MainThread (1268):
Done fetching parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.460) - MainThread (1268):
Generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.460) - MainThread (1268):
Done generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.460) - MainThread (1268):
Yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.461) - MainThread (1268):
Done yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.461) - MainThread (1268):
Yielding balanced fields for textRange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.461) - MainThread (1268):
no children
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.462) - MainThread (1268):
Yielding text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.462) - MainThread (1268):
_getTextWithFields_text start
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.462) - MainThread (1268):
Walking by unit None, with further units of: [1, 2, 0]
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.462) - MainThread (1268):
Chunk has text. Fetching formatting
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.463) - MainThread (1268):
Yielding formatting and text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.464) - MainThread (1268):
Done _getTextWithFields_text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.464) - MainThread (1268):
_getTextWithFieldsForUIARange end
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.464) - MainThread (1268):
_getTextWithFieldsForUIARange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.465) - MainThread (1268):
rootElement: document
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.465) - MainThread (1268):
full text:                                                                                                                         
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.466) - MainThread (1268):
Fetching parents starting from enclosingElement
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.468) - MainThread (1268):
Hit root
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.468) - MainThread (1268):
Done fetching parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.468) - MainThread (1268):
Generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.469) - MainThread (1268):
Done generating controlFields for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.469) - MainThread (1268):
Yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.469) - MainThread (1268):
Done yielding control starts for parents
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.469) - MainThread (1268):
Yielding balanced fields for textRange
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.470) - MainThread (1268):
no children
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFieldsForUIARange (20:39:25.470) - MainThread (1268):
Yielding text
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.470) - MainThread (1268):
_getTextWithFields_text start
DEBUG - NVDAObjects.UIA.UIATextInfo._getTextWithFields_text (20:39:25.471) - MainThread (1268):
Walking by unit None, with further units of: [1, 2, 0]
DEBUG - _UIAHandler.UIAHandler.IUIAutomationPropertyChangedEventHandler_HandlePropertyChangedEvent (20:39:25.567) - Dummy-2 (4712):
HandlePropertyChangedEvent: Ignoring event valueChange for shouldAcceptEvent=False
DEBUGWARNING - watchdog._watcher (20:39:25.938) - watchdog (7972):
Trying to recover from freeze. Listing stacks for Python threads:
Python stack for thread 6536 (Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA._monitorThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "NVDAObjects\behaviors.pyc", line 300, in _monitor
  File "threading.pyc", line 552, in wait
  File "threading.pyc", line 296, in wait

Python stack for thread 1036 (watchdog.CancellableCallThread.execute(<_FuncPtr object at 0x00F7A6C0>)):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "watchdog.pyc", line 332, in run
  File "threading.pyc", line 552, in wait
  File "threading.pyc", line 296, in wait

Python stack for thread 7972 (watchdog):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "watchdog.pyc", line 127, in _watcher
  File "watchdog.pyc", line 63, in getFormattedStacksForAllThreads

Python stack for thread 1284 (Thread-3):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "C:\Users\andre\AppData\Roaming\nvda\addons\markdownForever\globalPlugins\markdownForever\updateCheck.py", line 175, in run
    time.sleep(0.2)

Python stack for thread 2908 (winInputHook):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "winInputHook.pyc", line 79, in hookThreadFunc

Python stack for thread 14288 (_UIAHandler.UIAHandler.MTAThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "_UIAHandler.pyc", line 310, in MTAThreadFunc
  File "queue.pyc", line 170, in get
  File "threading.pyc", line 296, in wait

Python stack for thread 10576 (braille._BgThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "braille.pyc", line 2187, in func

Python stack for thread 7744 (Thread-1):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "C:\Users\andre\AppData\Roaming\nvda\addons\codefactory-py3\synthDrivers\_cfnvdainterface.py", line 272, in run
    func, args, kwargs = _bgQueue.get()
  File "queue.pyc", line 170, in get
  File "threading.pyc", line 296, in wait

Python stack for thread 1268 (MainThread):
  File "nvda.pyw", line 236, in <module>
  File "core.pyc", line 553, in main
  File "wx\core.pyc", line 2134, in MainLoop
  File "gui\__init__.pyc", line 1062, in Notify
  File "core.pyc", line 523, in run
  File "queueHandler.pyc", line 88, in pumpAll
  File "queueHandler.pyc", line 55, in flushQueue
  File "eventHandler.pyc", line 65, in _queueEventCallback
  File "eventHandler.pyc", line 246, in executeEvent
  File "eventHandler.pyc", line 96, in __init__
  File "eventHandler.pyc", line 105, in next
  File "NVDAObjects\behaviors.pyc", line 195, in event_caret
  File "NVDAObjects\__init__.pyc", line 1180, in event_caret
  File "review.pyc", line 177, in handleCaretMove
  File "api.pyc", line 215, in setReviewPosition
  File "braille.pyc", line 2036, in handleReviewMove
  File "braille.pyc", line 1963, in _doCursorMove
  File "C:\Users\andre\AppData\Roaming\nvda\addons\BrailleExtender\globalPlugins\brailleExtender\documentFormatting.py", line 249, in update
    fn(self)
  File "C:\Users\andre\AppData\Roaming\nvda\addons\BrailleExtender\globalPlugins\brailleExtender\patches.py", line 277, in update_TextInfoRegion
    self._addTextWithFields(chunk, formatConfig)
  File "C:\Users\andre\AppData\Roaming\nvda\addons\BrailleExtender\globalPlugins\brailleExtender\documentFormatting.py", line 246, in addTextWithFields_edit
    fn(self, info, formatConfig_, isSelection)
  File "C:\Users\andre\AppData\Roaming\nvda\addons\BrailleExtender\globalPlugins\brailleExtender\patches.py", line 713, in _addTextWithFields
    for command in info.getTextWithFields(formatConfig=formatConfig):
  File "NVDAObjects\UIA\__init__.pyc", line 718, in getTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 706, in _getTextWithFieldsForUIARange
  File "NVDAObjects\UIA\__init__.pyc", line 491, in _getTextWithFields_text
  File "NVDAObjects\UIA\__init__.pyc", line 457, in _getTextFromUIARange
  File "comtypesMonkeyPatches.pyc", line 26, in __call__

DEBUG - _UIAHandler.UIAHandler.IUIAutomationPropertyChangedEventHandler_HandlePropertyChangedEvent (20:39:26.572) - Dummy-2 (4712):
HandlePropertyChangedEvent: Ignoring event valueChange for shouldAcceptEvent=False
DEBUG - _UIAHandler.UIAHandler.IUIAutomationPropertyChangedEventHandler_HandlePropertyChangedEvent (20:39:27.576) - Dummy-2 (4712):
HandlePropertyChangedEvent: Ignoring event valueChange for shouldAcceptEvent=False
ERROR - eventHandler.executeEvent (20:39:27.718) - MainThread (1268):
error executing event: caret on <NVDAObjects.Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA object at 0x0780E630> with extra args of {}
Traceback (most recent call last):
  File "eventHandler.pyc", line 246, in executeEvent
  File "eventHandler.pyc", line 96, in __init__
  File "eventHandler.pyc", line 105, in next
  File "NVDAObjects\behaviors.pyc", line 195, in event_caret
  File "NVDAObjects\__init__.pyc", line 1180, in event_caret
  File "review.pyc", line 177, in handleCaretMove
  File "api.pyc", line 215, in setReviewPosition
  File "braille.pyc", line 2036, in handleReviewMove
  File "braille.pyc", line 1963, in _doCursorMove
  File "C:\Users\andre\AppData\Roaming\nvda\addons\BrailleExtender\globalPlugins\brailleExtender\documentFormatting.py", line 249, in update
    fn(self)
  File "C:\Users\andre\AppData\Roaming\nvda\addons\BrailleExtender\globalPlugins\brailleExtender\patches.py", line 277, in update_TextInfoRegion
    self._addTextWithFields(chunk, formatConfig)
  File "C:\Users\andre\AppData\Roaming\nvda\addons\BrailleExtender\globalPlugins\brailleExtender\documentFormatting.py", line 246, in addTextWithFields_edit
    fn(self, info, formatConfig_, isSelection)
  File "C:\Users\andre\AppData\Roaming\nvda\addons\BrailleExtender\globalPlugins\brailleExtender\patches.py", line 713, in _addTextWithFields
    for command in info.getTextWithFields(formatConfig=formatConfig):
  File "NVDAObjects\UIA\__init__.pyc", line 718, in getTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 706, in _getTextWithFieldsForUIARange
  File "NVDAObjects\UIA\__init__.pyc", line 491, in _getTextWithFields_text
  File "NVDAObjects\UIA\__init__.pyc", line 457, in _getTextFromUIARange
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147418113, 'Catastrophic failure', (None, None, None, 0, None))
DEBUGWARNING - NVDAObjects.IAccessible.IAccessible._get_IAccessibleRole (20:39:27.928) - MainThread (1268):
accRole failed: (-2147024809, 'Paramètre incorrect.', (None, None, None, 0, None))
DEBUG - appModuleHandler.cleanup (20:39:27.943) - MainThread (1268):
application conhost closed

(NVDA alpha-21196,4840e5fb, Windows 10, build 20236)

@codeofdusk
Copy link
Contributor

This is really odd. Can you please test again with add-ons disabled?

(I can run emacs just fine in OpenConsole).

@aaclause
Copy link
Contributor Author

OK, I'll try this during this weekend. The bug is random. Before the crash I was able open Emacs about 10 times with no issue.

@aaclause
Copy link
Contributor Author

I can reproduce the issue with Openconsole. Occurred just now (add-ons enabled).

@DHowett
Copy link

DHowett commented Oct 15, 2020

I apologize -- I miscounted the fixes that went into 20136 and osme of the crash fixes are stuck in a later build.

@aaclause aaclause reopened this Oct 16, 2020
@codeofdusk
Copy link
Contributor

@Andre9642 This means the crashes have been fixed, but the fixes still aren't available to you. They should come out in another build or two (probably by the end of November) but @DHowett would know more.

@aaclause
Copy link
Contributor Author

Also, sometimes I get the following error:

ERROR - comtypes._comobject.call_without_this (15:15:19.553) - Dummy-2 (27800):
Exception in IUIAutomationEventHandler.HandleAutomationEvent implementation:
Traceback (most recent call last):
  File "comtypes\_comobject.pyc", line 147, in call_without_this
  File "_UIAHandler.pyc", line 447, in IUIAutomationEventHandler_HandleAutomationEvent
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147220991, 'An event was unable to invoke any of the subscribers', (None, None, None, 0, None))

@codeofdusk
Copy link
Contributor

Build 20241 was recently released with additional fixes (confirmed offline with @DHowett).

@Andre9642 Could you please retest on the new build and report back on the crash?

@aaclause
Copy link
Contributor Author

aaclause commented Oct 24, 2020

Unfortunately, the issue is not solved for me. I am able to reproduce it in a few minutes easily now.

Log (snippets)
INFO - __main__ (10:50:17.029) - MainThread (16336):
Starting NVDA version alpha-21252,111a0f03
[...]
INFO - core.main (10:50:19.353) - MainThread (16336):
Using Windows version 10.0.20241 workstation
[...]
INFO - core.main (10:50:19.627) - MainThread (16336):
Add-ons are disabled. Restart NVDA to enable them.
[...]
ERROR - eventHandler.executeEvent (10:51:01.865) - MainThread (16336):
error executing event: caret on <NVDAObjects.Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA object at 0x08C53370> with extra args of {}
Traceback (most recent call last):
  File "eventHandler.pyc", line 246, in executeEvent
  File "eventHandler.pyc", line 96, in __init__
  File "eventHandler.pyc", line 105, in next
  File "NVDAObjects\behaviors.pyc", line 195, in event_caret
  File "NVDAObjects\__init__.pyc", line 1180, in event_caret
  File "review.pyc", line 177, in handleCaretMove
  File "api.pyc", line 215, in setReviewPosition
  File "braille.pyc", line 2036, in handleReviewMove
  File "braille.pyc", line 1963, in _doCursorMove
  File "braille.pyc", line 1014, in update
  File "NVDAObjects\UIA\__init__.pyc", line 789, in setEndPoint
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (10:51:07.462) - MainThread (16336):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
ERROR - eventHandler.executeEvent (10:51:10.886) - MainThread (16336):
error executing event: caret on <NVDAObjects.Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA object at 0x08C53370> with extra args of {}
Traceback (most recent call last):
  File "eventHandler.pyc", line 246, in executeEvent
  File "eventHandler.pyc", line 96, in __init__
  File "eventHandler.pyc", line 105, in next
  File "NVDAObjects\behaviors.pyc", line 195, in event_caret
  File "NVDAObjects\__init__.pyc", line 1180, in event_caret
  File "review.pyc", line 177, in handleCaretMove
  File "api.pyc", line 215, in setReviewPosition
  File "braille.pyc", line 2036, in handleReviewMove
  File "braille.pyc", line 1963, in _doCursorMove
  File "braille.pyc", line 1042, in update
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - watchdog._watcher (10:51:22.441) - watchdog (15000):
Trying to recover from freeze. Listing stacks for Python threads:
Python stack for thread 6512 (Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA._monitorThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "NVDAObjects\behaviors.pyc", line 312, in _monitor
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 371, in _getTextLines
  File "NVDAObjects\behaviors.pyc", line 276, in _getTextLines
  File "textInfos\__init__.pyc", line 522, in getTextInChunks
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__

Python stack for thread 14980 (watchdog.CancellableCallThread.execute(<CFunctionType object at 0x05B4ACD8>)):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "watchdog.pyc", line 332, in run
  File "threading.pyc", line 552, in wait
  File "threading.pyc", line 296, in wait

Python stack for thread 15000 (watchdog):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "watchdog.pyc", line 127, in _watcher
  File "watchdog.pyc", line 63, in getFormattedStacksForAllThreads

Python stack for thread 14960 (winInputHook):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "winInputHook.pyc", line 79, in hookThreadFunc

Python stack for thread 10384 (_UIAHandler.UIAHandler.MTAThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "_UIAHandler.pyc", line 310, in MTAThreadFunc
  File "queue.pyc", line 170, in get
  File "threading.pyc", line 296, in wait

Python stack for thread 8380 (braille._BgThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "braille.pyc", line 2187, in func

Python stack for thread 16336 (MainThread):
  File "nvda.pyw", line 236, in <module>
  File "core.pyc", line 553, in main
  File "wx\core.pyc", line 2134, in MainLoop
  File "gui\__init__.pyc", line 1062, in Notify
  File "core.pyc", line 523, in run
  File "queueHandler.pyc", line 88, in pumpAll
  File "queueHandler.pyc", line 55, in flushQueue
  File "eventHandler.pyc", line 65, in _queueEventCallback
  File "eventHandler.pyc", line 246, in executeEvent
  File "eventHandler.pyc", line 96, in __init__
  File "eventHandler.pyc", line 105, in next
  File "NVDAObjects\behaviors.pyc", line 195, in event_caret
  File "NVDAObjects\__init__.pyc", line 1180, in event_caret
  File "review.pyc", line 177, in handleCaretMove
  File "api.pyc", line 215, in setReviewPosition
  File "braille.pyc", line 2036, in handleReviewMove
  File "braille.pyc", line 1963, in _doCursorMove
  File "braille.pyc", line 1016, in update
  File "braille.pyc", line 854, in _addTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 718, in getTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 706, in _getTextWithFieldsForUIARange
  File "NVDAObjects\UIA\__init__.pyc", line 491, in _getTextWithFields_text
  File "NVDAObjects\UIA\__init__.pyc", line 457, in _getTextFromUIARange
  File "comtypesMonkeyPatches.pyc", line 26, in __call__

DEBUG - _UIAHandler.UIAHandler.IUIAutomationPropertyChangedEventHandler_HandlePropertyChangedEvent (10:51:22.817) - Dummy-2 (5968):
HandlePropertyChangedEvent: Ignoring event valueChange for shouldAcceptEvent=False
DEBUG - _UIAHandler.UIAHandler.IUIAutomationPropertyChangedEventHandler_HandlePropertyChangedEvent (10:51:23.820) - Dummy-2 (5968):
HandlePropertyChangedEvent: Ignoring event valueChange for shouldAcceptEvent=False
DEBUG - _UIAHandler.UIAHandler.IUIAutomationPropertyChangedEventHandler_HandlePropertyChangedEvent (10:51:24.845) - Dummy-2 (5968):
HandlePropertyChangedEvent: Ignoring event valueChange for shouldAcceptEvent=False
DEBUG - _UIAHandler.UIAHandler.IUIAutomationFocusChangedEventHandler_HandleFocusChangedEvent (10:51:25.165) - Dummy-2 (5968):
HandleFocusChangedEvent: Ignoring for non native element
ERROR - eventHandler.executeEvent (10:51:25.079) - MainThread (16336):
error executing event: caret on <NVDAObjects.Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA object at 0x08C53370> with extra args of {}
Traceback (most recent call last):
  File "eventHandler.pyc", line 246, in executeEvent
  File "eventHandler.pyc", line 96, in __init__
  File "eventHandler.pyc", line 105, in next
  File "NVDAObjects\behaviors.pyc", line 195, in event_caret
  File "NVDAObjects\__init__.pyc", line 1180, in event_caret
  File "review.pyc", line 177, in handleCaretMove
  File "api.pyc", line 215, in setReviewPosition
  File "braille.pyc", line 2036, in handleReviewMove
  File "braille.pyc", line 1963, in _doCursorMove
  File "braille.pyc", line 1016, in update
  File "braille.pyc", line 854, in _addTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 718, in getTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 706, in _getTextWithFieldsForUIARange
  File "NVDAObjects\UIA\__init__.pyc", line 491, in _getTextWithFields_text
  File "NVDAObjects\UIA\__init__.pyc", line 457, in _getTextFromUIARange
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147418113, 'Catastrophic Failure', (None, None, None, 0, None))
DEBUGWARNING - NVDAObjects.IAccessible.IAccessible._get_IAccessibleRole (10:51:25.246) - MainThread (16336):
accRole failed: (-2147024809, 'The parameter is incorrect.', (None, None, None, 0, None))
DEBUG - appModuleHandler.cleanup (10:51:25.315) - MainThread (16336):
application conhost closed

Here's what I Did during this NVDA session until the crash:

  1. Started NVDA with add-ons disabled, UIA events enabled and language interface set to English (I'm French).
  2. Pressed kb:windows+r.
  3. Typed "cmd" then kb:enter.
  4. Pressed kb:NVDA+Control+t until — Braille tethered to review —.
  5. Opened a SSH connection.
  6. Opened a file with nano (typed "nano foo.bar").
  7. Closed the file (pressed kb:control+x)
  8. Repeated steps 5 and 6 several times (about 15 times).

I kept this (full) log if you need more details.

@codeofdusk
Copy link
Contributor

I can now reproduce the crash, however only with my physical Braille display connected (i.e. not with Braille viewer running).

Your log suggests that the console is crashing in moveEndPointByRange: line 789 of uia/__init__.py reads:

		self._rangeObj.MoveEndpointByRange(src,other._rangeObj,target)

Since this crash happens during a switch to/from the alt buffer, I suspect it is an uncaught fail fast in conhost, but see the following lines in the console source code (starting at around line 677 of types/UiaTextRangeBase.cpp:

IFACEMETHODIMP UiaTextRangeBase::MoveEndpointByRange(_In_ TextPatternRangeEndpoint endpoint,
                                                     _In_ ITextRangeProvider* pTargetRange,
                                                     _In_ TextPatternRangeEndpoint targetEndpoint) noexcept
try
{
    _pData->LockConsole();
    auto Unlock = wil::scope_exit([&]() noexcept {
        _pData->UnlockConsole();
    });

    const UiaTextRangeBase* range = static_cast<UiaTextRangeBase*>(pTargetRange);
    if (range == nullptr)
    {
        return E_INVALIDARG;
    }

    // TODO GH#5406: create a different UIA parent object for each TextBuffer
    //   This is a temporary solution to comparing two UTRs from different TextBuffers
    //   Ensure both endpoints fit in the current buffer.
    const auto bufferSize = _pData->GetTextBuffer().GetSize();
    const auto mine = GetEndpoint(endpoint);
    const auto other = range->GetEndpoint(targetEndpoint);
    if (!bufferSize.IsInBounds(mine, true) || !bufferSize.IsInBounds(other, true))
    {
        return E_FAIL;
    }

So that must not be it...

@carlos-zamora or @DHowett, Is there a way I can collect a stack trace to see where in the console code this is breaking? (since it only crashes with a display connected and not when using the Braille viewer, I'd be willing to let you remotely connect to my machine for data gathering/debugging purposes if necessary).

@codeofdusk
Copy link
Contributor

Can you please try this build and let me know if the crash is fixed?

@aaclause
Copy link
Contributor Author

I can still reproduce the issue. However it seems to happen after a longer period. I was able to open/exit nano ~40-50 times before crash (based on 4 tests).

Log (snippets)
INFO - __main__ (07:15:41.351) - MainThread (19612):
Starting NVDA version alpha-21261,2d7d2988
[...]
INFO - core.main (07:15:43.349) - MainThread (19612):
Add-ons are disabled. Restart NVDA to enable them.
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:16:27.881) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:16:31.593) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
ERROR - core.CorePump.run (07:16:37.459) - MainThread (19612):
errors in this core pump cycle
Traceback (most recent call last):
  File "core.pyc", line 525, in run
  File "braille.pyc", line 2221, in pumpAll
  File "braille.pyc", line 1957, in handlePendingCaretUpdate
  File "braille.pyc", line 1963, in _doCursorMove
  File "braille.pyc", line 1014, in update
  File "NVDAObjects\UIA\__init__.pyc", line 789, in setEndPoint
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:16:39.006) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
ERROR - eventHandler.executeEvent (07:16:43.793) - MainThread (19612):
error executing event: caret on <NVDAObjects.Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA object at 0x08DE04D0> with extra args of {}
Traceback (most recent call last):
  File "eventHandler.pyc", line 246, in executeEvent
  File "eventHandler.pyc", line 96, in __init__
  File "eventHandler.pyc", line 105, in next
  File "NVDAObjects\behaviors.pyc", line 195, in event_caret
  File "NVDAObjects\__init__.pyc", line 1180, in event_caret
  File "review.pyc", line 174, in handleCaretMove
  File "documentBase.pyc", line 24, in makeTextInfo
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 37, in __init__
  File "NVDAObjects\UIA\__init__.pyc", line 313, in __init__
  File "NVDAObjects\UIA\__init__.pyc", line 767, in collapse
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:16:55.456) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:16:57.557) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:16:59.148) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:16:59.689) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
ERROR - NVDAObjects.behaviors.LiveText._monitor (07:16:59.659) - Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA._monitorThread (12104):
Error getting lines or calculating new text
Traceback (most recent call last):
  File "NVDAObjects\behaviors.pyc", line 312, in _monitor
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 371, in _getTextLines
  File "NVDAObjects\behaviors.pyc", line 276, in _getTextLines
  File "textInfos\__init__.pyc", line 525, in getTextInChunks
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
ERROR - core.CorePump.run (07:17:00.798) - MainThread (19612):
errors in this core pump cycle
Traceback (most recent call last):
  File "core.pyc", line 525, in run
  File "braille.pyc", line 2221, in pumpAll
  File "braille.pyc", line 1957, in handlePendingCaretUpdate
  File "braille.pyc", line 1963, in _doCursorMove
  File "braille.pyc", line 1041, in update
  File "documentBase.pyc", line 24, in makeTextInfo
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 39, in __init__
  File "NVDAObjects\UIA\__init__.pyc", line 767, in collapse
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:17:02.338) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:17:06.578) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:17:08.193) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:17:14.011) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:17:21.412) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:17:26.177) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:17:32.536) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:17:38.374) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - NVDAObjects.UIA.winConsoleUIA.WinConsoleUIA.detectPossibleSelectionChange (07:17:39.957) - MainThread (19612):
Exception raised when comparing selections, probably due to a switch to/from the alt buffer.
Traceback (most recent call last):
  File "NVDAObjects\UIA\winConsoleUIA.pyc", line 382, in detectPossibleSelectionChange
  File "editableText.pyc", line 348, in detectPossibleSelectionChange
  File "editableText.pyc", line 355, in _updateSelectionAnchor
  File "NVDAObjects\UIA\__init__.pyc", line 778, in compareEndPoints
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147467259, 'Unspecified error', (None, None, None, 0, None))
[...]
DEBUGWARNING - watchdog._watcher (07:17:43.117) - watchdog (21264):
Trying to recover from freeze. Listing stacks for Python threads:
Python stack for thread 14368 (Dummy-2):
  File "comtypes\_comobject.pyc", line 147, in call_without_this
  File "_UIAHandler.pyc", line 456, in IUIAutomationEventHandler_HandleAutomationEvent
  File "_UIAHandler.pyc", line 737, in getNearestWindowHandle
  File "comtypesMonkeyPatches.pyc", line 26, in __call__

Python stack for thread 12104 (Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA._monitorThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "NVDAObjects\behaviors.pyc", line 300, in _monitor
  File "threading.pyc", line 552, in wait
  File "threading.pyc", line 296, in wait

Python stack for thread 21264 (watchdog):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "watchdog.pyc", line 127, in _watcher
  File "watchdog.pyc", line 63, in getFormattedStacksForAllThreads

Python stack for thread 6088 (winInputHook):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "winInputHook.pyc", line 79, in hookThreadFunc

Python stack for thread 5400 (_UIAHandler.UIAHandler.MTAThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "_UIAHandler.pyc", line 310, in MTAThreadFunc
  File "queue.pyc", line 170, in get
  File "threading.pyc", line 296, in wait

Python stack for thread 10272 (braille._BgThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "braille.pyc", line 2187, in func

Python stack for thread 19612 (MainThread):
  File "nvda.pyw", line 236, in <module>
  File "core.pyc", line 553, in main
  File "wx\core.pyc", line 2134, in MainLoop
  File "gui\__init__.pyc", line 1062, in Notify
  File "core.pyc", line 525, in run
  File "braille.pyc", line 2221, in pumpAll
  File "braille.pyc", line 1957, in handlePendingCaretUpdate
  File "braille.pyc", line 1963, in _doCursorMove
  File "braille.pyc", line 1016, in update
  File "braille.pyc", line 854, in _addTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 718, in getTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 706, in _getTextWithFieldsForUIARange
  File "NVDAObjects\UIA\__init__.pyc", line 491, in _getTextWithFields_text
  File "NVDAObjects\UIA\__init__.pyc", line 457, in _getTextFromUIARange
  File "comtypesMonkeyPatches.pyc", line 26, in __call__

DEBUGWARNING - NVDAObjects.__call__ (07:17:45.383) - Dummy-2 (14368):
Invalid NVDAObject: no windowHandle
Traceback (most recent call last):
  File "NVDAObjects\__init__.pyc", line 82, in __call__
  File "NVDAObjects\UIA\__init__.pyc", line 1060, in __init__
NVDAObjects.InvalidNVDAObject: no windowHandle
DEBUG - _UIAHandler.UIAHandler.IUIAutomationEventHandler_HandleAutomationEvent (07:17:45.385) - Dummy-2 (14368):
HandleAutomationEvent: Ignoring event textChange because no object or ignored by object itself
DEBUGWARNING - NVDAObjects.__call__ (07:17:45.388) - Dummy-2 (14368):
Invalid NVDAObject: no windowHandle
Traceback (most recent call last):
  File "NVDAObjects\__init__.pyc", line 82, in __call__
  File "NVDAObjects\UIA\__init__.pyc", line 1060, in __init__
NVDAObjects.InvalidNVDAObject: no windowHandle
DEBUG - _UIAHandler.UIAHandler.IUIAutomationEventHandler_HandleAutomationEvent (07:17:45.388) - Dummy-2 (14368):
HandleAutomationEvent: Ignoring event textChange because no object or ignored by object itself
DEBUGWARNING - NVDAObjects.__call__ (07:17:45.391) - Dummy-2 (14368):
Invalid NVDAObject: no windowHandle
Traceback (most recent call last):
  File "NVDAObjects\__init__.pyc", line 82, in __call__
  File "NVDAObjects\UIA\__init__.pyc", line 1060, in __init__
NVDAObjects.InvalidNVDAObject: no windowHandle
DEBUG - _UIAHandler.UIAHandler.IUIAutomationEventHandler_HandleAutomationEvent (07:17:45.391) - Dummy-2 (14368):
HandleAutomationEvent: Ignoring event textChange because no object or ignored by object itself
ERROR - core.CorePump.run (07:17:45.376) - MainThread (19612):
errors in this core pump cycle
Traceback (most recent call last):
  File "core.pyc", line 525, in run
  File "braille.pyc", line 2221, in pumpAll
  File "braille.pyc", line 1957, in handlePendingCaretUpdate
  File "braille.pyc", line 1963, in _doCursorMove
  File "braille.pyc", line 1016, in update
  File "braille.pyc", line 854, in _addTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 718, in getTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 706, in _getTextWithFieldsForUIARange
  File "NVDAObjects\UIA\__init__.pyc", line 491, in _getTextWithFields_text
  File "NVDAObjects\UIA\__init__.pyc", line 457, in _getTextFromUIARange
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2147418113, 'Catastrophic Failure', (None, None, None, 0, None))
DEBUGWARNING - NVDAObjects.IAccessible.IAccessible._get_IAccessibleRole (07:17:45.574) - MainThread (19612):
accRole failed: (-2147024809, 'The parameter is incorrect.', (None, None, None, 0, None))
DEBUG - appModuleHandler.cleanup (07:17:45.690) - MainThread (19612):
application openconsole closed

@codeofdusk
Copy link
Contributor

I cannot reproduce the issue with the linked build, even when running/exiting Nano over 100 times.

@codeofdusk
Copy link
Contributor

Just for consistency in setup, could you please run the following in the Python console (nvda+control+z) and put the output here?

{k: v for k,v in config.conf["documentFormatting"].items()}

@aaclause
Copy link
Contributor Author

>>> {k: v for k,v in config.conf["documentFormatting"].items()}
{'reportTableCellCoords': True, 'reportClickable': True, 'reportTableHeaders': True, 'reportTables': True, 'autoLanguageSwitching': 'False', 'reportArticles': True, 'reportFontAttributes': True, 'reportSuperscriptsAndSubscripts': True, 'reportColor': False, 'reportEmphasis': True, 'reportStyle': False, 'reportLinks': True, 'reportBorderStyle': False, 'reportBorderColor': False, 'reportAlignment': True, 'reportLineIndentationWithTones': False, 'reportParagraphIndentation': True, 'reportLineSpacing': True, 'reportLineNumber': False, 'detectFormatAfterCursor': False, 'reportLineIndentation': False, 'reportFontName': False, 'reportFontSize': False, 'reportRevisions': True, 'reportHighlight': True, 'reportSpellingErrors': True, 'reportPage': True, 'includeLayoutTables': False, 'reportGraphics': True, 'reportComments': True, 'reportLists': True, 'reportHeadings': True, 'reportBlockQuotes': True, 'reportGroupings': True, 'reportLandmarks': True, 'reportFrames': True}

@aaclause
Copy link
Contributor Author

aaclause commented Oct 27, 2020

I've just created a portable version of NVDA (without my configuration) and I'm unable to reproduce the issue (running/exiting Nano over 100 times too).

@codeofdusk
Copy link
Contributor

codeofdusk commented Oct 27, 2020

I've copied your exact config, using:

console fragment
d = {'reportTableCellCoords': True, 'reportClickable': True, 'reportTableHeaders': True, 'reportTables': True, 'autoLanguageSwitching': 'False', 'reportArticles': True, 'reportFontAttributes': True, 'reportSuperscriptsAndSubscripts': True, 'reportColor': False, 'reportEmphasis': True, 'reportStyle': False, 'reportLinks': True, 'reportBorderStyle': False, 'reportBorderColor': False, 'reportAlignment': True, 'reportLineIndentationWithTones': False, 'reportParagraphIndentation': True, 'reportLineSpacing': True, 'reportLineNumber': False, 'detectFormatAfterCursor': False, 'reportLineIndentation': False, 'reportFontName': False, 'reportFontSize': False, 'reportRevisions': True, 'reportHighlight': True, 'reportSpellingErrors': True, 'reportPage': True, 'includeLayoutTables': False, 'reportGraphics': True, 'reportComments': True, 'reportLists': True, 'reportHeadings': True, 'reportBlockQuotes': True, 'reportGroupings': True, 'reportLandmarks': True, 'reportFrames': True}
for k, v in d.items():
    config.conf["documentFormatting"][k] = v

With a Braille display connected, in that build of UIA console, I still can't reproduce the crash running over 100 times. So it must not be formatting settings.

Could you please paste the contents of your nvda.ini file in %appdata%\nvda?

ghost pushed a commit to microsoft/terminal that referenced this issue Oct 27, 2020
In nvaccess/nvda#11428 (comment),
Andre9642 reported a Conhost crash when switching to/from the alt buffer
a few times with a Braille display connected. Upon further
investigation, @carlos-zamora and I discovered that the FailFast was in
`GetText`: more checks similar to #7677 were needed for this case.

Tested with NVDA using a [Focus](https://www.freedomscientific.com/products/blindness/focus40brailledisplay/) Braille display.

Improves nvaccess/nvda#11428
DHowett pushed a commit to microsoft/terminal that referenced this issue Oct 28, 2020
In nvaccess/nvda#11428 (comment),
Andre9642 reported a Conhost crash when switching to/from the alt buffer
a few times with a Braille display connected. Upon further
investigation, @carlos-zamora and I discovered that the FailFast was in
`GetText`: more checks similar to #7677 were needed for this case.

Tested with NVDA using a [Focus](https://www.freedomscientific.com/products/blindness/focus40brailledisplay/) Braille display.

Improves nvaccess/nvda#11428

(cherry picked from commit 60437b8)
@aaclause
Copy link
Contributor Author

Sorry, I forgot to enable "Use UI Automation to access the Windows Console when available" on my fresh copy of NVDA. I can reproduce the issue with no personal customization.

Also during a test, I received a serious freeze (about 30 seconds)...

Log extract
DEBUGWARNING - watchdog._watcher (09:42:36.473) - watchdog (18896):
Trying to recover from freeze. Listing stacks for Python threads:
Python stack for thread 19432 (Dummy-1):
  File "comtypes\_comobject.pyc", line 147, in call_without_this
  File "_UIAHandler.pyc", line 456, in IUIAutomationEventHandler_HandleAutomationEvent
  File "_UIAHandler.pyc", line 737, in getNearestWindowHandle
  File "comtypesMonkeyPatches.pyc", line 26, in __call__

Python stack for thread 9664 (Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA._monitorThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "NVDAObjects\behaviors.pyc", line 300, in _monitor
  File "threading.pyc", line 552, in wait
  File "threading.pyc", line 296, in wait

Python stack for thread 22504 (synthDrivers._espeak.BgThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "synthDrivers\_espeak.pyc", line 191, in run
  File "queue.pyc", line 170, in get
  File "threading.pyc", line 296, in wait

Python stack for thread 460 (watchdog.CancellableCallThread.execute(<_FuncPtr object at 0x09CF7B70>)):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "watchdog.pyc", line 332, in run
  File "threading.pyc", line 552, in wait
  File "threading.pyc", line 296, in wait

Python stack for thread 18896 (watchdog):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "watchdog.pyc", line 127, in _watcher
  File "watchdog.pyc", line 63, in getFormattedStacksForAllThreads

Python stack for thread 16376 (winInputHook):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "winInputHook.pyc", line 79, in hookThreadFunc

Python stack for thread 22336 (_UIAHandler.UIAHandler.MTAThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "_UIAHandler.pyc", line 310, in MTAThreadFunc
  File "queue.pyc", line 170, in get
  File "threading.pyc", line 296, in wait

Python stack for thread 19496 (braille._BgThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "braille.pyc", line 2187, in func

Python stack for thread 1236 (MainThread):
  File "nvda.pyw", line 236, in <module>
  File "core.pyc", line 553, in main
  File "wx\core.pyc", line 2134, in MainLoop
  File "gui\__init__.pyc", line 1062, in Notify
  File "core.pyc", line 523, in run
  File "queueHandler.pyc", line 88, in pumpAll
  File "queueHandler.pyc", line 55, in flushQueue
  File "eventHandler.pyc", line 65, in _queueEventCallback
  File "eventHandler.pyc", line 246, in executeEvent
  File "eventHandler.pyc", line 96, in __init__
  File "eventHandler.pyc", line 105, in next
  File "NVDAObjects\behaviors.pyc", line 195, in event_caret
  File "NVDAObjects\__init__.pyc", line 1180, in event_caret
  File "review.pyc", line 177, in handleCaretMove
  File "api.pyc", line 215, in setReviewPosition
  File "braille.pyc", line 2036, in handleReviewMove
  File "braille.pyc", line 1963, in _doCursorMove
  File "braille.pyc", line 1016, in update
  File "braille.pyc", line 854, in _addTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 718, in getTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 706, in _getTextWithFieldsForUIARange
  File "NVDAObjects\UIA\__init__.pyc", line 491, in _getTextWithFields_text
  File "NVDAObjects\UIA\__init__.pyc", line 457, in _getTextFromUIARange
  File "comtypesMonkeyPatches.pyc", line 26, in __call__

ERROR - watchdog._watcher (09:42:51.486) - watchdog (18896):
Core frozen in stack!
INFO - watchdog._watcher (09:42:51.533) - watchdog (18896):
Listing stacks for Python threads:
Python stack for thread 15768 (nvwave.playWaveFile(error.wav)):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "nvwave.pyc", line 426, in idle
  File "nvwave.pyc", line 436, in _idleUnbuffered
  File "nvwave.pyc", line 386, in sync
  File "winKernel.pyc", line 228, in waitForSingleObject

Python stack for thread 19432 (Dummy-1):
  File "comtypes\_comobject.pyc", line 147, in call_without_this
  File "_UIAHandler.pyc", line 456, in IUIAutomationEventHandler_HandleAutomationEvent
  File "_UIAHandler.pyc", line 737, in getNearestWindowHandle
  File "comtypesMonkeyPatches.pyc", line 26, in __call__

Python stack for thread 9664 (Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA._monitorThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "NVDAObjects\behaviors.pyc", line 300, in _monitor
  File "threading.pyc", line 552, in wait
  File "threading.pyc", line 296, in wait

Python stack for thread 22504 (synthDrivers._espeak.BgThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "synthDrivers\_espeak.pyc", line 191, in run
  File "queue.pyc", line 170, in get
  File "threading.pyc", line 296, in wait

Python stack for thread 460 (watchdog.CancellableCallThread.execute(<_FuncPtr object at 0x09CF7B70>)):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "watchdog.pyc", line 332, in run
  File "threading.pyc", line 552, in wait
  File "threading.pyc", line 296, in wait

Python stack for thread 18896 (watchdog):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "watchdog.pyc", line 142, in _watcher
  File "watchdog.pyc", line 63, in getFormattedStacksForAllThreads

Python stack for thread 16376 (winInputHook):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "winInputHook.pyc", line 79, in hookThreadFunc

Python stack for thread 22336 (_UIAHandler.UIAHandler.MTAThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "_UIAHandler.pyc", line 310, in MTAThreadFunc
  File "queue.pyc", line 170, in get
  File "threading.pyc", line 296, in wait

Python stack for thread 19496 (braille._BgThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "braille.pyc", line 2187, in func

Python stack for thread 1236 (MainThread):
  File "nvda.pyw", line 236, in <module>
  File "core.pyc", line 553, in main
  File "wx\core.pyc", line 2134, in MainLoop
  File "gui\__init__.pyc", line 1062, in Notify
  File "core.pyc", line 523, in run
  File "queueHandler.pyc", line 88, in pumpAll
  File "queueHandler.pyc", line 55, in flushQueue
  File "eventHandler.pyc", line 65, in _queueEventCallback
  File "eventHandler.pyc", line 246, in executeEvent
  File "eventHandler.pyc", line 96, in __init__
  File "eventHandler.pyc", line 105, in next
  File "NVDAObjects\behaviors.pyc", line 195, in event_caret
  File "NVDAObjects\__init__.pyc", line 1180, in event_caret
  File "review.pyc", line 177, in handleCaretMove
  File "api.pyc", line 215, in setReviewPosition
  File "braille.pyc", line 2036, in handleReviewMove
  File "braille.pyc", line 1963, in _doCursorMove
  File "braille.pyc", line 1016, in update
  File "braille.pyc", line 854, in _addTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 718, in getTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 706, in _getTextWithFieldsForUIARange
  File "NVDAObjects\UIA\__init__.pyc", line 491, in _getTextWithFields_text
  File "NVDAObjects\UIA\__init__.pyc", line 457, in _getTextFromUIARange
  File "comtypesMonkeyPatches.pyc", line 26, in __call__

DEBUGWARNING - NVDAObjects.__call__ (09:42:56.111) - Dummy-1 (19432):
Invalid NVDAObject: no windowHandle
Traceback (most recent call last):
  File "NVDAObjects\__init__.pyc", line 82, in __call__
  File "NVDAObjects\UIA\__init__.pyc", line 1060, in __init__
NVDAObjects.InvalidNVDAObject: no windowHandle
DEBUGWARNING - NVDAObjects.__call__ (09:42:56.116) - Dummy-1 (19432):
Invalid NVDAObject: no windowHandle
Traceback (most recent call last):
  File "NVDAObjects\__init__.pyc", line 82, in __call__
  File "NVDAObjects\UIA\__init__.pyc", line 1060, in __init__
NVDAObjects.InvalidNVDAObject: no windowHandle
DEBUGWARNING - NVDAObjects.__call__ (09:42:56.119) - Dummy-1 (19432):
Invalid NVDAObject: no windowHandle
Traceback (most recent call last):
  File "NVDAObjects\__init__.pyc", line 82, in __call__
  File "NVDAObjects\UIA\__init__.pyc", line 1060, in __init__
NVDAObjects.InvalidNVDAObject: no windowHandle
ERROR - eventHandler.executeEvent (09:42:56.103) - MainThread (1236):
error executing event: caret on <NVDAObjects.Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA object at 0x014B17D0> with extra args of {}
Traceback (most recent call last):
  File "eventHandler.pyc", line 246, in executeEvent
  File "eventHandler.pyc", line 96, in __init__
  File "eventHandler.pyc", line 105, in next
  File "NVDAObjects\behaviors.pyc", line 195, in event_caret
  File "NVDAObjects\__init__.pyc", line 1180, in event_caret
  File "review.pyc", line 177, in handleCaretMove
  File "api.pyc", line 215, in setReviewPosition
  File "braille.pyc", line 2036, in handleReviewMove
  File "braille.pyc", line 1963, in _doCursorMove
  File "braille.pyc", line 1016, in update
  File "braille.pyc", line 854, in _addTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 718, in getTextWithFields
  File "NVDAObjects\UIA\__init__.pyc", line 706, in _getTextWithFieldsForUIARange
  File "NVDAObjects\UIA\__init__.pyc", line 491, in _getTextWithFields_text
  File "NVDAObjects\UIA\__init__.pyc", line 457, in _getTextFromUIARange
  File "comtypesMonkeyPatches.pyc", line 26, in __call__
_ctypes.COMError: (-2146233083, None, (None, None, None, 0, None))
DEBUGWARNING - watchdog._watcher (09:42:56.683) - watchdog (18896):
Trying to recover from freeze. Listing stacks for Python threads:
Python stack for thread 9664 (Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA._monitorThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "NVDAObjects\behaviors.pyc", line 300, in _monitor
  File "threading.pyc", line 552, in wait
  File "threading.pyc", line 296, in wait

Python stack for thread 22504 (synthDrivers._espeak.BgThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "synthDrivers\_espeak.pyc", line 191, in run
  File "queue.pyc", line 170, in get
  File "threading.pyc", line 296, in wait

Python stack for thread 460 (watchdog.CancellableCallThread.execute(<_FuncPtr object at 0x09CF7B70>)):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "watchdog.pyc", line 332, in run
  File "threading.pyc", line 552, in wait
  File "threading.pyc", line 296, in wait

Python stack for thread 18896 (watchdog):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "watchdog.pyc", line 127, in _watcher
  File "watchdog.pyc", line 63, in getFormattedStacksForAllThreads

Python stack for thread 16376 (winInputHook):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "winInputHook.pyc", line 79, in hookThreadFunc

Python stack for thread 22336 (_UIAHandler.UIAHandler.MTAThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "_UIAHandler.pyc", line 310, in MTAThreadFunc
  File "queue.pyc", line 170, in get
  File "threading.pyc", line 296, in wait

Python stack for thread 19496 (braille._BgThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "braille.pyc", line 2187, in func

Python stack for thread 1236 (MainThread):
  File "nvda.pyw", line 236, in <module>
  File "core.pyc", line 553, in main
  File "wx\core.pyc", line 2134, in MainLoop
  File "gui\__init__.pyc", line 1062, in Notify
  File "core.pyc", line 522, in run
  File "IAccessibleHandler\__init__.pyc", line 1099, in pumpAll
  File "IAccessibleHandler\__init__.pyc", line 691, in processGenericWinEvent
  File "IAccessibleHandler\__init__.pyc", line 636, in winEventToNVDAEvent
  File "NVDAObjects\IAccessible\__init__.pyc", line 42, in getNVDAObjectFromEvent
  File "IAccessibleHandler\__init__.pyc", line 412, in accessibleObjectFromEvent
  File "oleacc.pyc", line 265, in AccessibleObjectFromEvent

ERROR - watchdog._watcher (09:43:11.728) - watchdog (18896):
Core frozen in stack!
INFO - watchdog._watcher (09:43:11.776) - watchdog (18896):
Listing stacks for Python threads:
Python stack for thread 19028 (nvwave.playWaveFile(error.wav)):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "nvwave.pyc", line 426, in idle
  File "nvwave.pyc", line 436, in _idleUnbuffered
  File "nvwave.pyc", line 386, in sync
  File "winKernel.pyc", line 228, in waitForSingleObject

Python stack for thread 9664 (Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA._monitorThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "NVDAObjects\behaviors.pyc", line 300, in _monitor
  File "threading.pyc", line 552, in wait
  File "threading.pyc", line 296, in wait

Python stack for thread 22504 (synthDrivers._espeak.BgThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "synthDrivers\_espeak.pyc", line 191, in run
  File "queue.pyc", line 170, in get
  File "threading.pyc", line 296, in wait

Python stack for thread 460 (watchdog.CancellableCallThread.execute(<_FuncPtr object at 0x09CF7B70>)):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "watchdog.pyc", line 332, in run
  File "threading.pyc", line 552, in wait
  File "threading.pyc", line 296, in wait

Python stack for thread 18896 (watchdog):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "watchdog.pyc", line 142, in _watcher
  File "watchdog.pyc", line 63, in getFormattedStacksForAllThreads

Python stack for thread 16376 (winInputHook):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "winInputHook.pyc", line 79, in hookThreadFunc

Python stack for thread 22336 (_UIAHandler.UIAHandler.MTAThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "_UIAHandler.pyc", line 310, in MTAThreadFunc
  File "queue.pyc", line 170, in get
  File "threading.pyc", line 296, in wait

Python stack for thread 19496 (braille._BgThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "braille.pyc", line 2187, in func

Python stack for thread 1236 (MainThread):
  File "nvda.pyw", line 236, in <module>
  File "core.pyc", line 553, in main
  File "wx\core.pyc", line 2134, in MainLoop
  File "gui\__init__.pyc", line 1062, in Notify
  File "core.pyc", line 522, in run
  File "IAccessibleHandler\__init__.pyc", line 1099, in pumpAll
  File "IAccessibleHandler\__init__.pyc", line 691, in processGenericWinEvent
  File "IAccessibleHandler\__init__.pyc", line 636, in winEventToNVDAEvent
  File "NVDAObjects\IAccessible\__init__.pyc", line 42, in getNVDAObjectFromEvent
  File "IAccessibleHandler\__init__.pyc", line 412, in accessibleObjectFromEvent
  File "oleacc.pyc", line 265, in AccessibleObjectFromEvent

ERROR - watchdog._watcher (09:43:26.746) - watchdog (18896):
Core frozen in stack!
INFO - watchdog._watcher (09:43:26.802) - watchdog (18896):
Listing stacks for Python threads:
Python stack for thread 10672 (nvwave.playWaveFile(error.wav)):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "nvwave.pyc", line 426, in idle
  File "nvwave.pyc", line 436, in _idleUnbuffered
  File "nvwave.pyc", line 386, in sync
  File "winKernel.pyc", line 228, in waitForSingleObject

Python stack for thread 9664 (Dynamic_WinConsoleUIAEditableTextWithAutoSelectDetectionUIA._monitorThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "NVDAObjects\behaviors.pyc", line 300, in _monitor
  File "threading.pyc", line 552, in wait
  File "threading.pyc", line 296, in wait

Python stack for thread 22504 (synthDrivers._espeak.BgThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "synthDrivers\_espeak.pyc", line 191, in run
  File "queue.pyc", line 170, in get
  File "threading.pyc", line 296, in wait

Python stack for thread 460 (watchdog.CancellableCallThread.execute(<_FuncPtr object at 0x09CF7B70>)):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "watchdog.pyc", line 332, in run
  File "threading.pyc", line 552, in wait
  File "threading.pyc", line 296, in wait

Python stack for thread 18896 (watchdog):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "watchdog.pyc", line 142, in _watcher
  File "watchdog.pyc", line 63, in getFormattedStacksForAllThreads

Python stack for thread 16376 (winInputHook):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "winInputHook.pyc", line 79, in hookThreadFunc

Python stack for thread 22336 (_UIAHandler.UIAHandler.MTAThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "_UIAHandler.pyc", line 310, in MTAThreadFunc
  File "queue.pyc", line 170, in get
  File "threading.pyc", line 296, in wait

Python stack for thread 19496 (braille._BgThread):
  File "threading.pyc", line 890, in _bootstrap
  File "threading.pyc", line 926, in _bootstrap_inner
  File "threading.pyc", line 870, in run
  File "braille.pyc", line 2187, in func

Python stack for thread 1236 (MainThread):
  File "nvda.pyw", line 236, in <module>
  File "core.pyc", line 553, in main
  File "wx\core.pyc", line 2134, in MainLoop
  File "gui\__init__.pyc", line 1062, in Notify
  File "core.pyc", line 522, in run
  File "IAccessibleHandler\__init__.pyc", line 1099, in pumpAll
  File "IAccessibleHandler\__init__.pyc", line 691, in processGenericWinEvent
  File "IAccessibleHandler\__init__.pyc", line 636, in winEventToNVDAEvent
  File "NVDAObjects\IAccessible\__init__.pyc", line 42, in getNVDAObjectFromEvent
  File "IAccessibleHandler\__init__.pyc", line 412, in accessibleObjectFromEvent
  File "oleacc.pyc", line 265, in AccessibleObjectFromEvent

DEBUG - appModuleHandler.cleanup (09:43:28.802) - MainThread (1236):
application openconsole closed

For reference, here's my minimal configuration (on this copy):

schemaVersion = 4
[update]
	autoCheck = True
	startupNotification = True
	allowUsageStats = False
	askedAllowUsageStats = True
[general]
	language = en
	saveConfigurationOnExit = True
	askToExit = True
	playStartAndExitSounds = False
	loggingLevel = DEBUG
	showWelcomeDialogAtStartup = False
[development]
	enableScratchpadDir = False
[upgrade]
[speech]
	synth = espeak
	outputDevice = Mappeur de sons Microsoft
	[[oneCore]]
		voice = HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\Speech_OneCore\Voices\Tokens\MSTTS_V110_frFR_HortenseM
		volume = 100
	[[espeak]]
		voice = fr
		variant = max
		rate = 70
		pitch = 40
		inflection = 75
		volume = 100
[braille]
	[[noBraille]]
	[[baum]]
[keyboard]
	keyboardLayout = laptop
	useCapsLockAsNVDAModifierKey = True
	speakTypedCharacters = False
[vision]
	[[NVDAHighlighter]]
		highlightFocus = False
		highlightNavigator = False
		highlightBrowseMode = False
	[[screenCurtain]]
[UIA]
	useInMSWordWhenAvailable = True
	winConsoleImplementation = UIA
[debugLog]
	UIA = True

@codeofdusk
Copy link
Contributor

OK, please try the following:

  1. Download and extract this build.
  2. Open (and add to registry) EnableCrashDumps.reg (feel free to view it in notepad, it's mostly human-readable).
  3. Run OpenConsole.exe and reproduce the crash from that console.
    • this is a debug build, so UIA (particularly word navigation) may be very slow. However, the debug symbols available will allow me or Microsoft to find the problem more easily.
  4. Navigate (in Windows Explorer) to %localappdata%\CrashDumps and send the most recent .dmp file for OpenConsole.
    • The file should attach to GitHub, but if it's too big DM me.

Also going to CC @miniksa on this issue.

@aaclause
Copy link
Contributor Author

I'm not familiar with dmp files and I don't know if can contain sensitive data. So I prefer to avoid publicly sharing this. Sent to @codeofdusk in DM.

@aaclause
Copy link
Contributor Author

aaclause commented Feb 1, 2021

Windows 10 Insider 21301.1000, NVDA alpha-21685,90dd4e9a: I'm no longer able to reproduce the crash. Closing.

@aaclause aaclause closed this as completed Feb 1, 2021
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

7 participants