From: Dan L. <da...@la...> - 2006-01-29 15:16:23
|
On 28 Jan 2006 at 17:41, Dan Langille wrote: > Hi folks, > > I upgraded a XP Home box to use the 1.38.4 client today. I also > added in the VSS option. During the first run (using 1.38.5 > Director, etc), I ran a message command in the console, and the > Director had gone away. > > *mes > 28-Jan 15:20 bconsole: Error: bnet.c:426 Write error sending 3 bytes > to Director daemon:bacula.unixathome.org:9101: ERR=Broken pipe > > I restarted the Director. Going into bconsole, this was the first > message I saw: > > *mes > 28-Jan 15:20 bconsole: Error: bnet.c:426 Write error sending 3 bytes > to Director daemon:bacula.unixathome.org:9101: ERR=Broken pipe > > I restarted the job and went out. > > Later that day, I got a phone call from a distressed 16 year old > studying for exams who told me that the XP computer had restarted and > she'd lost the document she's been writing. > > Checking the job logs: > > *run job=pepper yes > Job started. JobId=7348 > *mes > 28-Jan 15:22 bacula-dir: No prior Full backup Job record found. > 28-Jan 15:22 bacula-dir: No prior or suitable Full backup found. > Doing FULL backup. > 28-Jan 15:22 bacula-dir: Start Backup JobId 7348, Job=pepper.2006-01- > 28_15.22.53 > *mes > 28-Jan 15:23 polo-sd: Volume "FILE-0001" previously written, moving > to end of data. > 28-Jan 15:23 polo-sd: Spooling data ... > 28-Jan 15:24 pepper-fd: Cannot open c:/Documents and > Settings/Dan/NTUSER.DAT: ERR=The process cannot access the file > because it is being used by another process. > . > 28-Jan 15:24 pepper-fd: Cannot open c:/Documents and > Settings/Dan/ntuser.dat.LOG: ERR=The process cannot access the file > because it is being used by another process. > . > 28-Jan 15:26 pepper-fd: Cannot open c:/Documents and > Settings/OtherUserKatrina/Local Settings/Application > Data/Microsoft/Windows/UsrClass.dat: ERR=The process cannot access > the file because it is being used by another process. > . > 28-Jan 15:26 pepper-fd: Cannot open c:/Documents and > Settings/OtherUser/Local Settings/Application > Data/Microsoft/Windows/UsrClass.dat.LOG: ERR=The process cannot > access the file because it is being used by another process. > . > *mes > 28-Jan 15:38 pepper-fd: Cannot open c:/Documents and > Settings/OtherUser/NTUSER.DAT: ERR=The process cannot access the file > because it is being used by another process. > . > 28-Jan 15:38 pepper-fd: Cannot open c:/Documents and > Settings/OtherUser/ntuser.dat.LOG: ERR=The process cannot access the > file because it is being used by another process. > . > 28-Jan 15:46 pepper-fd: Cannot open c:/Documents and > Settings/LocalService/Local Settings/Application > Data/Microsoft/Windows/UsrClass.dat: ERR=The process cannot access > the file because it is being used by another process. > . > 28-Jan 15:46 pepper-fd: Cannot open c:/Documents and > Settings/LocalService/Local Settings/Application > Data/Microsoft/Windows/UsrClass.dat.LOG: ERR=The process cannot > access the file because it is being used by another process. > . > 28-Jan 15:46 pepper-fd: Cannot open c:/Documents and > Settings/LocalService/NTUSER.DAT: ERR=The process cannot access the > file because it is being used by another process. > . > 28-Jan 15:46 pepper-fd: Cannot open c:/Documents and > Settings/LocalService/ntuser.dat.LOG: ERR=The process cannot access > the file because it is being used by another process. > . > 28-Jan 15:46 pepper-fd: Cannot open c:/Documents and > Settings/NetworkService/Local Settings/Application > Data/Microsoft/Windows/UsrClass.dat: ERR=The process cannot access > the file because it is being used by another process. > . > 28-Jan 15:46 pepper-fd: Cannot open c:/Documents and > Settings/NetworkService/Local Settings/Application > Data/Microsoft/Windows/UsrClass.dat.LOG: ERR=The process cannot > access the file because it is being used by another process. > . > 28-Jan 15:46 pepper-fd: Cannot open c:/Documents and > Settings/NetworkService/NTUSER.DAT: ERR=The process cannot access the > file because it is being used by another process. > . > 28-Jan 15:46 pepper-fd: Cannot open c:/Documents and > Settings/NetworkService/ntuser.dat.LOG: ERR=The process cannot access > the file because it is being used by another process. > . > 28-Jan 15:50 polo-sd: Committing spooled data to Volume "FILE-0001". > Despooling 6,197,327,755 bytes ... > 28-Jan 16:10 polo-sd: Sending spooled attrs to the Director. > Despooling 3,198,410 bytes ... > 28-Jan 16:11 bacula-dir: Bacula 1.38.5 (18Jan06): 28-Jan-2006 > 16:11:52 > JobId: 7348 > Job: pepper.2006-01-28_15.22.53 > Backup Level: Full (upgraded from Incremental) > Client: "pepper-fd" Windows XP,MVS,NT 5.1.2600 > FileSet: "pepper files" 2006-01-28 14:49:11 > Pool: "Default" > Storage: "File" > Scheduled time: 28-Jan-2006 15:22:53 > Start time: 28-Jan-2006 15:22:55 > End time: 28-Jan-2006 16:11:52 > Priority: 10 > FD Files Written: 8,859 > SD Files Written: 8,859 > FD Bytes Written: 6,189,446,123 > SD Bytes Written: 6,191,287,331 > Rate: 2107.4 KB/s > Software Compression: None > Volume name(s): FILE-0001 > Volume Session Id: 57 > Volume Session Time: 1138108315 > Last Volume Bytes: 18,548,046,572 > Non-fatal FD errors: 14 > SD Errors: 0 > FD termination status: OK > SD termination status: OK > Termination: Backup OK -- with warnings > > 28-Jan 16:11 bacula-dir: Begin pruning Jobs. > 28-Jan 16:11 bacula-dir: No Jobs found to prune. > 28-Jan 16:11 bacula-dir: Begin pruning Files. > 28-Jan 16:11 bacula-dir: Pruned Files from 1 Jobs for client pepper- > fd from catalog. > 28-Jan 16:11 bacula-dir: End auto prune. > > The end time for the job is interesting. I got the phone call at > 16:14 telling me that the computer had restarted. I talked her > through shutting down the bacula-fd client, just in case. > > I think that timing is too close to just be a coincidence. She says > the XP system did not crash. It did a shutdown and then restarted. I > don't want to ask for any more details just now. ;) > > Any ideas/suggestions/comments? I ran the job again today. One with VSS, one without. The one with VSS looked like this: 29-Jan 09:09 bacula-dir: Start Backup JobId 7369, Job=pepper.2006-01- 29_09.09.54 29-Jan 09:10 polo-sd: Volume "FILE-0001" previously written, moving to end of data. 29-Jan 09:10 polo-sd: Spooling data ... 29-Jan 09:10 pepper-fd: Generate VSS snapshots. Driver="VSS WinXP", Drive(s)="C" 29-Jan 09:10 pepper-fd: VSS Writer: "Microsoft Writer (Bootable State)", State: 1 (VSS_WS_STABLE) 29-Jan 09:10 pepper-fd: VSS Writer: "Microsoft Writer (Service State)", State: 1 (VSS_WS_STABLE) 29-Jan 09:10 pepper-fd: VSS Writer: "MSDEWriter", State: 1 (VSS_WS_STABLE) 29-Jan 09:10 pepper-fd: VSS Writer: "WMI Writer", State: 1 (VSS_WS_STABLE) 29-Jan 09:30 polo-sd: Committing spooled data to Volume "FILE-0001". Despooling 6,234,773,559 bytes ... 29-Jan 09:45 polo-sd: Sending spooled attrs to the Director. Despooling 3,287,868 bytes ... 29-Jan 09:46 bacula-dir: Bacula 1.38.5 (18Jan06): 29-Jan-2006 09:46:48 JobId: 7369 Job: pepper.2006-01-29_09.09.54 Backup Level: Full Client: "pepper-fd" Windows XP,MVS,NT 5.1.2600 FileSet: "pepper files" 2006-01-28 14:49:11 Pool: "Default" Storage: "File" Scheduled time: 29-Jan-2006 09:09:52 Start time: 29-Jan-2006 09:09:56 End time: 29-Jan-2006 09:46:48 Priority: 10 FD Files Written: 9,106 SD Files Written: 9,106 FD Bytes Written: 6,226,797,486 SD Bytes Written: 6,228,689,575 Rate: 2815.0 KB/s Software Compression: None Volume name(s): FILE-0001 Volume Session Id: 78 Volume Session Time: 1138108315 Last Volume Bytes: 43,133,853,921 Non-fatal FD errors: 0 SD Errors: 0 FD termination status: OK SD termination status: OK Termination: Backup OK 29-Jan 09:46 bacula-dir: Begin pruning Jobs. 29-Jan 09:46 bacula-dir: No Jobs found to prune. 29-Jan 09:46 bacula-dir: Begin pruning Files. 29-Jan 09:46 bacula-dir: No Files found to prune. 29-Jan 09:46 bacula-dir: End auto prune. No Dir crash. No XP reboot. No explanation as to why this happened. -- Dan Langille : http://www.langille.org/ BSDCan - The Technical BSD Conference - http://www.bsdcan.org/ |