Start a Conversation

Unsolved

This post is more than 5 years old

S

1355

April 7th, 2009 05:00

NME 5.1.2: Error "No savetime was sent"

Hi,

We're using NME 5.1.2 for backups of active node in a Exchange 2007 CCR cluster. The backup seems to save the data successfully but the job reports as failed at the end with error message "No savetime was sent".
We have another cluster with same config and backup runs just fine.
Anyone seen this error?

best regards,
Stefan

2 Intern

 • 

14.3K Posts

April 7th, 2009 06:00

What does module log say? Can you paste whole session?

9 Posts

April 7th, 2009 06:00

Ok, here's the module log (edited for clarity.)

nsrxchsv 14 Start time: %s 1 35 25 Mon Apr 06 20:13:00 2009

43621 1239041580 1 nsrxchsv 35 Computer Name: %s User Name: %s 2 12 9 BL-MAILN3 13 6 SYSTEM
52705 1239041580 1 nsrxchsv 40 Computer Name: %s User Name: %s (%s) 3 0 9 BL-MAILN3 0 19 NT AUTHORITY\SYSTEM 0 6 SYSTEM
52708 1239041580 1 nsrxchsv 17 %s Version: %s %s 3 23 16 nsrxchsv_ese.exe 0 9 5.1.2.237 0 24 Supporting Exchange 2007
52702 1239041580 1 nsrxchsv 33 System Version: %d.%d Build %d %s 4 1 1 5 1 1 2 1 4 3790 0 1 S
52708 1239041580 1 nsrxchsv 17 %s Version: %s %s 3 23 12 ESEBCLI2.DLL 0 14 08.01.0329.000 0 14 Service Pack 1
43337 1239041580 1 nsrxchsv 13 Command line: 0
51342 1239041580 1 nsrxchsv 4 %s 1 40 154 "C:\Program Files\Legato\nsr\bin\nsrxchsv.exe" -s bl-back1.vagverket.dr.vv.se -g BL_EXCH_DAGLIG2 -LL -m bl-mailbx2 -l full -q -W 78 -N MSEXCH:IS MSEXCH:IS
46901 1239041580 1 nsrxchsv 16 %s is clustered. 1 0 9 BL-MAILN3
52681 1239041580 1 nsrxchsv 28 NetWorker Server Version: %s 1 0 45 NetWorker 7.4.3.Build.569 Network Edition/396
52673 1239041580 1 nsrxchsv 84 Console Code Page: %ld; System Default Locale: %ld; Master Flag: %d (originally: %d) 4 2 4 1252 2 4 1053 1 1 2 1 1 1
18130 1239041580 1 nsrxchsv 8 Options: 0
52649 1239041580 1 nsrxchsv 12 Server: %s 1 0 27 bl-back1.vagverket.dr.vv.se
52650 1239041580 1 nsrxchsv 12 Client: %s 1 0 10 bl-mailbx2
52651 1239041580 1 nsrxchsv 20 Virtual Server: %s 1 0 10 bl-mailbx2
52652 1239041580 1 nsrxchsv 19 Save set name: %s 1 0 9 MSEXCH:IS
52653 1239041580 1 nsrxchsv 10 Pool: %s 1 0 4 None
52654 1239041580 1 nsrxchsv 11 Group: %s 1 0 15 BL_EXCH_DAGLIG2
52655 1239041580 1 nsrxchsv 11 Level: %s 1 0 4 full
52656 1239041580 1 nsrxchsv 14 Compress: %s 1 0 5 False
52657 1239041580 1 nsrxchsv 25 Temporary directory: %s 1 0 31 C:\Program Files\Legato\nsr\tmp
52662 1239041580 1 nsrxchsv 20 Operation type: %s 1 0 11 Traditional
46896 1239041580 1 nsrxchsv 56 Performing an Exchange save operation; save level is %s. 1 0 32 Full (Legato Backup Level: full)
28930 1239041580 1 nsrxchsv 43 Parsing parameters from the command line... 0
28912 1239041580 1 nsrxchsv 41 Opening connection to NetWorker Server... 0
46801 1239041580 1 nsrxchsv 38 Started PerfMon update thread (Id: %u) 1 5 5 12876
31019 1239041580 1 0 0 12876 6636 0 bl-mailn3.vagverket.dr.vv.se nsrxchsv 41 Performance monitor update thread running 0
46199 1239041580 1 nsrxchsv 26 Found Exchange server %s 1 0 10 BL-MAILBX2
46191 1239041580 1 nsrxchsv 25 Preparing %s on server %s 2 0 27 Microsoft Information Store 0 10 bl-mailbx2
37881 1239041580 1 nsrxchsv 32 Started resource thread (Id: %u) 1 5 5 11576
37883 1239041580 1 0 0 11576 6636 0 bl-mailn3.vagverket.dr.vv.se nsrxchsv 59 Cluster resource monitor thread running; virtual server %s. 1 0 10 bl-mailbx2
46757 1239041581 1 nsrxchsv 29 Processing storage group "%s" 1 0 5 SG101
46764 1239041587 1 nsrxchsv 29 Backup storage group %s to %s 2 0 5 SG101 0 27 bl-back1.vagverket.dr.vv.se
52620 1239041587 1 nsrxchsv 17 Save set name: %s 1 0 9 MSEXCH:IS
46776 1239041587 1 nsrxchsv 26 Backup database: %s %s 2 0 5 SV101 0 38 {A19C02BF-3075-45FF-A96B-BB08308815F0}
46786 1239041587 1 nsrxchsv 33 Checking size of database "%s/%s" 2 0 5 SG101 0 5 SV101
46787 1239041587 1 nsrxchsv 26 Database "%s/%s" Size: %s 3 0 5 SG101 0 5 SV101 0 5 65 GB
52238 1239043007 1 nsrxchsv 25 %s %s %.0f bytes/sec 3 0 18 E:\SV101\SV101.edb 0 5 65 GB 3 15 47384724,000000
46780 1239043007 1 nsrxchsv 29 Backup of %s was successful 1 0 5 SV101
52238 1239043007 1 nsrxchsv 25 %s %s %.0f bytes/sec 3 0 28 H:\Log_SV101\E010001612D.log 0 7 1024 KB 3 15 13273114,000000
...
52238 1239043010 1 nsrxchsv 25 %s %s %.0f bytes/sec 3 0 28 H:\Log_SV101\E0100016150.log 0 7 1024 KB 3 15 16912516,000000
46781 1239043010 1 nsrxchsv 52 Backup of logs for storage group %s was successful 1 0 5 SG101
46916 1239043010 1 nsrxchsv 58 Processed %ld items %s in %d:%02d:%02d %.0f bytes/second 6 2 2 37 0 5 65 GB 1 1 0 1 2 23 1 2 43 3 15 47319680,000000
46772 1239043013 1 nsrxchsv 60 The backup of %s completed successfully; backup level is %s. 2 0 5 SG101 0 32 Full (Legato Backup Level: full)
46767 1239043013 1 nsrxchsv 45 Completed backup of %s Status: 0x%0.8x\n %s 3 0 5 SG101 1 1 0 24 37 The operation completed successfully.
46757 1239043013 1 nsrxchsv 29 Processing storage group "%s" 1 0 5 SG102
46764 1239043019 1 nsrxchsv 29 Backup storage group %s to %s 2 0 5 SG102 0 27 bl-back1.vagverket.dr.vv.se
52620 1239043019 1 nsrxchsv 17 Save set name: %s 1 0 15 MSEXCH:IS/SG102
46776 1239043019 1 nsrxchsv 26 Backup database: %s %s 2 0 5 SV102 0 38 {2BD9707E-BCF5-4906-9C82-719C432D88B7}
46786 1239043019 1 nsrxchsv 33 Checking size of database "%s/%s" 2 0 5 SG102 0 5 SV102
46787 1239043019 1 nsrxchsv 26 Database "%s/%s" Size: %s 3 0 5 SG102 0 5 SV102 0 5 55 GB
52238 1239044195 1 nsrxchsv 25 %s %s %.0f bytes/sec 3 0 18 E:\SV102\SV102.edb 0 5 55 GB 3 15 48025188,000000
46780 1239044195 1 nsrxchsv 29 Backup of %s was successful 1 0 5 SV102
52238 1239044195 1 nsrxchsv 25 %s %s %.0f bytes/sec 3 0 28 H:\Log_SV102\E0200012D40.log 0 7 1024 KB 3 15 11155064,000000
...
52238 1239044197 1 nsrxchsv 25 %s %s %.0f bytes/sec 3 0 28 H:\Log_SV102\E0200012D5D.log 0 7 1024 KB 3 15 16644063,000000
46781 1239044197 1 nsrxchsv 52 Backup of logs for storage group %s was successful 1 0 5 SG102
46916 1239044197 1 nsrxchsv 58 Processed %ld items %s in %d:%02d:%02d %.0f bytes/second 6 2 2 31 0 5 55 GB 1 1 0 1 2 19 1 2 38 3 15 47943000,000000
46772 1239044199 1 nsrxchsv 60 The backup of %s completed successfully; backup level is %s. 2 0 5 SG102 0 32 Full (Legato Backup Level: full)
46767 1239044199 1 nsrxchsv 45 Completed backup of %s Status: 0x%0.8x\n %s 3 0 5 SG102 1 1 0 24 37 The operation completed successfully.
46757 1239044199 1 nsrxchsv 29 Processing storage group "%s" 1 0 5 SG103
46764 1239044206 1 nsrxchsv 29 Backup storage group %s to %s 2 0 5 SG103 0 27 bl-back1.vagverket.dr.vv.se
52620 1239044206 1 nsrxchsv 17 Save set name: %s 1 0 15 MSEXCH:IS/SG103
46776 1239044206 1 nsrxchsv 26 Backup database: %s %s 2 0 5 SV103 0 38 {9AE14085-B768-4381-A9E4-349FB15B6544}
46786 1239044206 1 nsrxchsv 33 Checking size of database "%s/%s" 2 0 5 SG103 0 5 SV103
46787 1239044206 1 nsrxchsv 26 Database "%s/%s" Size: %s 3 0 5 SG103 0 5 SV103 0 5 54 GB
52238 1239045828 1 nsrxchsv 25 %s %s %.0f bytes/sec 3 0 18 E:\SV103\SV103.edb 0 5 54 GB 3 15 34570392,000000
46780 1239045828 1 nsrxchsv 29 Backup of %s was successful 1 0 5 SV103
52238 1239045828 1 nsrxchsv 25 %s %s %.0f bytes/sec 3 0 28 H:\LOG_SV103\E0400012485.log 0 7 1024 KB 3 15 16644063,000000
...
52238 1239045831 1 nsrxchsv 25 %s %s %.0f bytes/sec 3 0 28 H:\LOG_SV103\E04000124A6.log 0 7 1024 KB 3 15 13443282,000000
46781 1239045831 1 nsrxchsv 52 Backup of logs for storage group %s was successful 1 0 5 SG103
46916 1239045831 1 nsrxchsv 58 Processed %ld items %s in %d:%02d:%02d %.0f bytes/second 6 2 2 35 0 5 54 GB 1 1 0 1 2 27 1 1 5 3 15 34527848,000000
46772 1239045833 1 nsrxchsv 60 The backup of %s completed successfully; backup level is %s. 2 0 5 SG103 0 32 Full (Legato Backup Level: full)
46767 1239045833 1 nsrxchsv 45 Completed backup of %s Status: 0x%0.8x\n %s 3 0 5 SG103 1 1 0 24 37 The operation completed successfully.
46757 1239045833 1 nsrxchsv 29 Processing storage group "%s" 1 0 5 SG104
52663 1239045833 5 nsrxchsv 16 %s: %s (0x%0.8x) 3 0 16 HrESEBackupSetup 24 37 unknown error -939647163 (0xc7fe1f45) 1 10 -939647163
52241 1239045833 5 nsrxchsv 62 Exchange ESE HResult: %d (0x%0.8x); last error: %d (0x%0.8x) 4 1 10 -939647163 1 10 -939647163 1 1 0 1 1 0
30902 1239045833 5 nsrxchsv 25 No databases are mounted. 0
46916 1239045833 1 nsrxchsv 58 Processed %ld items %s in %d:%02d:%02d %.0f bytes/second 6 2 2 35 0 5 54 GB 1 1 0 1 2 27 1 1 7 3 15 34485404,000000
46767 1239045833 1 nsrxchsv 45 Completed backup of %s Status: 0x%0.8x\n %s 3 0 5 SG104 1 10 -939647163 24 37 unknown error -939647163 (0xc7fe1f45)
46757 1239045833 1 nsrxchsv 29 Processing storage group "%s" 1 0 5 SG201
46764 1239045833 1 nsrxchsv 29 Backup storage group %s to %s 2 0 5 SG201 0 27 bl-back1.vagverket.dr.vv.se
52620 1239045833 1 nsrxchsv 17 Save set name: %s 1 0 15 MSEXCH:IS/SG201
46776 1239045833 1 nsrxchsv 26 Backup database: %s %s 2 0 5 TS201 0 38 {83EC5725-25FA-46D1-A185-D72AE6C2754A}
46786 1239045833 1 nsrxchsv 33 Checking size of database "%s/%s" 2 0 5 SG201 0 5 TS201
46787 1239045833 1 nsrxchsv 26 Database "%s/%s" Size: %s 3 0 5 SG201 0 5 TS201 0 7 4120 KB
52238 1239045834 1 nsrxchsv 25 %s %s %.0f bytes/sec 3 0 18 F:\TS201\TS201.edb 0 7 4120 KB 3 15 38705320,000000
46780 1239045834 1 nsrxchsv 29 Backup of %s was successful 1 0 5 TS201
52238 1239045834 1 nsrxchsv 25 %s %s %.0f bytes/sec 3 0 28 I:\LOG_TS201\E0000000280.log 0 7 1024 KB 3 14 9532509,000000
...
52238 1239045834 1 nsrxchsv 25 %s %s %.0f bytes/sec 3 0 28 I:\LOG_TS201\E000000028A.log 0 7 1024 KB 3 15 13273114,000000
46781 1239045834 1 nsrxchsv 52 Backup of logs for storage group %s was successful 1 0 5 SG201
46916 1239045834 1 nsrxchsv 58 Processed %ld items %s in %d:%02d:%02d %.0f bytes/second 6 2 2 12 0 5 15 MB 1 1 0 1 1 0 1 1 1 3 15 15753216,000000
46772 1239045836 1 nsrxchsv 60 The backup of %s completed successfully; backup level is %s. 2 0 5 SG201 0 32 Full (Legato Backup Level: full)
46767 1239045836 1 nsrxchsv 45 Completed backup of %s Status: 0x%0.8x\n %s 3 0 5 SG201 1 1 0 24 37 The operation completed successfully.
46757 1239045836 1 nsrxchsv 29 Processing storage group "%s" 1 0 5 SG301
46764 1239045837 1 nsrxchsv 29 Backup storage group %s to %s 2 0 5 SG301 0 27 bl-back1.vagverket.dr.vv.se
52620 1239045837 1 nsrxchsv 17 Save set name: %s 1 0 15 MSEXCH:IS/SG301
46776 1239045837 1 nsrxchsv 26 Backup database: %s %s 2 0 5 VE301 0 38 {D449DBBC-234F-4DBC-9530-6A51789DFC2C}
46786 1239045837 1 nsrxchsv 33 Checking size of database "%s/%s" 2 0 5 SG301 0 5 VE301
46787 1239045837 1 nsrxchsv 26 Database "%s/%s" Size: %s 3 0 5 SG301 0 5 VE301 0 7 4120 KB
52238 1239045837 1 nsrxchsv 25 %s %s %.0f bytes/sec 3 0 18 G:\VE301\VE301.edb 0 7 4120 KB 3 15 33751040,000000
46780 1239045837 1 nsrxchsv 29 Backup of %s was successful 1 0 5 VE301
52238 1239045837 1 nsrxchsv 25 %s %s %.0f bytes/sec 3 0 28 J:\LOG_VE301\E0300000330.log 0 7 1024 KB 3 15 11155064,000000
...
52238 1239045837 1 nsrxchsv 25 %s %s %.0f bytes/sec 3 0 28 J:\LOG_VE301\E030000033E.log 0 7 1024 KB 3 15 13443282,000000
46781 1239045837 1 nsrxchsv 52 Backup of logs for storage group %s was successful 1 0 5 SG301
46916 1239045838 1 nsrxchsv 58 Processed %ld items %s in %d:%02d:%02d %.0f bytes/second 6 2 2 16 0 5 19 MB 1 1 0 1 1 0 1 1 1 3 15 19947520,000000
46772 1239045841 1 nsrxchsv 60 The backup of %s completed successfully; backup level is %s. 2 0 5 SG301 0 32 Full (Legato Backup Level: full)
46767 1239045841 1 nsrxchsv 45 Completed backup of %s Status: 0x%0.8x\n %s 3 0 5 SG301 1 1 0 24 37 The operation completed successfully.
37890 1239045846 1 0 0 11576 6636 0 bl-mailn3.vagverket.dr.vv.se nsrxchsv 48 Resource monitor thread termination status: 0x%x 1 1 1 0
46898 1239045846 5 nsrxchsv 38 %s(%d): %s failed with status = 0x%.8x 4 23 6 svmain 1 4 1306 0 17 process_is_backup 1 10 -939647163
46898 1239045846 5 nsrxchsv 38 %s(%d): %s failed with status = 0x%.8x 4 23 6 svmain 1 4 1342 0 13 end_is_backup 1 10 -939647163
31018 1239045846 1 nsrxchsv 43 Stopping performance monitor update thread. 0
31022 1239045846 1 0 0 12876 6636 0 bl-mailn3.vagverket.dr.vv.se nsrxchsv 45 Performance monitor update thread terminating 0
46885 1239045846 5 nsrxchsv 55 Backup operation finished with errors. No %s was sent. 1 0 8 savetime
43709 1239045846 1 nsrxchsv 13 Stop time: %s 1 35 25 Mon Apr 06 21:24:06 2009

2 Intern

 • 

14.3K Posts

April 7th, 2009 07:00

SG103 fails with unknown error -939647163 (0xc7fe1f45). If you render your log it would be more readable.

2 Intern

 • 

14.3K Posts

April 7th, 2009 07:00

9 Posts

April 7th, 2009 07:00

Ok, thanks. I checked the log disks but there are plenty of free space.
However since we are in the process of migrating to E2007 there might have been log issues earlier. I'm gonna check with the Exchange admins.

best regards,
Stefan

January 26th, 2010 21:00

Did anyone know how this was fixed(if so)?

Even I have the same setup which produces same errors..

Karthik

No Events found!

Top