Re: More on the nvdaHelperRemote (outprocMgrThreadFunc) error in new snapshots


Christo de Klerk
 

Hello Joseph

Thank you for responding and showing your readiness to assist.

Sorry, yes, my mistake, at that time I had a next snapshot installed. However, I have just installed the master snapshot as of today and am experiencing exactly the same problem. Here is my NVDA start up log in Debug format:

INFO - __main__ (10:02:39.930):
Starting NVDA
INFO - core.main (10:02:40.417):
Config dir: C:\Users\Christo\AppData\Roaming\nvda
INFO - config.ConfigManager._loadConfig (10:02:40.418):
Loading config: C:\Users\Christo\AppData\Roaming\nvda\nvda.ini
INFO - config.ConfigManager._loadConfig (10:02:40.532):
Config loaded (after upgrade, and in the state it will be used by NVDA):
{u'schemaVersion': u'2', u'upgrade': {}, u'update': {u'autoCheck': u'True'}, u'general': {u'language': u'Windows', u'saveConfigurationOnExit': u'False', u'askToExit': u'True', u'playStartAndExitSounds': u'True', u'loggingLevel': u'DEBUG', u'showWelcomeDialogAtStartup': u'False'}, u'speech': {u'synth': u'vocalizer_expressive', u'outputDevice': u'Microsoft Sound Mapper', u'autoLanguageSwitching': u'False', u'espeak': {u'capPitchChange': u'30', u'rateBoost': u'False', u'inflection': u'75', u'variant': u'john', u'beepForCapitals': u'True', u'volume': u'50', u'rate': u'30', u'useSpellingFunctionality': u'True', u'sayCapForCapitals': u'False', u'pitch': u'40', u'voice': u'en'}, u'vocalizer_expressive': {u'capPitchChange': u'30', u'variant': u'full_vssq5f22', u'beepForCapitals': u'True', u'volume': u'55', u'rate': u'60', u'useSpellingFunctionality': u'True', u'sayCapForCapitals': u'False', u'pitch': u'50', u'voice': u'Tessa'}, u'sapi5': {u'volume': u'60', u'beepForCapitals': u'True', u'capPitchChange': u'30', u'rate': u'60', u'useSpellingFunctionality': u'True', u'sayCapForCapitals': u'False', u'pitch': u'50', u'voice': u'HKEY_LOCAL_MACHINE\\SOFTWARE\\Microsoft\\Speech\\Voices\\Tokens\\IVONA 2 Voice Emma22'}}, u'braille': {u'noBraille': {u'port': u''}}, u'wintenApps': {u'updateCheckTime': u'1519489207'}, u'keyboard': {u'useCapsLockAsNVDAModifierKey': u'True', u'allowSkimReadingInSayAll': u'True'}}
DEBUG - core.main (10:02:40.601):
setting language to Windows
INFO - core.main (10:02:40.604):
NVDA version master-14865,769e09f1
INFO - core.main (10:02:40.604):
Using Windows version 10.0.16299 workstation
INFO - core.main (10:02:40.605):
Using Python version 2.7.14 (v2.7.14:84471935ed, Sep 16 2017, 20:19:30) [MSC v.1500 32 bit (Intel)]
INFO - core.main (10:02:40.605):
Using comtypes version 1.1.3
DEBUG - core.main (10:02:40.617):
Initializing add-ons system
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.621):
Listing add-ons from C:\Users\Christo\AppData\Roaming\nvda\addons
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.621):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\dropbox
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.624):
Found add-on dropbox
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.624):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\easyTableNavigator
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.625):
Found add-on easyTableNavigator
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.625):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\extendedWinamp
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.628):
Found add-on extendedWinamp
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.628):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\goldenCursor
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.631):
Found add-on goldenCursor
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.631):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\goldwave
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.632):
Found add-on goldwave
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.634):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\instantTranslate
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.635):
Found add-on instantTranslate
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.635):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\kurzweil
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.638):
Found add-on kurzweil
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.638):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\Mozilla
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.641):
Found add-on Mozilla
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.641):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\NotepadPlusPlus
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.644):
Found add-on NotepadPlusPlus
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.644):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\remote
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.645):
Found add-on remote
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.645):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\resourceMonitor
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.648):
Found add-on resourceMonitor
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.648):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\speakPasswords
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.650):
Found add-on speakPasswords
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.650):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\switchSynth
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.651):
Found add-on switchSynth
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.653):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\symbols
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.654):
Found add-on symbols
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.654):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\virtualRevision
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.655):
Found add-on virtualRevision
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.657):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\vocalizer-expressive-voice-ellen Premium High
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.660):
Found add-on vocalizer-expressive-voice-ellen Premium High
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.661):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\vocalizer-expressive-voice-nora Premium High
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.661):
Found add-on vocalizer-expressive-voice-nora Premium High
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.663):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\vocalizer-expressive-voice-tessa Premium High
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.664):
Found add-on vocalizer-expressive-voice-tessa Premium High
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.665):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\vocalizer-expressive-voice-xander Premium High
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.667):
Found add-on vocalizer-expressive-voice-xander Premium High
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.667):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\vocalizer_expressive_driver
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.670):
Found add-on vocalizer_expressive_driver
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.671):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\Weather Plus
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.671):
Found add-on Weather Plus
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.673):
Loading add-on from C:\Users\Christo\AppData\Roaming\nvda\addons\wintenApps
DEBUG - addonHandler._getAvailableAddonsFromPath (10:02:40.674):
Found add-on wintenApps
INFO - core.main (10:02:40.674):
Add-ons are disabled. Restart NVDA to enable them.
DEBUG - core.main (10:02:41.015):
Initializing appModule Handler
DEBUG - core.main (10:02:41.016):
Initializing NVDAHelper
ERROR - RPC process 6480 (nvda.exe) (10:02:41.043):
Thread 17736, build\x86\remote\injection.cpp, inprocMgrThreadFunc, 168:
SetWinEventHook failed

DEBUG - core.main (10:02:41.188):
Speech Dictionary processing
DEBUG - speechDictHandler.SpeechDict.load (10:02:41.188):
Loading speech dictionary 'C:\Users\Christo\AppData\Roaming\nvda\speechDicts\default.dic'...
DEBUG - speechDictHandler.SpeechDict.load (10:02:41.190):
file 'C:\Users\Christo\AppData\Roaming\nvda\speechDicts\default.dic' not found.
DEBUG - speechDictHandler.SpeechDict.load (10:02:41.190):
Loading speech dictionary 'builtin.dic'...
DEBUG - speechDictHandler.SpeechDict.load (10:02:41.194):
3 loaded records.
DEBUG - core.main (10:02:41.194):
Initializing speech
ERROR - synthDriverHandler.setSynth (10:02:41.194):
setSynth
Traceback (most recent call last):
  File "synthDriverHandler.pyc", line 87, in setSynth
  File "synthDriverHandler.pyc", line 39, in _getSynthDriver
ImportError: No module named vocalizer_expressive
INFO - synthDrivers.espeak.SynthDriver.__init__ (10:02:41.322):
Using eSpeak NG version 1.49.1 dev
DEBUG - speechDictHandler.SpeechDict.load (10:02:41.453):
Loading speech dictionary 'C:\Users\Christo\AppData\Roaming\nvda\speechDicts\voiceDicts.v1\espeak\espeak-English (Great Britain).dic'...
DEBUG - speechDictHandler.SpeechDict.load (10:02:41.453):
file 'C:\Users\Christo\AppData\Roaming\nvda\speechDicts\voiceDicts.v1\espeak\espeak-English (Great Britain).dic' not found.
INFO - synthDriverHandler.setSynth (10:02:41.457):
Loaded synthDriver espeak
INFO - core.main (10:02:41.457):
Using wx version 3.0.2.0 msw (classic)
DEBUG - core.main (10:02:41.459):
Initializing braille input
INFO - brailleInput.initialize (10:02:41.460):
Braille input initialized
DEBUG - core.main (10:02:41.460):
Initializing braille
INFO - braille.initialize (10:02:41.460):
Using liblouis version 3.3.0
INFO - braille.BrailleHandler.setDisplayByName (10:02:41.460):
Loaded braille display driver noBraille, current display has 0 cells.
DEBUG - core.main (10:02:41.460):
Initializing displayModel
DEBUG - core.main (10:02:41.461):
Initializing GUI
DEBUGWARNING - touchHandler.touchSupported (10:02:41.519):
No touch devices found
DEBUG - core.main (10:02:41.552):
initializing Java Access Bridge support
DEBUG - core.main (10:02:41.713):
Initializing winConsole support
DEBUG - core.main (10:02:41.713):
Initializing UIA support
INFO - _UIAHandler.UIAHandler.MTAThreadFunc (10:02:41.717):
UIAutomation: IUIAutomation3
DEBUG - core.main (10:02:43.717):
Initializing IAccessible support
DEBUG - core.main (10:02:43.719):
Initializing input core
DEBUGWARNING - inputCore.InputManager.loadLocaleGestureMap (10:02:43.720):
No locale gesture map for language en
DEBUG - core.main (10:02:43.726):
Initializing keyboard handler
DEBUG - core.main (10:02:43.729):
initializing mouse handler
DEBUG - core.main (10:02:43.782):
Initializing touchHandler
DEBUGWARNING - touchHandler.touchSupported (10:02:43.786):
No touch devices found
DEBUG - core.main (10:02:43.786):
Initializing global plugin handler
DEBUG - core.main (10:02:43.789):
Initializing core pump
DEBUG - core.main (10:02:43.793):
Initializing watchdog
DEBUG - core.main (10:02:43.796):
initializing updateCheck
INFO - core.main (10:02:43.798):
NVDA initialized
DEBUG - core.main (10:02:43.798):
entering wx application main loop
IO - speech.speak (10:02:43.865):
Speaking [u'nvdaHelperRemote (outprocMgrThreadFunc)  dialog  Error registering focus winEvent hook']
IO - speech.speak (10:02:44.426):
Speaking [u'OK  button']
IO - inputCore.InputManager.executeGesture (10:02:51.242):
Input: kb(desktop):enter
DEBUGWARNING - NVDAObjects.IAccessible.IAccessible._get_IAccessibleRole (10:02:51.306):
accRole failed: (-2147024809, 'The parameter is incorrect.', (None, None, None, 0, None))
IO - speech.speak (10:02:51.519):
Speaking [u'C:\\Users\\Christo\\Downloads  window']
IO - speech.speak (10:02:51.538):
Speaking [u'Items View  list']
IO - speech.speak (10:02:51.562):
Speaking [u'nvda_snapshot_master-14865,769e09f1.exe  40 of 53']
IO - inputCore.InputManager.executeGesture (10:03:04.710):
Input: kb(desktop):NVDA+f1
DEBUGWARNING - displayModel.DisplayModelTextInfo._get__storyFieldsAndRects (10:03:04.746):
AppModule does not have a binding handle
INFO - globalCommands.GlobalCommands.script_navigatorObject_devInfo (10:03:04.959):
Developer info for navigator object:
name: u'nvda_snapshot_master-14865,769e09f1.exe'
role: ROLE_LISTITEM
states: STATE_FOCUSABLE, STATE_SELECTABLE, STATE_FOCUSED, STATE_SELECTED
isFocusable: True
hasFocus: True
Python object: <NVDAObjects.Dynamic_UIItemListItemUIA object at 0x07AC45B0>
Python class mro: (<class 'NVDAObjects.Dynamic_UIItemListItemUIA'>, <class 'NVDAObjects.UIA.UIItem'>, <class 'NVDAObjects.UIA.ListItem'>, <class 'NVDAObjects.UIA.UIA'>, <class 'NVDAObjects.window.Window'>, <class 'NVDAObjects.NVDAObject'>, <class 'documentBase.TextContainerObject'>, <class 'baseObject.ScriptableObject'>, <class 'baseObject.AutoPropertyObject'>, <type 'object'>)
description: ''
location: (174, 420, 598, 22)
value: ''
appModule: <'explorer' (appName u'explorer', process ID 3504) at address 7ac4770>
appModule.productName: u'Microsoft\xae Windows\xae Operating System'
appModule.productVersion: u'10.0.16299.15'
TextInfo: <class 'NVDAObjects.NVDAObjectTextInfo'>
windowHandle: 1838422
windowClassName: u'DirectUIHWND'
windowControlID: 0
windowStyle: 1442840576
windowThreadID: 9364
windowText: u''
displayText: u''
UIAElement: <POINTER(IUIAutomationElement) ptr=0x85e40a0 at 7af6760>
UIA automationID: 39
UIA frameworkID: DirectUI
UIA runtimeID: (3504, 145482880, 0)
UIA providerDescription: [pid:3504,providerId:0x0 Main(parent link):Unidentified Provider (unmanaged:explorerframe.dll)]
UIA className: UIItem

On 2018/02/18 6:39 PM, Joseph Lee wrote:
Hi,
The log and your post doesn't match up. You say you're using master snapshots, but the log says you're actually using a next branch snapshot. Also, for situations like this, an info log won't help - you might want to set log level to debug and see if it can give you more info. Also, please do try using master snapshot (not next) and see if it fixes this.
Cheers,
Joseph

-----Original Message-----
From: nvda@nvda.groups.io [mailto:nvda@nvda.groups.io] On Behalf Of Christo de Klerk
Sent: Sunday, February 18, 2018 3:54 AM
To: nvda@nvda.groups.io
Subject: [nvda] More on the nvdaHelperRemote (outprocMgrThreadFunc) error in new snapshots

Hello especially to the developers


Some days ago I reported that I have been getting an error with every master snapshot after 4 February. The header of the error message is:


nvdaHelperRemote (outprocMgrThreadFunc)

And the error text is:


Error registering focus winEvent hook


This only happens on my 64 bit machine. I have included the start-up log
for NVDA below. All add-ons were disabled to eliminate any of them as
the cause and UAC is set to 0. Here is the log which I hope will make
sense to some and clarify this very worrying problem:


INFO - __main__ (13:30:58.799):
Starting NVDA
INFO - core.main (13:30:59.378):
Config dir: C:\Users\Christo\AppData\Roaming\nvda
INFO - config.ConfigManager._loadConfig (13:30:59.378):
Loading config: C:\Users\Christo\AppData\Roaming\nvda\nvda.ini
INFO - core.main (13:30:59.482):
NVDA version next-14860,d6c75234
INFO - core.main (13:30:59.482):
Using Windows version 10.0.16299 workstation
INFO - core.main (13:30:59.482):
Using Python version 2.7.14 (v2.7.14:84471935ed, Sep 16 2017, 20:19:30)
[MSC v.1500 32 bit (Intel)]
INFO - core.main (13:30:59.482):
Using comtypes version 1.1.3
ERROR - RPC process 10068 (nvda.exe) (13:30:59.917):
Thread 724, build\x86\remote\injection.cpp, inprocMgrThreadFunc, 168:
SetWinEventHook failed

INFO - synthDrivers.espeak.SynthDriver.__init__ (13:31:00.233):
Using eSpeak NG version 1.49.3-dev
INFO - synthDriverHandler.setSynth (13:31:00.378):
Loaded synthDriver espeak
INFO - core.main (13:31:00.380):
Using wx version 3.0.2.0 msw (classic)
INFO - brailleInput.initialize (13:31:00.382):
Braille input initialized
INFO - braille.initialize (13:31:00.384):
Using liblouis version 3.3.0
INFO - braille.BrailleHandler.setDisplayByName (13:31:00.387):
Loaded braille display driver noBraille, current display has 0 cells.
INFO - _UIAHandler.UIAHandler.MTAThreadFunc (13:31:00.661):
UIAutomation: IUIAutomation3
INFO - core.main (13:31:02.700):
NVDA initialized
INFO - globalCommands.GlobalCommands.script_navigatorObject_devInfo
(13:31:28.930):
Developer info for navigator object:
name: u'nvda_snapshot_next-14860,d6c75234.exe'
role: ROLE_LISTITEM
states: STATE_FOCUSABLE, STATE_SELECTABLE, STATE_FOCUSED, STATE_SELECTED
isFocusable: True
hasFocus: True
Python object: <NVDAObjects.Dynamic_UIItemListItemUIA object at 0x07989C50>
Python class mro: (<class 'NVDAObjects.Dynamic_UIItemListItemUIA'>,
<class 'NVDAObjects.UIA.UIItem'>, <class 'NVDAObjects.UIA.ListItem'>,
<class 'NVDAObjects.UIA.UIA'>, <class 'NVDAObjects.window.Window'>,
<class 'NVDAObjects.NVDAObject'>, <class
'documentBase.TextContainerObject'>, <class
'baseObject.ScriptableObject'>, <class 'baseObject.AutoPropertyObject'>,
<type 'object'>)
description: ''
location: (174, 420, 598, 22)
value: ''
appModule: <'explorer' (appName u'explorer', process ID 3504) at address
7989e30>
appModule.productName: u'Microsoft\xae Windows\xae Operating System'
appModule.productVersion: u'10.0.16299.15'
TextInfo: <class 'NVDAObjects.NVDAObjectTextInfo'>
windowHandle: 1838422
windowClassName: u'DirectUIHWND'
windowControlID: 0
windowStyle: 1442840576
windowThreadID: 9364
windowText: u''
displayText: u''
UIAElement: <POINTER(IUIAutomationElement) ptr=0x879bec0 at 798b030>
UIA automationID: 39
UIA frameworkID: DirectUI
UIA runtimeID: (3504, 145482880, 0)
UIA providerDescription: [pid:3504,providerId:0x0 Main(parent
link):Unidentified Provider (unmanaged:explorerframe.dll)]
UIA className: UIItem
UIA patterns available: LegacyIAccessiblePattern, InvokePattern,
ScrollItemPattern, SelectionItemPattern, ValuePattern


A solution and/or suggestions would be more than welcome. I am seriously
worried about this error that it might go through to the 2018.1 release.


Kind regards


Christo






Join nvda@nvda.groups.io to automatically receive all group messages.