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

Jump to bottom

Firefox 32 beta is slower than previous versions in nvda #4337

Closed
nvaccessAuto opened this issue Jul 28, 2014 · 10 comments
Closed

Firefox 32 beta is slower than previous versions in nvda #4337

nvaccessAuto opened this issue Jul 28, 2014 · 10 comments
Labels
blocked/needs-external-fix bug performance

Comments

@nvaccessAuto
Copy link

Reported by briang1 on 2014-07-28 11:35
Of course we do not know if this is a Firefox issue or an nvda one.
The information below shows the delays.
This is on Windows XP, so this might also have a bearing. It might be interesting if somebody could use a lsingle processor machine and windows 7 to see.
Here is what I have found. At first I thought it was just processor maxing, but I'm not so sure now.

Log of Firefox 32 with watchdog stopped.
This actually allows it to display the launch page. as with it on you have to press f5 to get it, normally, but that is not demonstrated in this log.

IO - inputCore.InputManager.executeGesture (10:57:44):
Input: kb(desktop):enter
That was the enter after putting "known unknowns" into the edit field.
The almost 1 minute delay was full of watchdog errors. I disabled watchdog so its a bit clearer.
Note the gap time is not much more with the errors in. Its due to excessive Firefox processor use during the look up.
DEBUGWARNING - NVDAObjects.IAccessible.IA2TextTextInfo.getCaretOffset (10:58:25):
IAccessibleText::caretOffset failed
Traceback (most recent call last):
File "NVDAObjects\IAccessible__init_.pyc", line 151, in _getCaretOffset
COMError: (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_EditableTextWithAutoSelectDetectionMozillaIAccessible._get_IA2Attributes (10:58:25):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_DocumentBrokenFocusedStateMozillaIAccessible._get_IA2Attributes (10:58:26):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUG - treeInterceptorHandler.killTreeInterceptor (10:58:26):
Killed treeInterceptor: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x040CA570>
DEBUG - treeInterceptorHandler.update (10:58:26):
Adding new treeInterceptor to runningTable: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x040AD130>
IO - speech.speak (10:58:26):
Speaking ('en_GB'), u'browse mode'
IO - speech.speak (10:58:26):
Speaking ('en_GB'), u'known unknowns - Startpage Web Search'
DEBUG - queueHandler.registerGeneratorObject (10:58:26):
Adding generator 4851
IO - speech.speak (10:58:26):
Speaking [- speech.speak (10:58:26):
Speaking [IndexCommand(2), LangChangeCommand ('en_GB'), u'link', u'align default', u'Web', u' \xa0\xa0 ', u'link', u'Images', u' \xa0\xa0 ', u'link', u'Videos', u' \xa0\xa0 '](IndexCommand%281%29]
IO)
IO - speech.speak (10:58:26):
Speaking LangChangeCommand ('en_GB'), u'align default', IndexCommand(4), u'link', u'align default', u'Advanced Search', u' \xa0\xa0 ', u'link', u'Settings'
IO - speech.speak (10:58:26):
Speaking LangChangeCommand ('en_GB'), u'link', u'graphic', u'align default', u'eng &prfh=lang_homepageEEEs%2F\u2026'
IO - speech.speak (10:58:26):
Speaking LangChangeCommand ('en_GB'), u'table with 2 rows and 5 columns ', u'row 1 column 1', u'edit', u'align default', u'"known unknowns"'
IO - speech.speak (10:58:26):
Speaking LangChangeCommand ('en_GB'), u'column 2', u'button', u'align default'
IO - speech.speak (10:58:26):
Speaking LangChangeCommand ('en_GB'), u'column 3', u'align center', u'enhanced by\n'
IO - speech.speak (10:58:26):
Speaking LangChangeCommand ('en_GB'), u'column 4', u'link', u'Details', u'out of table'
IO - speech.speak (10:58:28):
Speaking LangChangeCommand ('en_GB'), u'align default', u'About 42,461 results (0.38 seconds)', IndexCommand(11)
IO - speech.speak (10:58:28):
Speaking LangChangeCommand ('en_GB'), u'link', u'Click here to learn the top 10 ways StartPage protects your privacy.'
IO - speech.speak (10:58:31):
Speaking LangChangeCommand ('en_GB'), u'link', u'align default', u'Bookmark this search'
IO - speech.speak (10:58:31):
Speaking LangChangeCommand ('en_GB'), u'list with 20 items', u'heading level 3', u'link', u'align default', u'There are known knowns - Wikipedia, the free encyclopedia', u' '
IO - inputCore.InputManager.executeGesture (10:58:31):
Input: kb(desktop):h
IO - speech.speak (10:58:31):
Speaking ('en_GB'), u'list with 20 items', u'There are known knowns - Wikipedia, the free encyclopedia', u'link', u' ', u'heading level 3'
IO - inputCore.InputManager.executeGesture (10:58:39):
Input: kb(desktop):enter
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_DocumentBrokenFocusedStateMozillaIAccessible._get_IA2Attributes (10:59:00):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUG - treeInterceptorHandler.killTreeInterceptor (10:59:00):
Killed treeInterceptor: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x040AD130>
DEBUG - treeInterceptorHandler.update (10:59:00):
Adding new treeInterceptor to runningTable: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x040D1C50>
IO - speech.speak (10:59:04):
Speaking ('en_GB'), u'There are known knowns - Wikipedia, the free encyclopedia'
DEBUG - queueHandler.registerGeneratorObject (10:59:04):
Adding generator 4852
IO - speech.speak (10:59:04):
Speaking LangChangeCommand ('en_GB'), u'main landmark ', u'heading level 1', u'align default', u'There are known knowns'
IO - speech.speak (10:59:04):
Speaking LangChangeCommand ('en_GB'), u'From Wikipedia, the free encyclopedia', IndexCommand(3), u'Jump to: ', u'link', u'navigation', u', ', u'link', u'search', IndexCommand(4), u'"Known unknowns" redirects here. '
IO - speech.speak (10:59:04):
Speaking ('en_GB'), u'For the ', u'link', u'House', u' episode, see ', u'link', u'Known Unknowns', u'.'
IO - speech.speak (10:59:04):
Speaking LangChangeCommand ('en_GB'), u'"There are known knowns" is a phrase from a response ', u'link', u'United States Secretary of Defense', u' ', u'link', u'Donald ', IndexCommand(6), u'link', u'Rumsfeld', u' gave to a question at a ', u'link', u'U.S. '
IO - speech.speak (10:59:04):
Speaking ('en_GB'), u'Department of Defense', u' news briefing in February 2002 about ', IndexCommand(7), u'the lack of evidence linking the government of ', u'link', u'Iraq', u' with the supply of ', u'link', u'weapons of mass destruction', u' ', IndexCommand(8), u'to terrorist groups.'
IO - speech.speak (10:59:04):
Speaking [('en_GB'), u'link', u'1']
IO - speech.speak (10:59:04):
Speaking LangChangeCommand ('en_GB'), u'Rumsfeld stated:'
IO - speech.speak (10:59:04):
Speaking LangChangeCommand ('en_GB'), u'block quote', u"Reports that say there's -- that something hasn't happened are always interesting to me, because as ", IndexCommand(11), u'we know, there are known knowns; there are things that we know that we know. '
IO - speech.speak (10:59:07):
Speaking ('en_GB'), u'We also know there are ', IndexCommand(12), u'known unknowns; that is to say we know there are some things we do not know. '
IO - speech.speak (10:59:10):
Speaking ('en_GB'), u'But there are also ', IndexCommand(13), u"unknown unknowns, the ones we don't know we don't know."
IO - inputCore.InputManager.executeGesture (10:59:10):
Input: kb(desktop):control+alt+r
INFO - core.main (10:59:11):
Exiting

Next the same log for Firefox 28. Its not quite the same key wise but close enough where it matters. The delay is nominally less than 2 seconds.

IO - inputCore.InputManager.executeGesture (11:17:02):
Input: kb(desktop):enter
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_DocumentBrokenFocusedStateMozillaIAccessible._get_IA2Attributes (11:17:04):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUG - treeInterceptorHandler.killTreeInterceptor (11:17:04):
Killed treeInterceptor: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x040744B0>
DEBUG - treeInterceptorHandler.update (11:17:04):
Adding new treeInterceptor to runningTable: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x04074E10>
IO - speech.speak (11:17:05):
Speaking ('en_GB'), u'browse mode'
IO - speech.speak (11:17:05):
Speaking ('en_GB'), u'known unknowns - Startpage Web Search busy'
DEBUG - queueHandler.registerGeneratorObject (11:17:05):
Adding generator 339
IO - speech.speak (11:17:05):
Speaking [- speech.speak (11:17:05):
Speaking [IndexCommand(2), LangChangeCommand ('en_GB'), u'link', u'align default', u'Web', u' \xa0\xa0 ', u'link', u'Images', u' \xa0\xa0 ', u'link', u'Videos', u' \xa0\xa0 '](IndexCommand%281%29]
IO)
IO - speech.speak (11:17:05):
Speaking LangChangeCommand ('en_GB'), u'align default', IndexCommand(4), u'link', u'align default', u'Advanced Search', u' \xa0\xa0 ', u'link', u'Settings'
IO - speech.speak (11:17:05):
Speaking LangChangeCommand ('en_GB'), u'link', u'graphic', u'align default', u'eng &prfh=lang_homepageEEEs%2F\u2026'
IO - speech.speak (11:17:05):
Speaking LangChangeCommand ('en_GB'), u'table with 2 rows and 5 columns ', u'row 1 column 1', u'edit', u'align default', u'"known unknowns"'
IO - speech.speak (11:17:05):
Speaking LangChangeCommand ('en_GB'), u'column 2', u'button', u'align default'
IO - speech.speak (11:17:05):
Speaking LangChangeCommand ('en_GB'), u'column 3', u'align center', u'enhanced by\n'
IO - speech.speak (11:17:05):
Speaking LangChangeCommand ('en_GB'), u'column 4', u'link', u'Details', u'out of table'
IO - speech.speak (11:17:07):
Speaking LangChangeCommand ('en_GB'), u'align default', u'About 42,585 results (0.38 seconds)', IndexCommand(11)
IO - speech.speak (11:17:07):
Speaking LangChangeCommand ('en_GB'), u'link', u'Startpage does NOT record your IP address!'
IO - inputCore.InputManager.executeGesture (11:17:09):
Input: kb(desktop):downArrow
IO - speech.speak (11:17:09):
Speaking ('en_GB'), u'align default', u'space', u'blank'
IO - inputCore.InputManager.executeGesture (11:17:11):
Input: kb(desktop):h
IO - speech.speak (11:17:11):
Speaking ('en_GB'), u'list with 20 items', u'align default', u'There are known knowns - Wikipedia, the free encyclopedia', u'visited link', u' ', u'heading level 3'
IO - inputCore.InputManager.executeGesture (11:17:14):
Input: kb(desktop):downArrow
IO - speech.speak (11:17:14):
Speaking ('en_GB'), u'no indent', u'https://en.wikipedia.org/wiki/There_are_known_knowns - ', u'link', u'View by Ixquick Proxy', u' - ', u'link', u'Highlight'
IO - inputCore.InputManager.executeGesture (11:17:15):
Input: kb(desktop):upArrow
IO - speech.speak (11:17:15):
Speaking ('en_GB'), u'heading level 3', u'visited link', u'There are known knowns - Wikipedia, the free encyclopedia', u' '
IO - inputCore.InputManager.executeGesture (11:17:17):
Input: kb(desktop):enter
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_DocumentBrokenFocusedStateMozillaIAccessible._get_IA2Attributes (11:17:20):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUG - treeInterceptorHandler.killTreeInterceptor (11:17:20):
Killed treeInterceptor: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x04074E10>
DEBUG - treeInterceptorHandler.update (11:17:20):
Adding new treeInterceptor to runningTable: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x0409C5D0>
IO - speech.speak (11:17:21):
Speaking ('en_GB'), u'There are known knowns - Wikipedia, the free encyclopedia busy'
DEBUG - queueHandler.registerGeneratorObject (11:17:21):
Adding generator 340
IO - speech.speak (11:17:21):
Speaking LangChangeCommand ('en_GB'), u'main landmark ', u'heading level 1', u'align default', u'There are known knowns'
IO - speech.speak (11:17:21):
Speaking LangChangeCommand ('en_GB'), u'From Wikipedia, the free encyclopedia', IndexCommand(3), u'Jump to:\t', u'link', u'navigation', u', ', u'link', u'search', IndexCommand(4), u'"Known unknowns" redirects here. '
IO - speech.speak (11:17:21):
Speaking ('en_GB'), u'For the ', u'link', u'House', u' episode, see ', u'link', u'Known Unknowns', u'.'
IO - speech.speak (11:17:21):
Speaking LangChangeCommand ('en_GB'), u'"There are known knowns" is a phrase from a response ', u'link', u'United States Secretary of Defense', u' ', u'link', u'Donald ', IndexCommand(6), u'link', u'Rumsfeld', u' gave to a question at a ', u'link', u'U.S. '
IO - speech.speak (11:17:21):
Speaking ('en_GB'), u'Department of Defense', u' news briefing in February 2002 about ', IndexCommand(7), u'the lack of evidence linking the government of ', u'link', u'Iraq', u' with the supply of ', u'link', u'weapons of mass destruction', u' ', IndexCommand(8), u'to terrorist groups.'
IO - speech.speak (11:17:21):
Speaking [('en_GB'), u'link', u'1']
IO - speech.speak (11:17:21):
Speaking LangChangeCommand ('en_GB'), u'Rumsfeld stated:'
IO - speech.speak (11:17:21):
Speaking LangChangeCommand ('en_GB'), u'block quote', u"Reports that say there's -- that something hasn't happened are always interesting to me, because as ", IndexCommand(11), u'we know, there are known knowns; there are things that we know that we know. '
IO - speech.speak (11:17:24):
Speaking ('en_GB'), u'We also know there are ', IndexCommand(12), u'known unknowns; that is to say we know there are some things we do not know. '
IO - speech.speak (11:17:26):
Speaking ('en_GB'), u'But there are also ', IndexCommand(13), u"unknown unknowns, the ones we don't know we don't know."
IO - inputCore.InputManager.executeGesture (11:17:34):
Input: kb(desktop):control+alt+r
INFO - core.main (11:17:35):
Exiting

The not connected to server error seems to be not implicated here. It is, I think the maxing out of the processor that gives the delay at least on complete page changes, the other issue of time while displaying new content might be different.

The following log, essentially the same on 32 is with watchdog enabled.

Note, Flash is set to never use.
IO - inputCore.InputManager.executeGesture (11:33:06):
Input: kb(desktop):enter
DEBUGWARNING - NVDAObjects.IAccessible.IA2TextTextInfo.getCaretOffset (11:33:18):
IAccessibleText::caretOffset failed
Traceback (most recent call last):
File "NVDAObjects\IAccessible__init_.pyc", line 151, in _getCaretOffset
COMError: (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
Now there is no watchdog here but look at the time.

DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_EditableTextWithAutoSelectDetectionMozillaIAccessible._get_IA2Attributes (11:33:41):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_DocumentBrokenFocusedStateMozillaIAccessible._get_IA2Attributes (11:33:47):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUG - treeInterceptorHandler.killTreeInterceptor (11:33:48):
Killed treeInterceptor: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x03FF7490>
DEBUG - treeInterceptorHandler.update (11:33:48):
Adding new treeInterceptor to runningTable: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x03FF7B30>
IO - speech.speak (11:33:48):
Speaking ('en_GB'), u'browse mode'
IO - speech.speak (11:33:48):
Speaking ('en_GB'), u'known unknowns - Startpage Web Search'
DEBUG - queueHandler.registerGeneratorObject (11:33:48):
Adding generator 311
IO - speech.speak (11:33:48):
Speaking [- speech.speak (11:33:48):
Speaking [IndexCommand(2), LangChangeCommand ('en_GB'), u'link', u'align default', u'Web', u' \xa0\xa0 ', u'link', u'Images', u' \xa0\xa0 ', u'link', u'Videos', u' \xa0\xa0 '](IndexCommand%281%29]
IO)
IO - speech.speak (11:33:48):
Speaking LangChangeCommand ('en_GB'), u'align default', IndexCommand(4), u'link', u'align default', u'Advanced Search', u' \xa0\xa0 ', u'link', u'Settings'
IO - speech.speak (11:33:48):
Speaking LangChangeCommand ('en_GB'), u'link', u'graphic', u'align default', u'eng &prfh=lang_homepageEEEs%2F\u2026'
IO - speech.speak (11:33:48):
Speaking LangChangeCommand ('en_GB'), u'table with 2 rows and 5 columns ', u'row 1 column 1', u'edit', u'align default', u'"known unknowns"'
IO - speech.speak (11:33:48):
Speaking LangChangeCommand ('en_GB'), u'column 2', u'button', u'align default'
IO - speech.speak (11:33:48):
Speaking LangChangeCommand ('en_GB'), u'column 3', u'align center', u'enhanced by\n'
IO - speech.speak (11:33:48):
Speaking LangChangeCommand ('en_GB'), u'column 4', u'link', u'Details', u'out of table'
IO - speech.speak (11:33:50):
Speaking LangChangeCommand ('en_GB'), u'align default', u'About 42,494 results (0.13 seconds)', IndexCommand(11)
IO - speech.speak (11:33:50):
Speaking LangChangeCommand ('en_GB'), u'link', u'Startpage does NOT record your IP address!'
IO - speech.speak (11:33:53):
Speaking LangChangeCommand ('en_GB'), u'link', u'align default', u'Bookmark this search'
IO - inputCore.InputManager.executeGesture (11:33:53):
Input: kb(desktop):h
IO - speech.speak (11:33:53):
Speaking ('en_GB'), u'list with 20 items', u'There are known knowns - Wikipedia, the free encyclopedia', u'visited link', u' ', u'heading level 3'
IO - inputCore.InputManager.executeGesture (11:33:57):
Input: kb(desktop):enter
Time lost again here.

DEBUGWARNING - NVDAObjects.IAccessible.IAccessible.init (11:34:14):
IAccessible2::windowHandle failed: (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
Time here as well.
DEBUGWARNING - watchdog.watcher (11:34:28):
Trying to recover from freeze, core stack:
File "nvda.pyw", line 181, in
File "core.pyc", line 346, in main
File "wx_core.pyc", line 8010, in MainLoop
File "wx_core.pyc", line 7306, in MainLoop
File "core.pyc", line 316, in Notify
File "IAccessibleHandler.pyc", line 858, in pumpAll
File "IAccessibleHandler.pyc", line 610, in processGenericWinEvent
File "IAccessibleHandler.pyc", line 524, in winEventToNVDAEvent
File "NVDAObjects\IAccessible__init_.pyc", line 43, in getNVDAObjectFromEvent
File "NVDAObjects__init__.pyc", line 68, in call
File "NVDAObjects\IAccessible__init__.pyc", line 571, in init

Time again.

WARNING - watchdog.watcher (11:34:49):
Core frozen in stack:
File "nvda.pyw", line 181, in
File "core.pyc", line 346, in main
File "wx_core.pyc", line 8010, in MainLoop
File "wx_core.pyc", line 7306, in MainLoop
File "core.pyc", line 316, in Notify
File "IAccessibleHandler.pyc", line 858, in pumpAll
File "IAccessibleHandler.pyc", line 610, in processGenericWinEvent
File "IAccessibleHandler.pyc", line 524, in winEventToNVDAEvent
File "NVDAObjects\IAccessible__init_.pyc", line 43, in getNVDAObjectFromEvent
File "NVDAObjects__init__.pyc", line 75, in call
File "NVDAObjects\IAccessible__init__.pyc", line 439, in findOverlayClasses
File "baseObject.pyc", line 34, in get
File "baseObject.pyc", line 110, in getPropertyViaCache
File "NVDAObjects\IAccessible__init_.pyc", line 769, in get_IAccessibleRole
File "comtypes__init_.pyc", line 795, in call
File "comtypes\automation.pyc", line 163, in del

More time lost. If you suspend watchdog it is faster!

DEBUGWARNING - eventHandler.executeEvent (11:34:56):
error executing event: stateChange on <NVDAObjects.IAccessible.mozilla.Mozilla object at 0x03F4AC70> with extra args of {}
Traceback (most recent call last):
File "eventHandler.pyc", line 143, in executeEvent
File "eventHandler.pyc", line 91, in init
File "eventHandler.pyc", line 98, in next
File "appModules\firefox.pyc", line 24, in event_stateChange
File "eventHandler.pyc", line 97, in next
File "eventHandler.pyc", line 117, in gen
File "NVDAObjects__init__.pyc", line 342, in _get_treeInterceptor
File "treeInterceptorHandler.pyc", line 18, in getTreeInterceptor
File "watchdog.pyc", line 192, in _COMError_init
CallCancelled
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_DocumentBrokenFocusedStateMozillaIAccessible._get_IA2Attributes (11:34:57):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUG - treeInterceptorHandler.killTreeInterceptor (11:34:57):
Killed treeInterceptor: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x03FF7B30>
DEBUG - treeInterceptorHandler.update (11:34:57):
Adding new treeInterceptor to runningTable: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x04044190>
IO - speech.speak (11:34:58):
Speaking ('en_GB'), u'There are known knowns - Wikipedia, the free encyclopedia'
DEBUG - queueHandler.registerGeneratorObject (11:34:58):
Adding generator 312
IO - speech.speak (11:34:58):
Speaking LangChangeCommand ('en_GB'), u'main landmark ', u'heading level 1', u'align default', u'There are known knowns'
IO - speech.speak (11:34:58):
Speaking LangChangeCommand ('en_GB'), u'From Wikipedia, the free encyclopedia', IndexCommand(3), u'Jump to: ', u'link', u'navigation', u', ', u'link', u'search', IndexCommand(4), u'"Known unknowns" redirects here. '
IO - speech.speak (11:34:58):
Speaking ('en_GB'), u'For the ', u'link', u'House', u' episode, see ', u'link', u'Known Unknowns', u'.'
IO - speech.speak (11:34:58):
Speaking LangChangeCommand ('en_GB'), u'"There are known knowns" is a phrase from a response ', u'link', u'United States Secretary of Defense', u' ', u'link', u'Donald ', IndexCommand(6), u'link', u'Rumsfeld', u' gave to a question at a ', u'link', u'U.S. '
IO - speech.speak (11:34:58):
Speaking ('en_GB'), u'Department of Defense', u' news briefing in February 2002 about ', IndexCommand(7), u'the lack of evidence linking the government of ', u'link', u'Iraq', u' with the supply of ', u'link', u'weapons of mass destruction', u' ', IndexCommand(8), u'to terrorist groups.'
IO - speech.speak (11:34:58):
Speaking [('en_GB'), u'link', u'1']
IO - speech.speak (11:34:58):
Speaking LangChangeCommand ('en_GB'), u'Rumsfeld stated:'
IO - speech.speak (11:34:58):
Speaking LangChangeCommand ('en_GB'), u'block quote', u"Reports that say there's -- that something hasn't happened are always interesting to me, because as ", IndexCommand(11), u'we know, there are known knowns; there are things that we know that we know. '
IO - speech.speak (11:35:00):
Speaking ('en_GB'), u'We also know there are ', IndexCommand(12), u'known unknowns; that is to say we know there are some things we do not know. '
IO - speech.speak (11:35:03):
Speaking ('en_GB'), u'But there are also ', IndexCommand(13), u"unknown unknowns, the ones we don't know we don't know."
IO - speech.speak (11:35:05):
Speaking ('en_GB'), u'out of block quote'
IO - speech.speak (11:35:05):
Speaking LangChangeCommand ('en_GB'), u'The statement became the subject of much commentary and derision.'
IO - speech.speak (11:35:07):
Speaking [('en_GB'), u'link', u'2']
IO - inputCore.InputManager.executeGesture (11:35:07):
Input: kb(desktop):downArrow
IO - speech.speak (11:35:08):
Speaking ('en_GB'), u'align default', u'"There are known knowns" is a phrase from a response ', u'link', u'United States Secretary of Defense', u' ', u'link', u'Donald '
IO - inputCore.InputManager.executeGesture (11:35:09):
Input: kb(desktop):downArrow
IO - speech.speak (11:35:09):
Speaking ('en_GB'), u'link', u'Rumsfeld', u' gave to a question at a ', u'link', u'U.S. Department of Defense', u' news briefing in February 2002 about '
IO - inputCore.InputManager.executeGesture (11:35:20):
Input: kb(desktop):control+alt+r
INFO - core.main (11:35:20):
Exiting

Well looking at that lot, there is obviously more going on here than just thecore freezing, but most of it is not really covered in the log, only the smoking guns, so to speak.

And finally, the launching of firefox 32. This is well dire comes to mind.

IO - speech.speak (12:11:40):
Speaking ('en_GB'), u'Mozilla Firefox 12 of 61'
DEBUG - queueHandler.pumpAll (12:11:40):
generator 1216 finished
IO - inputCore.InputManager.executeGesture (12:11:45):
Input: kb(desktop):enter
DEBUGWARNING - watchdog.watcher (12:11:49):
Trying to recover from freeze, core stack:
File "nvda.pyw", line 181, in
File "core.pyc", line 346, in main
File "wx_core.pyc", line 8010, in MainLoop
File "wx_core.pyc", line 7306, in MainLoop
File "core.pyc", line 316, in Notify
File "IAccessibleHandler.pyc", line 861, in pumpAll
File "IAccessibleHandler.pyc", line 747, in processForegroundWinEvent
File "IAccessibleHandler.pyc", line 524, in winEventToNVDAEvent
File "NVDAObjects\IAccessible__init_.pyc", line 37, in getNVDAObjectFromEvent
File "IAccessibleHandler.pyc", line 339, in accessibleObjectFromEvent
File "oleacc.pyc", line 240, in AccessibleObjectFromEvent

DEBUGWARNING - watchdog.watcher (12:11:50):
Trying to recover from freeze, core stack:
File "nvda.pyw", line 181, in
File "core.pyc", line 346, in main
File "wx_core.pyc", line 8010, in MainLoop
File "wx_core.pyc", line 7306, in MainLoop
File "core.pyc", line 317, in Notify
File "queueHandler.pyc", line 83, in pumpAll
File "queueHandler.pyc", line 50, in flushQueue
File "eventHandler.pyc", line 61, in queueEventCallback
File "eventHandler.pyc", line 138, in executeEvent
File "eventHandler.pyc", line 151, in doPreGainFocus
File "api.pyc", line 107, in setFocusObject
File "baseObject.pyc", line 34, in get
File "baseObject.pyc", line 110, in getPropertyViaCache
File "NVDAObjects__init.pyc", line 469, in get_container
File "baseObject.pyc", line 34, in get
File "baseObject.pyc", line 110, in getPropertyViaCache
File "NVDAObjects\IAccessible__init.pyc", line 914, in get_parent
File "NVDAObjects__init.pyc", line 58, in call
File "NVDAObjects__init__.pyc", line 187, in findBestAPIClass
File "NVDAObjects\IAccessible\MSHTML.pyc", line 352, in kwargsFromSuper
File "NVDAObjects\IAccessible\MSHTML.pyc", line 96, in HTMLNodeFromIAccessible
File "comtypes__init__.pyc", line 1111, in QueryService

DEBUGWARNING - eventHandler.executeEvent (12:11:50):
error executing event: gainFocus on <NVDAObjects.IAccessible.IAccessible object at 0x040332F0> with extra args of {}
Traceback (most recent call last):
File "eventHandler.pyc", line 138, in executeEvent
File "eventHandler.pyc", line 151, in doPreGainFocus
File "api.pyc", line 107, in setFocusObject
File "baseObject.pyc", line 34, in get
File "baseObject.pyc", line 110, in getPropertyViaCache
File "NVDAObjects__init_.pyc", line 469, in get_container
File "baseObject.pyc", line 34, in get
File "baseObject.pyc", line 110, in getPropertyViaCache
File "NVDAObjects\IAccessible__init.pyc", line 914, in get_parent
File "NVDAObjects__init_.pyc", line 68, in call
File "watchdog.pyc", line 192, in COMError_init
CallCancelled
DEBUGWARNING - watchdog.watcher (12:11:51):
Trying to recover from freeze, core stack:
File "nvda.pyw", line 181, in
File "core.pyc", line 346, in main
File "wx_core.pyc", line 8010, in MainLoop
File "wx_core.pyc", line 7306, in MainLoop
File "core.pyc", line 316, in Notify
File "IAccessibleHandler.pyc", line 846, in pumpAll
File "IAccessibleHandler.pyc", line 647, in processFocusWinEvent
File "IAccessibleHandler.pyc", line 524, in winEventToNVDAEvent
File "NVDAObjects\IAccessible__init.pyc", line 43, in getNVDAObjectFromEvent
File "NVDAObjects__init__.pyc", line 68, in call
File "NVDAObjects\IAccessible__init__.pyc", line 598, in init
File "comtypes__init__.pyc", line 1078, in QueryInterface

DEBUGWARNING - core.Notify (12:11:51):
errors in this core pump cycle
Traceback (most recent call last):
File "core.pyc", line 316, in Notify
File "IAccessibleHandler.pyc", line 846, in pumpAll
File "IAccessibleHandler.pyc", line 647, in processFocusWinEvent
File "IAccessibleHandler.pyc", line 524, in winEventToNVDAEvent
File "NVDAObjects\IAccessible__init__.pyc", line 43, in getNVDAObjectFromEvent
File "NVDAObjects__init__.pyc", line 68, in call
File "watchdog.pyc", line 192, in COMError_init
CallCancelled
DEBUGWARNING - watchdog.watcher (12:11:52):
Trying to recover from freeze, core stack:
File "nvda.pyw", line 181, in
File "core.pyc", line 346, in main
File "wx_core.pyc", line 8010, in MainLoop
File "wx_core.pyc", line 7306, in MainLoop
File "core.pyc", line 316, in Notify
File "IAccessibleHandler.pyc", line 858, in pumpAll
File "IAccessibleHandler.pyc", line 610, in processGenericWinEvent
File "IAccessibleHandler.pyc", line 524, in winEventToNVDAEvent
File "NVDAObjects\IAccessible__init.pyc", line 37, in getNVDAObjectFromEvent
File "IAccessibleHandler.pyc", line 343, in accessibleObjectFromEvent
File "IAccessibleHandler.pyc", line 327, in normalizeIAccessible
File "comtypes__init__.pyc", line 1111, in QueryService

DEBUGWARNING - core.Notify (12:11:52):
errors in this core pump cycle
Traceback (most recent call last):
File "core.pyc", line 316, in Notify
File "IAccessibleHandler.pyc", line 858, in pumpAll
File "IAccessibleHandler.pyc", line 610, in processGenericWinEvent
File "IAccessibleHandler.pyc", line 524, in winEventToNVDAEvent
File "NVDAObjects\IAccessible__init__.pyc", line 43, in getNVDAObjectFromEvent
File "NVDAObjects__init__.pyc", line 68, in call
File "watchdog.pyc", line 192, in COMError_init
CallCancelled
DEBUGWARNING - watchdog.watcher (12:11:53):
Trying to recover from freeze, core stack:
File "nvda.pyw", line 181, in
File "core.pyc", line 346, in main
File "wx_core.pyc", line 8010, in MainLoop
File "wx_core.pyc", line 7306, in MainLoop
File "core.pyc", line 316, in Notify
File "IAccessibleHandler.pyc", line 858, in pumpAll
File "IAccessibleHandler.pyc", line 610, in processGenericWinEvent
File "IAccessibleHandler.pyc", line 524, in winEventToNVDAEvent
File "NVDAObjects\IAccessible__init.pyc", line 37, in getNVDAObjectFromEvent
File "IAccessibleHandler.pyc", line 339, in accessibleObjectFromEvent
File "oleacc.pyc", line 240, in AccessibleObjectFromEvent

DEBUGWARNING - IAccessibleHandler.accessibleObjectFromEvent (12:11:53):
oleacc.AccessibleObjectFromEvent with window 655904, objectID -4 and childID -289221200:
DEBUGWARNING - watchdog.watcher (12:11:56):
Trying to recover from freeze, core stack:
File "nvda.pyw", line 181, in
File "core.pyc", line 346, in main
File "wx_core.pyc", line 8010, in MainLoop
File "wx_core.pyc", line 7306, in MainLoop
File "core.pyc", line 316, in Notify
File "IAccessibleHandler.pyc", line 858, in pumpAll
File "IAccessibleHandler.pyc", line 610, in processGenericWinEvent
File "IAccessibleHandler.pyc", line 524, in winEventToNVDAEvent
File "NVDAObjects\IAccessible__init_.pyc", line 43, in getNVDAObjectFromEvent
File "NVDAObjects__init__.pyc", line 68, in call
File "NVDAObjects\IAccessible__init__.pyc", line 536, in init

DEBUGWARNING - core.Notify (12:11:56):
errors in this core pump cycle
Traceback (most recent call last):
File "core.pyc", line 316, in Notify
File "IAccessibleHandler.pyc", line 858, in pumpAll
File "IAccessibleHandler.pyc", line 610, in processGenericWinEvent
File "IAccessibleHandler.pyc", line 524, in winEventToNVDAEvent
File "NVDAObjects\IAccessible__init__.pyc", line 43, in getNVDAObjectFromEvent
File "NVDAObjects__init__.pyc", line 68, in call
File "watchdog.pyc", line 192, in _COMError_init
CallCancelled

This shows how long I waited and obviously nvda had given up by now.

IO - inputCore.InputManager.executeGesture (12:13:29):
Input: kb(desktop):f5
DEBUGWARNING - watchdog.watcher (12:13:31):
Trying to recover from freeze, core stack:
File "nvda.pyw", line 181, in
File "core.pyc", line 346, in main
File "wx_core.pyc", line 8010, in MainLoop
File "wx_core.pyc", line 7306, in MainLoop
File "core.pyc", line 316, in Notify
File "IAccessibleHandler.pyc", line 858, in pumpAll
File "IAccessibleHandler.pyc", line 610, in processGenericWinEvent
File "IAccessibleHandler.pyc", line 524, in winEventToNVDAEvent
File "NVDAObjects\IAccessible__init_.pyc", line 43, in getNVDAObjectFromEvent
File "NVDAObjects__init__.pyc", line 68, in call
File "NVDAObjects\IAccessible__init__.pyc", line 606, in init
File "comtypesMonkeyPatches.pyc", line 52, in newCpbDel
File "comtypes__init__.pyc", line 278, in setattr

DEBUG - treeInterceptorHandler.update (12:13:31):
Adding new treeInterceptor to runningTable: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x04029D10>
IO - speech.speak (12:13:31):
Speaking ('en_GB'), u'Startpage Search Engine - Mozilla Firefox'
IO - speech.speak (12:13:31):
Speaking ('en_GB'), u'Startpage Search Engine document'
IO - speech.speak (12:13:31):
Speaking ('en_GB'), u'Startpage Search Engine - Mozilla Firefox'
IO - speech.speak (12:13:31):
Speaking ('en_GB'), u'Startpage Search Engine document'
IO - speech.speak (12:13:31):
Speaking ('en_GB'), u'edit'
IO - speech.speak (12:13:31):
Speaking ('en_GB'), u'blank'
IO - speech.speak (12:13:31):
Speaking ('en_GB'), u'focus mode'
IO - inputCore.InputManager.executeGesture (12:13:41):
Input: kb(desktop):control+alt+r
INFO - core.main (12:13:41):
Exiting

Note,Having decided it was never going to display the page it had obviusly loaded, I hit f5 to achieve it, so perhaps nvda was too bored by this time?

The machine all of this was done on is.

MS Windows XP Professional SP3Intel Pentium 4 CPU 2.66GHz, 1.5GB RAM, NVIDIA GeForce4 MX 420
Obviously on very very javascript or graphic driven pages, one expects slow downs on this machine, but I mostly use it on 'normal' simple web sites and it is normally fine.
@nvaccessAuto
Copy link
Author

Comment 1 by jteh on 2014-07-28 11:40
Do you have any way of testing this without NVDA to see if this is specific to NVDA; i.e. sighted friend, etc.?

@nvaccessAuto
Copy link
Author

Comment 2 by briang1 on 2014-07-28 12:47
As I said originally, when I was posting in the dev list. I was very suspico ious that much of the slow down is being caused by the software, not nvda.
The best I can do right now is a basic narrator test, and yes, the delay is great in Firefox 32 than in 28 in every case as mentioned above. The delays are greater in nvda though, but this might just be that nvda cannot figure out what is going on.
In the case of going to the searched for item, if you click the void graphic with Narator, it will allow you to tab to this link and click it.
That is by far the biggest delay with Narrator.
Starting Firefox is hardly any delay, and getting a result from the search is a kind of in between delay. Hard to actually time it of course.
So I suspect that the new code in Firefox is at least partly the problem. I'd certainly not want to use it even with the reduced delays visible with Narrator, assuming of course Narrator worked properly on Firefox.
I did get a Supernova user with Windows 7 to try it but she said it was a bit slower than before the update, but not much, but then that is a multi core processor.
Maybe they have optimised the code in that area, so single core machines are going to struggle now.

Perhaps you could pass this on to Mozilla for me.
PS it has nothing to do with the focus announcing ticket though!

@nvaccessAuto
Copy link
Author

Comment 3 by briang1 (in reply to comment 2) on 2014-07-28 16:50
Replying to briang1:

As I said originally, when I was posting in the dev list. I was very suspico ious that much of the slow down is being caused by the software, not nvda.

The best I can do right now is a basic narrator test, and yes, the delay is great in Firefox 32 than in 28 in every case as mentioned above. The delays are greater in nvda though, but this might just be that nvda cannot figure out what is going on.

In the case of going to the searched for item, if you click the void graphic with Narator, it will allow you to tab to this link and click it.

That is by far the biggest delay with Narrator.

Starting Firefox is hardly any delay, and getting a result from the search is a kind of in between delay. Hard to actually time it of course.

So I suspect that the new code in Firefox is at least partly the problem. I'd certainly not want to use it even with the reduced delays visible with Narrator, assuming of course Narrator worked properly on Firefox.

I did get a Supernova user with Windows 7 to try it but she said it was a bit slower than before the update, but not much, but then that is a multi core processor.

Maybe they have optimised the code in that area, so single core machines are going to struggle now.

Perhaps you could pass this on to Mozilla for me.

PS it has nothing to do with the focus announcing ticket though!

Ihave now tested it on some other slow machines and its not so simple as I thought. Below are the logs of two machines, one is an Athlon machine running both versions, and they seem to be very similar, ie very little slowdown.
Then 32 on a Sempron machine, where the slow down is the same as on my Pentium. The plot thickens. I did post some of this to the dev list, but not seen it appear.

another interesting thing is that this is on an amd Athlon machine and the delay is not bad at all on firefox 32.

IO - inputCore.InputManager.executeGesture (15:40:15):
Input: kb(desktop):enter
DEBUGWARNING - NVDAObjects.IAccessible.IA2TextTextInfo.getCaretOffset (15:40:18):
IAccessibleText::caretOffset failed
Traceback (most recent call last):
File "NVDAObjects\IAccessible__init_.pyc", line 151, in getCaretOffset
COMError: (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUGWARNING - NVDAObjects.IAccessible.IA2TextTextInfo.getCaretOffset (15:40:20):
IAccessibleText::caretOffset failed
Traceback (most recent call last):
File "NVDAObjects\IAccessible__init.pyc", line 151, in _getCaretOffset
COMError: (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_EditableTextWithAutoSelectDetectionMozillaIAccessible._get_IA2Attributes (15:40:20):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_DocumentBrokenFocusedStateMozillaIAccessible._get_IA2Attributes (15:40:20):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUG - treeInterceptorHandler.killTreeInterceptor (15:40:20):
Killed treeInterceptor: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x03C2B270>
DEBUG - treeInterceptorHandler.update (15:40:20):
Adding new treeInterceptor to runningTable: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x03CB0ED0>
IO - speech.speak (15:40:21):
Speaking ('en_GB'), u'browse mode'
IO - speech.speak (15:40:21):
Speaking ('en_GB'), u'known unknowns - Startpage Web Search'
DEBUG - queueHandler.registerGeneratorObject (15:40:21):
Adding generator 191
IO - speech.speak (15:40:21):
Speaking [- speech.speak (15:40:21):
Speaking [IndexCommand(2), LangChangeCommand ('en_GB'), u'link', u'Web', u' \xa0\xa0 ', u'link', u'Images', u' \xa0\xa0 ', u'link', u'Videos', u' \xa0\xa0 '](IndexCommand%281%29]
IO)
IO - speech.speak (15:40:21):
Speaking IndexCommand(4), LangChangeCommand ('en_GB'), u'link', u'Advanced Search', u' \xa0\xa0 ', u'link', u'Settings'
IO - speech.speak (15:40:21):
Speaking LangChangeCommand ('en_GB'), u'link', u'graphic', u'uk &prfh=lang_homepageEEEs%2Fb\u2026'
IO - speech.speak (15:40:21):
Speaking LangChangeCommand ('en_GB'), u'table with 2 rows and 5 columns ', u'row 1 column 1', u'edit', u'"known unknowns"'
IO - speech.speak (15:40:21):
Speaking LangChangeCommand ('en_GB'), u'column 2', u'button'
IO - speech.speak (15:40:21):
Speaking LangChangeCommand ('en_GB'), u'column 3', u'enhanced by\n'
IO - speech.speak (15:40:21):
Speaking LangChangeCommand ('en_GB'), u'column 4', u'link', u'Details', u'out of table'
IO - speech.speak (15:40:23):
Speaking LangChangeCommand ('en_GB'), u'About 42,495 results (0.10 seconds)', IndexCommand(11)
IO - speech.speak (15:40:23):
Speaking LangChangeCommand ('en_GB'), u'link', u'Click here to learn the top 10 ways StartPage protects your privacy.'
IO - inputCore.InputManager.executeGesture (15:40:24):
Input: kb(desktop):h
IO - speech.speak (15:40:24):
Speaking ('en_GB'), u'list with 20 items', u'There are known knowns - Wikipedia, the free encyclopedia', u'visited link', u' ', u'heading level 3'
IO - inputCore.InputManager.executeGesture (15:40:26):
Input: kb(desktop):enter
DEBUGWARNING - NVDAObjects.IAccessible.IAccessible.init (15:40:28):
IAccessible2::windowHandle failed: (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_DocumentBrokenFocusedStateMozillaIAccessible._get_IA2Attributes (15:40:31):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUG - treeInterceptorHandler.killTreeInterceptor (15:40:31):
Killed treeInterceptor: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x03CB0ED0>
DEBUG - treeInterceptorHandler.update (15:40:33):
Adding new treeInterceptor to runningTable: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x03C2B610>
IO - speech.speak (15:40:34):
Speaking ('en_GB'), u'There are known knowns - Wikipedia, the free encyclopedia'
DEBUG - queueHandler.registerGeneratorObject (15:40:34):
Adding generator 192
IO - speech.speak (15:40:34):
Speaking LangChangeCommand ('en_GB'), u'main landmark ', u'heading level 1', u'There are known knowns'
IO - speech.speak (15:40:34):
Speaking LangChangeCommand ('en_GB'), u'From Wikipedia, the free encyclopedia', IndexCommand(3), u'Jump to: ', u'link', u'navigation', u', ', u'link', u'search', IndexCommand(4), u'"Known unknowns" redirects here. '

Just to prove they are the same, look at firefox 28

So Athlons are relatively unaffected, but Semprons are, unless its somethig obscure like front side bus speed or graphics card problems, which are a bit over my head. Its such a huge difference though., but not on this machine it seems...

IO - inputCore.InputManager.executeGesture (15:55:07):
Input: kb(desktop):enter
DEBUGWARNING - NVDAObjects.IAccessible.IA2TextTextInfo.getCaretOffset (15:55:10):
IAccessibleText::caretOffset failed
Traceback (most recent call last):
File "NVDAObjects\IAccessible__init_.pyc", line 151, in _getCaretOffset
COMError: (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_EditableTextWithAutoSelectDetectionMozillaIAccessible._get_IA2Attributes (15:55:10):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_DocumentBrokenFocusedStateMozillaIAccessible._get_IA2Attributes (15:55:10):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUG - treeInterceptorHandler.killTreeInterceptor (15:55:10):
Killed treeInterceptor: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x03E78AD0>
DEBUG - treeInterceptorHandler.update (15:55:10):
Adding new treeInterceptor to runningTable: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x03DFD350>
IO - speech.speak (15:55:11):
Speaking ('en_GB'), u'browse mode'
IO - speech.speak (15:55:11):
Speaking ('en_GB'), u'known unknowns - Startpage Web Search'
DEBUG - queueHandler.registerGeneratorObject (15:55:11):
Adding generator 162
IO - speech.speak (15:55:11):
Speaking [- speech.speak (15:55:11):
Speaking [IndexCommand(2), LangChangeCommand ('en_GB'), u'link', u'Web', u' \xa0\xa0 ', u'link', u'Images', u' \xa0\xa0 ', u'link', u'Videos', u' \xa0\xa0 '](IndexCommand%281%29]
IO)
IO - speech.speak (15:55:11):
Speaking IndexCommand(4), LangChangeCommand ('en_GB'), u'link', u'Advanced Search', u' \xa0\xa0 ', u'link', u'Settings'
IO - speech.speak (15:55:11):
Speaking LangChangeCommand ('en_GB'), u'link', u'graphic', u'uk &prfh=lang_homepageEEEs%2Fb\u2026'
IO - speech.speak (15:55:11):
Speaking LangChangeCommand ('en_GB'), u'table with 2 rows and 5 columns ', u'row 1 column 1', u'edit', u'"known unknowns"'
IO - speech.speak (15:55:11):
Speaking LangChangeCommand ('en_GB'), u'column 2', u'button'
IO - speech.speak (15:55:11):
Speaking LangChangeCommand ('en_GB'), u'column 3', u'enhanced by\n'
IO - speech.speak (15:55:11):
Speaking LangChangeCommand ('en_GB'), u'column 4', u'link', u'Details', u'out of table'
IO - speech.speak (15:55:13):
Speaking LangChangeCommand ('en_GB'), u'About 42,592 results (0.20 seconds)', IndexCommand(11)
IO - speech.speak (15:55:13):
Speaking LangChangeCommand ('en_GB'), u'link', u'Click here to learn the top 10 ways StartPage protects your privacy.'
IO - inputCore.InputManager.executeGesture (15:55:14):
Input: kb(desktop):h
IO - speech.speak (15:55:14):
Speaking ('en_GB'), u'list with 20 items', u'There are known knowns - Wikipedia, the free encyclopedia', u'visited link', u' ', u'heading level 3'
IO - inputCore.InputManager.executeGesture (15:55:19):
Input: kb(desktop):enter
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_DocumentBrokenFocusedStateMozillaIAccessible._get_IA2Attributes (15:55:22):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_DocumentBrokenFocusedStateMozillaIAccessible._get_IA2Attributes (15:55:22):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUG - treeInterceptorHandler.killTreeInterceptor (15:55:22):
Killed treeInterceptor: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x03DFD350>
DEBUG - treeInterceptorHandler.update (15:55:23):
Adding new treeInterceptor to runningTable: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x03E834B0>
IO - speech.speak (15:55:24):
Speaking ('en_GB'), u'There are known knowns - Wikipedia, the free encyclopedia'
DEBUG - queueHandler.registerGeneratorObject (15:55:24):
Adding generator 163
IO - speech.speak (15:55:24):
Speaking LangChangeCommand ('en_GB'), u'main landmark ', u'heading level 1', u'There are known knowns'
IO - speech.speak (15:55:24):
Speaking LangChangeCommand ('en_GB'), u'From Wikipedia, the free encyclopedia', IndexCommand(3), u'Jump to:\t', u'link', u'navigation', u', ', u'link', u'search', IndexCommand(4), u'"Known unknowns" redirects here. '
IO - speech.speak (15:55:24):
Speaking ('en_GB'), u'For the ', u'link', u'House', u' episode, see ', u'link', u'Known Unknowns', u'.'
IO - speech.speak (15:55:24):
Speaking LangChangeCommand ('en_GB'), u'"There are known knowns" is a phrase from a response ', u'link', u'United States Secretary of Defense', u' ', u'link', u'Donald ', IndexCommand(6), u'link', u'Rumsfeld', u' gave to a question at a ', u'link', u'U.S. '
IO - speech.speak (15:55:24):
Speaking ('en_GB'), u'Department of Defense', u' news briefing in February 2002 about ', IndexCommand(7), u'the lack of evidence linking the government of ', u'link', u'Iraq', u' with the supply of ', u'link', u'weapons of mass destruction', u' ', IndexCommand(8), u'to terrorist groups.'
IO - speech.speak (15:55:24):
Speaking [('en_GB'), u'link', u'1']
IO - speech.speak (15:55:24):
Speaking LangChangeCommand ('en_GB'), u'Rumsfeld stated:'
IO - speech.speak (15:55:24):
Speaking LangChangeCommand ('en_GB'), u'block quote', u"Reports that say there's -- that something hasn't happened are always interesting to me, because as ", IndexCommand(11), u'we know, there are known knowns; there are things that we know that we know. '
IO - speech.speak (15:55:26):
Speaking ('en_GB'), u'We also know there are ', IndexCommand(12), u'known unknowns; that is to say we know there are some things we do not know. '
IO - speech.speak (15:55:28):
Speaking ('en_GB'), u'But there are also ', IndexCommand(13), u"unknown unknowns, the ones we don't know we don't know."
IO - speech.speak (15:55:30):
Speaking ('en_GB'), u'out of block quote'
IO - speech.speak (15:55:30):
Speaking LangChangeCommand ('en_GB'), u'The statement became the subject of much commentary and derision.'
IO - speech.speak (15:55:32):
Speaking [('en_GB'), u'link', u'2']
IO - inputCore.InputManager.executeGesture (15:55:38):
Input: kb(desktop):control+alt+r
IO - speech.speak (15:55:38):
Speaking ('en_GB'), u'reboot nvda with log terminal'

This machine spec.

MS Windows XP Professional SP3 AMD Athlon XP 2500+, 1.2GB RAM, NVIDIA RIVA TNT2 Model 64/Model 64 Pro

Now on another amd machine.

Firefox 32 amd sempron
DEBUG - queueHandler.registerGeneratorObject (16:53:52):
Adding generator 101
DEBUG - queueHandler.pumpAll (16:53:52):
generator 101 finished
IO - inputCore.InputManager.executeGesture (16:53:53):
Input: kb(desktop):enter
DEBUGWARNING - NVDAObjects.IAccessible.IA2TextTextInfo.getCaretOffset (16:54:21):
IAccessibleText::caretOffset failed
Traceback (most recent call last):
File "NVDAObjects\IAccessible__init_.pyc", line 151, in _getCaretOffset
COMError: (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_EditableTextWithAutoSelectDetectionMozillaIAccessible._get_IA2Attributes (16:54:31):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_DocumentBrokenFocusedStateMozillaIAccessible._get_IA2Attributes (16:54:31):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUG - treeInterceptorHandler.killTreeInterceptor (16:54:31):
Killed treeInterceptor: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x03D660B0>
DEBUG - treeInterceptorHandler.update (16:54:31):
Adding new treeInterceptor to runningTable: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x03D660F0>
IO - speech.speak (16:54:32):
Speaking ('en_GB'), u'alert Firefox automatically sends some data to Mozilla so that we can improve your experience.'
IO - speech.speak (16:54:32):
Speaking ('en_GB'), u'Choose What I Share button Alt+C'
IO - speech.speak (16:54:32):
Speaking ('en_GB'), u'Close this message button 3 of 3'
IO - speech.speak (16:54:32):
Speaking ('en_GB'), u'browse mode'
IO - speech.speak (16:54:32):
Speaking ('en_GB'), u'known unknowns - Startpage Web Search'
DEBUG - queueHandler.registerGeneratorObject (16:54:32):
Adding generator 102
IO - speech.speak (16:54:32):
Speaking [- speech.speak (16:54:32):
Speaking [IndexCommand(2), LangChangeCommand ('en_GB'), u'link', u'align default', u'Web', u' \xa0\xa0 ', IndexCommand(3), u'link', u'Images', u' \xa0\xa0 ', IndexCommand(4), u'link', u'Videos', u' \xa0\xa0 '](IndexCommand%281%29]
IO)
IO - speech.speak (16:54:32):
Speaking LangChangeCommand ('en_GB'), u'align default', IndexCommand(6), u'link', u'align default', u'Advanced Search', u' \xa0\xa0 ', IndexCommand(7), u'link', u'Settings'
IO - speech.speak (16:54:32):
Speaking LangChangeCommand ('en_GB'), u'link', u'graphic', u'align default', u'eng &prf=400639e51841c04f47940\u2026'
IO - speech.speak (16:54:32):
Speaking LangChangeCommand ('en_GB'), u'table with 2 rows and 5 columns ', u'row 1 column 1', u'edit', u'align default', u'"known unknowns"'
IO - speech.speak (16:54:32):
Speaking LangChangeCommand ('en_GB'), u'column 2', u'button', u'align default'
IO - speech.speak (16:54:42):
Speaking LangChangeCommand ('en_GB'), u'column 3', u'align center', u'enhanced by\n'
IO - speech.speak (16:54:42):
Speaking LangChangeCommand ('en_GB'), u'column 4', u'link', u'Details', u'out of table'
IO - speech.speak (16:54:44):
Speaking LangChangeCommand ('en_GB'), u'align default', u'About 42,406 results (0.29 seconds)', IndexCommand(14)
IO - speech.speak (16:54:44):
Speaking LangChangeCommand ('en_GB'), u'link', u'Click here to learn the top 10 ways StartPage protects your privacy.'
IO - speech.speak (16:54:45):
Speaking LangChangeCommand ('en_GB'), u'link', u'align default', u'Bookmark this search'
IO - speech.speak (16:54:47):
Speaking LangChangeCommand ('en_GB'), u'list with 20 items', u'heading level 3', u'visited link', u'align default', u'There are known knowns - Wikipedia, the free encyclopedia', u' '
IO - inputCore.InputManager.executeGesture (16:54:47):
Input: kb(desktop):h
IO - speech.speak (16:54:47):
Speaking ('en_GB'), u'list with 20 items', u'There are known knowns - Wikipedia, the free encyclopedia', u'visited link', u' ', u'heading level 3'
IO - inputCore.InputManager.executeGesture (16:54:53):
Input: kb(desktop):enter
DEBUGWARNING - NVDAObjects.IAccessible.Dynamic_DocumentBrokenFocusedStateMozillaIAccessible._get_IA2Attributes (16:55:12):
IAccessibleObject.attributes COMError (-2147220995, 'Object is not connected to server', (None, None, None, 0, None))
DEBUG - treeInterceptorHandler.killTreeInterceptor (16:55:12):
Killed treeInterceptor: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x03D660F0>
DEBUG - treeInterceptorHandler.update (16:55:12):
Adding new treeInterceptor to runningTable: <virtualBuffers.gecko_ia2.Gecko_ia2 object at 0x03D6F590>
IO - speech.speak (16:55:12):
Speaking ('en_GB'), u'There are known knowns - Wikipedia, the free encyclopedia'
DEBUG - queueHandler.registerGeneratorObject (16:55:12):
Adding generator 103
IO - speech.speak (16:55:12):
Speaking LangChangeCommand ('en_GB'), u'main landmark ', u'heading level 1', u'align default', u'There are known knowns'
IO - speech.speak (16:55:12):
Speaking LangChangeCommand ('en_GB'), u'From Wikipedia, the free encyclopedia', IndexCommand(3), u'Jump to: ', IndexCommand(4), u'link', u'navigation', u', ', IndexCommand(5), u'link', u'search', IndexCommand(6), u'"Known unknowns" redirects here. '
IO - speech.speak (16:55:13):
Speaking ('en_GB'), u'For the ', IndexCommand(7), u'link', u'House', u' episode, see ', IndexCommand(8), u'link', u'Known Unknowns', u'.'
IO - speech.speak (16:55:15):
Speaking LangChangeCommand ('en_GB'), u'"There are known knowns" is a phrase from a response ', IndexCommand(10), u'link', u'United States Secretary of Defense', u' ', IndexCommand(11), u'link', u'Donald Rumsfeld', u' gave to a question at a ', IndexCommand(12), u'link', u'U.S. '
IO - inputCore.InputManager.executeGesture (16:55:20):
Input: kb(desktop):control+alt+i
IO - speech.speak (16:55:20):
Speaking ('en_GB'), u'reboot nvda with log terminal'
IO - speech.speak (16:55:20):
Speaking ('en_GB'), u'blank'
INFO - core.main (16:55:22):
Exiting

MS Windows XP Professional SP3AMD Sempron 2500+, 1.2GB RAM, NVIDIA GeForce FX 5200

Weird. It seems hit and miss which hardware causes Firefox to be slow.

I guess this ticket should go to Mozilla, and it marked here as needs external fix, well all but the start up bit.

@nvaccessAuto
Copy link
Author

Comment 4 by jteh on 2014-07-28 23:31
I'm not really willing to file a bug with Mozilla myself for this, as I can't reproduce it myself. Copying Marco for his reference and in case he has any further thoughts.

@nvaccessAuto
Copy link
Author

Comment 5 by MarcoZehe (in reply to comment 4) on 2014-07-29 05:15
Replying to jteh:

I'm not really willing to file a bug with Mozilla myself for this, as I can't reproduce it myself. Copying Marco for his reference and in case he has any further thoughts.

I've asked Brian on the list to set the about:config preference layers.offmainthreadcomposition.enabled to false, disabling the change that originally brought about the wm_getobject failure during the 32 cycle, which was subsequently fixed. My suspicion is that this is Windows-XP-specific fall-out from that change, which nobody tested because by that time, Windows XP itself had reached its official end of life. I, myself, also no longer have a Windows XP to test.

@nvaccessAuto
Copy link
Author

Comment 6 by jteh on 2014-07-29 05:23
Just so we don't lose this, here is the related nvda-devel discussion thread.

@nvaccessAuto
Copy link
Author

Comment 7 by MarcoZehe on 2014-07-29 08:56
I filed Mozilla bug 1045518 for this issue, but had to set its status to UNCONFIRMED because I cannot reproduce this on either Windows 7 nor 8, so it seems to be very specific to Windows XP.

@nvaccessAuto
Copy link
Author

Comment 8 by briang1 on 2014-07-29 12:29
Well, I do not think it is XP, as I've now seen this not be present on some XP machines. Notably both with Athlon single core processors. It is also present on one machine running a Sempron, but not on another. I'm wondering if its tied up with graphic or something.

@nvaccessAuto
Copy link
Author

Comment 9 by briang1 on 2014-07-31 06:32

      ----- Original Message ----- 
      From: Marco Zehe 
      To: NVDA screen reader development 
      Sent: Tuesday, July 29, 2014 6:12 AM
      Subject: Re: [Nvda-devel] Firefox 32 much slower than 31?


      Hi Brian,


      I have a suspicion, and would ask if you could confirm it. Please, take the following steps in Firefox 32 Beta:

        1.. In the URL bar, type about:config. 
        2.. If a warning appears, press the "I'll be careful, I promise!" button. 
        3.. In the search field, find the preference layers.offmainthreadcomposition.enabled. 
        4.. Tab to the list where this preference should now be selected, and hit the Context Menu key. 
        5.. Select the Toggle menu item. This will set it from the default of True to False. 
        6.. Restart Firefox. 
        7.. Test your scenarios.
      Does the problem go away? If yes, I'd like to know. If not, I'd also like to know. :)

Brian responded in the list...
Interestingly, the opposite of what you just said is true, ie its set to false and toggling it on either fixes most of the issues, or makes little difference, depending on the machine. IE machines that have not got the issue in xp, are not affected, but those which have are a lot better, with only the start up problem an issue now.

On all xp machines turning that option on either makes no difference on some machines which are fairly fast already, or makes it a lot better on the ones mostly affected. I have no idea what it is that makes a machine one or the other though, as they do not seem to be very different on other programs particularly.

There is one snag with 32 which is not affected, the way it will not actually display at start up about half the time. It usually needs a nudge from f5 or down cursor or alt. 28 on the other hand normally does work, and occasionally does not. I suspect its really there, but nvda cannot get the message without poking Firefox to do something. I think I put a log of this on the ticket for nvda.
The other thing I found is that the classic theme restorer add on, which works fine on 31, seems to get muddled sometimes on 32, leaving you with a focus on some invisible control above the address bar. I have now uninstalled it as in any case, with it in there and given the way Firefoxes on one machine share add ons, every time you run 28, it says its incompatible anyway.

Probably needs a tweak.
So if we can solve the issue of not seeing it at the start, it should be OK.

@dkager
Copy link
Collaborator

dkager commented Jul 2, 2017

According to the Mozilla bug Marco referenced this was fixed in FF 33.

@jcsteh jcsteh closed this as completed Jul 3, 2017
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
blocked/needs-external-fix bug performance
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@dkager @jcsteh @nvaccessAuto