Update Build and NVDA crashes/errors and have to repeat

[kevinchao89]

Version: main-5191

For a while, since automatic updates was introduced. Regardless of which method I go about updating, whether it be automatic update or running from .exe, and if I am running local or install copy, updating install copy will always fail the first time and work the second time.
Note: Portable copy updates without any issues.

Log is as follows:
Input: kb(laptop):enter
IO - speech.speak (18:37:17):
Speaking [LangChangeCommand ('en'), u'Open File - Security Warning dialog Do you want to run this file?\nName:\n... Chao\\Downloads\\nvda_snapshot_main-5191.exe\nPublisher:\nNV Access Inc\nType:\nApplication\nFrom:\nC:\\Users\\Kevin Chao\\Downloads\\nvda_snapshot_...\nWhat\u2019s the risk?']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (18:37:17):
Unknown speech command: LangChangeCommand ('en')
IO - speech.speak (18:37:17):
Speaking [LangChangeCommand ('en'), u'NV Access Inc link']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (18:37:17):
Unknown speech command: LangChangeCommand ('en')

[jteh]

When you say fail, what do you mean? Does NVDA start the install and then report that it failed? If so, we need a log from the temporary copy (i.e. just after the error dialog).

[kevinchao89]

Run .exe;
Select Run, accept ULA, select install local copy on computer, and hit continue;
ALT+Y for UAC;
And, here is where I get the following error message:
"The installation of NVDA failed. Please check the Log Viewer for more information."
In my original description, I provided the info from the log viewer

[kevinchao89]

Input: kb(laptop):space
IO - speech.speak (19:26:13):
Speaking [LangChangeCommand ('en'), u'pressed']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (19:26:13):
Unknown speech command: LangChangeCommand ('en')
IO - speech.speak (19:26:13):
Speaking [LangChangeCommand ('en'), u'Updating NVDA dialog']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (19:26:13):
Unknown speech command: LangChangeCommand ('en')
IO - speech.speak (19:26:13):
Speaking [LangChangeCommand ('en'), u'Please wait while your previous installation of NVDA is being updated. text']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (19:26:13):
Unknown speech command: LangChangeCommand ('en')
IO - speech.speak (19:26:13):
Speaking [LangChangeCommand ('en'), u'Secure Desktop']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (19:26:13):
Unknown speech command: LangChangeCommand ('en')
IO - speech.speak (19:26:19):
Speaking [LangChangeCommand ('en'), u'Updating NVDA dialog']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (19:26:19):
Unknown speech command: LangChangeCommand ('en')
IO - speech.speak (19:26:19):
Speaking [LangChangeCommand ('en'), u'Please wait while your previous installation of NVDA is being updated. text']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (19:26:19):
Unknown speech command: LangChangeCommand ('en')
IO - tones.beep (19:26:19):
Beep at pitch 440, for 40 ms, left volume 50, right volume 50
DEBUGWARNING - RPC process 1144 (nvda_slave.exe) (19:26:19):
installer.copyProgramFiles:
Unable to copy C:\Users\KEVINC~1\AppData\Local\Temp\nsb101B.tmp\app\nvda_slave.exe, trying rename and delete on reboot
DEBUGWARNING - RPC process 1144 (nvda_slave.exe) (19:26:20):
installer.copyProgramFiles:
Unable to copy C:\Users\KEVINC~1\AppData\Local\Temp\nsb101B.tmp\app\lib\IAccessible2Proxy.dll, trying rename and delete on reboot
IO - tones.beep (19:26:20):
Beep at pitch 440, for 40 ms, left volume 50, right volume 50
ERROR - RPC process 1144 (nvda_slave.exe) (19:26:20):
nvda_slave.main:
slave error
Traceback (most recent call last):

File "nvda_slave.pyw", line 26, in main
File "installer.pyc", line 257, in install

OSError: Error copying C:\Program Files\NVDA\nvda_UIAccess.exe to nvda.exe
IO - tones.beep (19:26:20):
Beep at pitch 1760, for 40 ms, left volume 50, right volume 50
ERROR - gui.installerGui.doInstall (19:26:20):
Installation failed: 1
IO - speech.speak (19:26:20):
Speaking [LangChangeCommand ('en'), u'Downloads']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (19:26:20):
Unknown speech command: LangChangeCommand ('en')
IO - speech.speak (19:26:20):
Speaking [LangChangeCommand ('en'), u'Items View list']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (19:26:20):
Unknown speech command: LangChangeCommand ('en')
IO - speech.speak (19:26:20):
Speaking [LangChangeCommand ('en'), u'nvda_snapshot_main-5191.exe']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (19:26:20):
Unknown speech command: LangChangeCommand ('en')
IO - speech.speak (19:26:20):
Speaking [LangChangeCommand ('en'), u'Error dialog The installation of NVDA failed. Please check the Log Viewer for more information.']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (19:26:20):
Unknown speech command: LangChangeCommand ('en')
IO - speech.speak (19:26:20):
Speaking [LangChangeCommand ('en'), u'OK button']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (19:26:20):
Unknown speech command: LangChangeCommand ('en')
IO - inputCore.InputManager.executeGesture (19:26:25):

[jteh]

Strange. I can certainly see how this would have happened before, but changeset:ac735c6968ef1c71d13e08cec6bcb06ad40034fa was supposed to fix this problem.

[briang1]

Looking at this, it remended me of a friend of mine who never shuts his laptop down, and it got into a very confused state after a few updates and merely shutting it down properly and rebooting made it work properly. I assumed when he told me it was finger trouble, but maybe hibernate or standby does have some issues inWindows 7?

Worth a try.

[jteh]

Kevin, are you running a virus scanner? If so, can you please try with this disabled?

[jteh]

changeset:35d2992a8c21d9e2c779517c73b3d64744e74a7b through changeset:b2e5919dadaa8c138ad5368a54b7604141f1b20e (merged in changeset:d48819415d3fb3dbef9d33e693e0c60fed357d53) may fix, allow retry or at least help us to debug this. Even if it asks you to retry, please provide a log; we'd still like to know why this is happening. Also, please do answer comment:6.

[kevinchao89]

Only virus scanner I'm running is Security Essentials (MSE)

It's quite rare that I shutdown or restart my Windows PC, but this time I restarted before doing a check for update.
After selecting checking for update, selecitng download/install, and ALT+Y for UAC; the followign error message was returned:

"The installation is unable to remove or overwrite a file. Another copy of NVDA may be running on another logged-on user account. Please make sure all installed copies of NVDA are shut down and try the installation again."
Note:
1) This is right after a restart and there are no other copies running (maybe, installer is having an issue with closing out its own process or local/install copy?)
2) Selecting "retry" worked this time. I will wait for the next couple of snapshots and see what happens. However, for now, I will go ahead and close this bug.

Following is log:

NVDA initialized
IO - speech.speak (17:28:21):
Speaking [LangChangeCommand ('en'), u'Updating NVDA dialog']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (17:28:21):
Unknown speech command: LangChangeCommand ('en')
IO - speech.speak (17:28:21):
Speaking [LangChangeCommand ('en'), u'Please wait while your previous installation of NVDA is being updated. text']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (17:28:21):
Unknown speech command: LangChangeCommand ('en')
IO - speech.speak (17:28:22):
Speaking [LangChangeCommand ('en'), u'Secure Desktop']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (17:28:22):
Unknown speech command: LangChangeCommand ('en')
IO - speech.speak (17:28:24):
Speaking [LangChangeCommand ('en'), u'Updating NVDA dialog']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (17:28:24):
Unknown speech command: LangChangeCommand ('en')
IO - speech.speak (17:28:24):
Speaking [LangChangeCommand ('en'), u'Please wait while your previous installation of NVDA is being updated. text']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (17:28:24):
Unknown speech command: LangChangeCommand ('en')
IO - tones.beep (17:28:24):
Beep at pitch 440, for 40 ms, left volume 50, right volume 50
DEBUGWARNING - RPC process 3704 (nvda_slave.exe) (17:28:24):
installer.copyProgramFiles:
Unable to copy C:\Users\KEVINC~1\AppData\Local\Temp\nsbFDB1.tmp\app\nvda_slave.exe, trying rename and delete on reboot
ERROR - RPC process 3704 (nvda_slave.exe) (17:28:24):
nvda_slave.main:
Installation failed, try again
Traceback (most recent call last):

File "nvda_slave.pyw", line 26, in main
File "installer.pyc", line 257, in install
File "installer.pyc", line 118, in copyProgramFiles

RetriableFailier: Failed to rename C:\Program Files\NVDA\nvda_slave.exe after failed remove
ERROR - gui.installerGui.doInstall (17:28:24):
Failed to execute installer
Traceback (most recent call last):

File "gui\installerGui.pyc", line 32, in doInstall

RetriableFailier
IO - tones.beep (17:28:24):
Beep at pitch 1760, for 40 ms, left volume 50, right volume 50
IO - speech.speak (17:28:24):
Speaking [LangChangeCommand ('en'), u'File in Use dialog The installation is unable to remove or overwrite a file. Another copy of NVDA may be running on another logged-on user account. Please make sure all installed copies of NVDA are shut down and try the installation again.']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (17:28:24):
Unknown speech command: LangChangeCommand ('en')
IO - speech.speak (17:28:24):
Speaking [LangChangeCommand ('en'), u'Retry button Alt+r']
DEBUGWARNING - synthDriverHandler.SynthDriver.speak (17:28:24):
Unknown speech command: LangChangeCommand ('en')
DEBUGWARNING - RPC process 2184 (nvda_update_nitb22.exe) (17:28:28):
Thread 2092, nvdaHelper\build\x86\remote\ia2Support.cpp, IA2Support_inProcess_terminate, 168:
WaitForMultipleObjects returned 258

[jteh]

I suspect the virus scanner is the problem here. Unfortunately, i don't really know how we can work around it. It looks like when we remove a file, it still stays around for a while. It's also blocking file renames for so-called deleted files.

[elliott94]

Replying to kevinchao89:

Only virus scanner I'm running is Security Essentials (MSE)

I've seen several people experiencing this issue, all of which seem to be using MSE.

Couldn't you try disabling the software temperarily if this issue reappears?

[mdcurran]

Please try snapshot for changeset:320fd5d244b16bb1321f0017fb5e2fc8d80c3a2f when its available. I hope it fixes your issue. It seems that removing a file and the copying to that location can fail in certain situations (specially where a virus scanner is involved).
Plan is to have beta3 (hopefully last one) out as soon as we know this is fixed.

[kevinchao89]

Excellent job! I updated to 5194 and there was no error at all, it was very smooth and seamless! Thank you! I think we can call this beta 3!