[wpkg-users] wpkgclient on shutdown on Vista

Wed Feb 24 11:49:01 CET 2010

I use wpkgclient on shutdown on Windows XP.
I'm testing it on Vista, but it reboots after about 30 seconds.
I know the GUI doesn't work because it can not interact with the desktop.

DETAILS:
Vista Business SP1 32 bit italian
UAC disabled
No antivirus (for testing)
wpkgclient installed as administrator (cmd as administrator, msiexec /i ... )
wpkgclient-1.3.14, in the past I tried other versions, from 1.3.6 on
After I discovered the problem I simplified the wpkg configuration for testing purposes.

SYMPTOMS:
wpkgclient start wpkg.js but after about 30 seconds the client reboots during the execution of the package command.
The wpkg log is truncated

EVENTLOGS:

Nome registro: Application
Origine: WSH
Data: 24/02/2010 11.05.34
ID evento: 4
Categoria attività:Nessuno
Livello: Informazioni
Parole chiave: Classico
Utente: N/D
Computer: esprimo259.intranet
Descrizione:
Installing 'un_minuto' (un_minuto)...

Nome registro: Application
Origine: WSH
Data: 24/02/2010 11.05.34
ID evento: 4
Categoria attività:Nessuno
Livello: Informazioni
Parole chiave: Classico
Utente: N/D
Computer: esprimo259.intranet
Descrizione:
User notification suppressed. Message: L'utilità di installazione automatica del software sta aggiornando il sistema. Verifica la marcatura temporale di questa segnalazione per capire se c'è qualche problema. Se tutto è a posto, salva e chiudi tutte le applicazioni aperte, perchè potrebbe essere necessario un riavvio. Se necessario, il sistema verrà infatti riavviato senza alcun preavviso. Grazie.

Nome registro: System
Origine: Service Control Manager
Data: 24/02/2010 11.06.06
ID evento: 7043
Categoria attività:Nessuno
Livello: Errore
Parole chiave: Classico
Utente: N/D
Computer: esprimo259.intranet
Descrizione:
Il servizio WPKG Service non è stato arrestato correttamente dopo la ricezione di un controllo di pre-arresto del sistema.

In english is something like:
The WPKG service has not been correctly arrested after receiving a pre-shutdown control

Informazioni 24/02/2010 11.06.06 EventLog 6006 Nessuno
Nome registro: System
Origine: EventLog
Data: 24/02/2010 11.06.06
ID evento: 6006
Categoria attività:Nessuno
Livello: Informazioni
Parole chiave: Classico
Utente: N/D
Computer: esprimo259.intranet
Descrizione:
Il servizio Registro eventi è stato arrestato.

WPKGCLIENT DEBUG LOG

...
2010-02-24 10:55:33 -> System query service status
2010-02-24 10:55:46 -> System query service status
2010-02-24 11:05:32 -> System query service status
2010-02-24 11:05:32 -> System query service status
2010-02-24 11:05:33 -> System send pre-shutdown signal
2010-02-24 11:05:33 -> SetEvent STOP (WAIT_OBJECT_0)
2010-02-24 11:05:33 -> Before create shared memory: successfuly done.
2010-02-24 11:05:33 -> Create shared memory: successfuly done.
2010-02-24 11:05:33 -> Set script security context: successfuly done.
2010-02-24 11:05:33 -> Offline mode enabled: successfuly done.
2010-02-24 11:05:33 -> Offline mode: server connecting method selected.
2010-02-24 11:05:34 -> Server connecting: successfuly done.
2010-02-24 11:05:34 -> Starting script action execution
2010-02-24 11:07:03 -> Initializing socket... <- after reboot
2010-02-24 11:07:03 -> ServiceStart->SERVICE_RUNNING
2010-02-24 11:07:12 -> System query service status
2010-02-24 11:08:12 -> System query service status
2010-02-24 11:08:29 -> System query service status

WPKG LOG:

2010-02-24 11:05:34, DEBUG : Initialized temporary local log file: C:\Windows\TEMP\wpkg-logInit.log
2010-02-24 11:05:34, DEBUG : Initializing new log file: C:\Windows\TEMP\esprimo259-20100224-1105.log
2010-02-24 11:05:34, DEBUG : Reboot-Cmd is standard.
2010-02-24 11:05:34, DEBUG : WPKG 1.1.2 starting...
2010-02-24 11:05:34, DEBUG : Base directory is '\\server\install\wpkg-test2'.
2010-02-24 11:05:34, DEBUG : Log level is 31
...

2010-02-24 11:05:34, DEBUG : Found installed package 'un_minuto' (un_minuto).
2010-02-24 11:05:34, DEBUG : Package 'un_minuto' (un_minuto) found in profile packages.
2010-02-24 11:05:34, DEBUG : Number of packages to remove: 0
2010-02-24 11:05:34, DEBUG : Going to install package 'un_minuto' (un_minuto), Revision 1, (execute flag is 'always', notify flag is 'true').
2010-02-24 11:05:34, DEBUG : Package 'un_minuto' (un_minuto): Not yet processed during this session.
2010-02-24 11:05:34, DEBUG : Package 'un_minuto' (un_minuto): Prepared for installation.
2010-02-24 11:05:34, DEBUG : Installation of references (dependencies) for 'un_minuto' (un_minuto) successfully finished.
2010-02-24 11:05:34, INFO : Installing 'un_minuto' (un_minuto)...
2010-02-24 11:05:34, DEBUG : Saving current environment
2010-02-24 11:05:34, DEBUG : Reading variables from package un_minuto
2010-02-24 11:05:34, DEBUG : Reading variables from profile[s]
2010-02-24 11:05:34, DEBUG : Getting profiles which apply to this node.
2010-02-24 11:05:34, DEBUG : 1 profiles apply to this host.
2010-02-24 11:05:34, DEBUG : Reading variables from profile esprimo259-install
2010-02-24 11:05:34, DEBUG : Reading variables from host esprimo259
2010-02-24 11:05:34, DEBUG : Install type: install
2010-02-24 11:05:34, DEBUG : Fetched 1 install command(s).
2010-02-24 11:05:34, DEBUG : Found system locale: 410
2010-02-24 11:05:34, DEBUG : Found language definition node for language ID 410
2010-02-24 11:05:34, INFO : User notification suppressed. Message: L'utilità di installazione automatica del software sta aggiornando il sistema. Verifica la marcatura temporale di questa segnalazione per capire se c'è qualche problema. Se tutto è a posto, salva e chiudi tutte le applicazioni aperte, perchè potrebbe essere necessario un riavvio. Se necessario, il sistema verrà infatti riavviato senza alcun preavviso. Grazie.
2010-02-24 11:05:34, DEBUG : Executing command: ping 127.0.0.1 -n 60 -w 1

REGISTRY:

[HKEY_LOCAL_MACHINE\SYSTEM\CurrentControlSet\Services\WpkgService]
"Type"=dword:00000110
"Start"=dword:00000002
"ErrorControl"=dword:00000001
"ImagePath"=hex(2):43,00,3a,00,5c,00,50,00,72,00,6f,00,67,00,72,00,61,00,6d,00,\
 20,00,46,00,69,00,6c,00,65,00,73,00,5c,00,77,00,70,00,6b,00,67,00,5c,00,57,\
 00,50,00,4b,00,47,00,53,00,72,00,76,00,2e,00,65,00,78,00,65,00,00,00
"DisplayName"="WPKG Service"
"DependOnService"=hex(7):61,00,66,00,64,00,00,00,74,00,63,00,70,00,69,00,70,00,\
 00,00,00,00 <- afd tcpip
"ObjectName"="LocalSystem"
"Description"="Software deployment"
"PreshutdownTimeout"=dword:001b7740 <- 1800000

[HKEY_LOCAL_MACHINE\SYSTEM\CurrentControlSet\Services\WpkgService\Enum]
"0"="Root\\LEGACY_WPKGSERVICE\\0000"
"Count"=dword:00000001
"NextInstance"=dword:00000001

[HKEY_LOCAL_MACHINE\SOFTWARE\Microsoft\Windows NT\CurrentVersion\Winlogon\Notify\WPKGLogon]
"MaxWait"=dword:00000000
"Impersonate"=dword:00000000
"DLLName"="C:\\Program Files\\wpkg\\wpkglogon.dll"
"Asynchronous"=dword:00000000
"Shutdown"="WLEventShutdown"
"Startup"="WLEventStartup"
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.wpkg.org/pipermail/wpkg-users/attachments/20100224/5cbc7221/attachment-0001.html>