Veritas-bu

[Veritas-bu] Point In Time (PIT) restore from NetBackup for Lotus Notes failed

2005-03-01 14:56:44
Subject: [Veritas-bu] Point In Time (PIT) restore from NetBackup for Lotus Notes failed
From: rfang AT coke.umuc DOT edu (Rongsheng Fang)
Date: Tue, 1 Mar 2005 14:56:44 -0500
Hi list,

We have NetBackup Enterprise Server 5.0 MP4 and NetBackup for Lotus
Notes running on Solaris 9 and 8 systems. We do a full backup of out
database weekly and do the transaction log backup once every hour. The
Archive Logging mode is enabled for the Lotus Domino server.  Everytime
when I do a Point-In-Time restore from the NetBackup for Lotus Notes
agent, the restore always fails at the PIT database recovery step and
spits out the following message:

14:46:56 (61990.001) ERR - 
/restore/lotus/notesadm/notesr5/ACCT221/0502/8800/class.nsf Recovery Failed! 
(0x13F5:Recovery Manager: Database is not latest copy.)

The original database file is located at
/lotus/notesadm/notesr5/ACCT221/0502/8800/class.nsf and everytime I
restore it to /jodie directory.

The full tar log is attached:

14:45:57 (61990.001) INF - NBLN_Initialize() <Enter>
14:45:57 (61990.001) INF - LN_CreateSuffixList() <Enter>
14:45:57 (61990.001) INF - LN_AddToSuffixList() <Enter>
14:45:57 (61990.001) INF - LN_AddToSuffixList() <Enter>
14:45:57 (61990.001) INF - LN_AddToSuffixList() <Enter>
14:45:57 (61990.001) INF - LN_CreateSuffixList() Suffix: NSF
14:45:57 (61990.001) INF - LN_CreateSuffixList() Suffix: NTF
14:45:57 (61990.001) INF - LN_CreateSuffixList() Suffix: BOX
14:45:57 (61990.001) INF - LN_CreateSuffixList() <Exit>
14:45:57 (61990.001) INF - NBLN_Initialize() <Exit> Lotus Context Initialized
14:45:57 (61990.001) INF - NBLN_SetNbInfo() <Enter> TempDir: 
ProgressLog:/usr/openv/netbackup/logs/user_ops/notesadm/logs/jbpHCBNSaa9l.log
14:45:57 (61990.001) Setting notes.ini from bp.conf

14:45:57 (61990.001) INF - NBLN_SetLotusInfo() <Enter> 
NotesIniPath:/lotus/notesadm/notesr5/notes.ini
14:45:57 (61990.001) Lotus program directory read from PATH: 
Notes_ExecDirectory=/lotus/notes/latest/sunspa

14:45:57 (61990.001) INF - NBLN_ParseRecoveryString() <Enter> Lotus recover 
options string:'LOTUS_NOTES ZapId YES'
14:45:57 (61990.001) INF - Recovery Option ZapId = 1
14:45:57 (61990.001) INF - NBLN_ParseRecoveryString() <Enter> Lotus recover 
options string:'LOTUS_NOTES ZapReplicaID YES'
14:45:57 (61990.001) INF - Recovery Option ZapReplicaId = 1
14:45:57 (61990.001) INF - NBLN_ParseRecoveryString() <Enter> Lotus recover 
options string:'LOTUS_NOTES PointInTime 1108926000'
14:45:57 (61990.001) INF - Recovery Option PointInTime = 1108926000
14:45:57 (61990.001) INF - NBLN_ParseRecoveryString() <Enter> Lotus recover 
options string:'LOTUS_NOTES WaitTime 0'
14:45:57 (61990.001) INF - Recovery Option WaitTime = 0
14:45:57 (61990.001) INF - NBLN_ParseRecoveryString() <Enter> Lotus recover 
options string:'LOTUS_NOTES RecoveryWait YES'
14:45:57 (61990.001) INF - Recovery Option RecoveryWait = 1
14:45:57 (61990.001) INF - NBLN_Connect() <Enter> 
NotesIniPath:'/lotus/notesadm/notesr5/notes.ini'
14:45:58 (61990.001) INF - Lotus Notes Version R5.0.12.0
14:45:58 (61990.001) INF - LN_StoreTransLogStyle() <Enter>
14:45:58 (61990.001) INF - LN_StoreTransLogStyle() <Exit> TransLogStyle: 0x4
14:45:58 (61990.001) INF - NBLN_Connect() <Exit> Connected to Lotus Notes 
Session
14:45:58 (61990.001) INF - NBLN_GetLogDirectory() <Enter>
14:45:58 (61990.001) INF - NBLN_GetLogDirectory() <Exit> TRANSLOG_Path=/domlog
14:45:58 (61990.001) INF - TAR STARTED
14:45:59 (61990.001) parent_bptm = 9526, num_bufs = 8, buff_size = 262144, 
dataport = 0, IS_MPX = 1, Ismpx = 0, CINDEX = 0, curr_buf = 0, b
uf_seq = 1, is_tape = 1
14:45:59 (61990.001) dataipc = /tmp/vnetFAAM8aie3
14:45:59 (61990.001) SHMID = 65127, BUF_PTR = 0xfd180000, BUF_CONTROL = 
0xfd380000, ReadyPtr = 0xfd3800c0, MPX_RES_CNTL = 0xfd3800c4
14:46:00 (61990.001) FRAGNUM = 1, COPYNUM = 1, KBYTES = 164294656, REMAINDER = 
0, FIRSTBLK = 0, LASTBLK = 328589311
14:46:00 (61990.001) FRAGNUM = 2, COPYNUM = 1, KBYTES = 53262176, REMAINDER = 
0, FIRSTBLK = 328589312, LASTBLK = 435113663
14:46:00 (61990.001) [9526] data socket to bprd is connected (5)
14:46:54 (61990.001) [9526] skipping 261632 bytes, input length was 261632 bytes
14:46:54 (61990.001) [9526] received string: (432 30394367 22544384 0 0 0 
22544384 /lotus/notesadm/notesr5/ACCT221/0502/8800/class.nsf)
14:46:54 (61990.001) INF - NBLN_DbRestoreOpen() <Enter> Database 
Pathname:/jodie/lotus/notesadm/notesr5/ACCT221/0502/8800/class.nsf OpenFla
gs:0x1 WaitTime:0
14:46:55 (61990.001) INF - 
/jodie/lotus/notesadm/notesr5/ACCT221/0502/8800/class.nsf Taken offline. (1 
seconds)
14:46:55 (61990.001) INF - NBLN_DbRestoreOpen() <Exit>
14:46:56 (61990.001) INF - NBLN_ChgInfoRestoreOpen() <Enter> Database 
Pathname:/jodie/lotus/notesadm/notesr5/ACCT221/0502/8800/class.nsf Si
ze(0:0)
14:46:56 (61990.001) INF - NBLN_ChgInfoRestoreOpen() <Exit>
14:46:56 (61990.001) INF - NBLN_ChgInfoRestoreClose() <Enter> ChgInfoFlags:0
14:46:56 (61990.001) INF - NBLN_ChgInfoRestoreClose() Db 
Pathname:/jodie/lotus/notesadm/notesr5/ACCT221/0502/8800/class.nsf 
ApplyInfoContex
t:1
14:46:56 (61990.001) INF - NBLN_ChgInfoRestoreClose() <Exit> Application of 
change info ended for:/jodie/lotus/notesadm/notesr5/ACCT221/050
2/8800/class.nsf
14:46:56 (61990.001) INF - NBLN_DbRestoreClose() <Enter> 
Database:/jodie/lotus/notesadm/notesr5/ACCT221/0502/8800/class.nsf 
CloseFlags:0x0
14:46:56 (61990.001) INF - NBLN_DbRestoreClose() <Exit>
14:46:56 (61990.001) [9526] received string: (EXIT STATUS 0)
14:46:56 (61990.001) [9526] waited for full buffer 0 times, delayed 0 times
14:46:56 (61990.001) [9526] sent 1 directories/files to client tychousa7
14:46:56 (61990.001) [9526] Exit_shm status = 0
14:46:56 (61990.001) INF - TAR EXITING WITH STATUS = 0
14:46:56 (61990.001) INF - TAR RESTORED 1 OF 1 FILES SUCCESSFULLY
14:46:56 (61990.001) INF - TAR KEPT 0 EXISTING FILES
14:46:56 (61990.001) INF - TAR PARTIALLY RESTORED 0 FILES

14:46:56 (61990.001) INF - NBLN_RecoverDb() <Enter> RecoveryFlags:27 
PointInTime:1108926000
14:46:56 (61990.001) INF - NBLN_RecoverDb() Databases: 
/jodie/lotus/notesadm/notesr5/ACCT221/0502/8800/class.nsf
14:46:56 (61990.001) INF - NBLN_GetTransLogStyle() <Enter>
14:46:56 (61990.001) INF - NBLN_GetTransLogStyle() <Exit> TransLogStyle: 0x4
14:46:56 (61990.001) INF - NBLN_RecoverDb() Opened RestoreRenameFile: 
/usr/openv/netbackup/logs/user_ops/LN_Restore_Rename_File_9531.chg
14:46:56 (61990.001) INF - Begin Lotus point in time database recovery to: 
02/20/2005 02:00:00 PM EST
14:46:56 (61990.001) INF - NBLN_RecoverDb() RecoverErrorCode:0x13F5 DbIndex:1
14:46:56 (61990.001) ERR - 
/jodie/lotus/notesadm/notesr5/ACCT221/0502/8800/class.nsf Recovery Failed! 
(0x13F5:Recovery Manager: Database is
 not latest copy.)
14:46:56 (61990.001) INF - TAR RECOVERED 0 of 1 DATABASES SUCCESSFULLY
14:46:56 (61990.001) INF - NBLN_RecoverDb() <Exit> returning failure
14:46:56 (61990.001) INF - NBLN_ReturnLastErrorString() <Enter>
14:46:56 (61990.001) INF - NBLN_ReturnLastErrorString() <Exit> (Error code:5109)
14:46:56 (61990.001) INF - NBLN_Disconnect() <Enter>
14:46:56 (61990.001) INF - NBLN_DbBackupClose() <Enter> FullDbPath:
14:46:56 (61990.001) INF - NBLN_DbBackupClose() <Exit> Lotus database: closed.
14:46:56 (61990.001) INF - NBLN_Disconnect() <Exit> Lotus Notes Session 
Disconnected
14:46:56 (61990.001) INF - NBLN_Terminate() <Enter>
14:46:56 (61990.001) INF - NBLN_Terminate() <Exit> Freeing Lotus Context

Has anybody experienced such problem before?

Thanks,

Rongsheng

<Prev in Thread] Current Thread [Next in Thread>
  • [Veritas-bu] Point In Time (PIT) restore from NetBackup for Lotus Notes failed, Rongsheng Fang <=