SQL1119N .... a previous restore is incomplete or still in progress. SQLSTATE=57019

amol17 · #1 (**permalink**) 03-25-09, 03:17

hi all, this is my 1st post here, Am new to db2

I am getting above error in db2 log ...pasted below, when trying to restore a manual incremental backup ...

2009-03-24-19.43.42.284463+330 E1358857G373 LEVEL: Warning
PID : 11079 TID : 2567957408 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000
EDUID : 606 EDUNAME: db2logmgr (BKPTEST)
FUNCTION: DB2 UDB, data protection services, sqlpgArchiveLogFile, probe:3108
MESSAGE : ADM1844I Started archive for log file "S0000002.LOG"

2009-03-24-19.43.42.356682+330 E1359231G553 LEVEL: Error
PID : 11079 TID : 2567957408 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000
EDUID : 606 EDUNAME: db2logmgr (BKPTEST)
FUNCTION: DB2 UDB, data protection services, sqlpgInvokeUserexit, probe:1140
MESSAGE : ADM1832E DB2 was unable to find the user exit program when archiving
log file "S0000002.LOG" from
"/home/db2inst1/db2inst1/NODE0000/SQL00011/SQLOGDIR/" for database
"BKPTEST". The error code was "24".

2009-03-24-19.43.42.357388+330 E1359785G456 LEVEL: Warning
PID : 11079 TID : 2567957408 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000
EDUID : 606 EDUNAME: db2logmgr (BKPTEST)
FUNCTION: DB2 UDB, data protection services, sqlpgArchiveLogFile, probe:3150
MESSAGE : ADM1848W Failed to archive log file "S0000002.LOG" to "USEREXIT"
from "/home/db2inst1/db2inst1/NODE0000/SQL00011/SQLOGDIR/".

2009-03-24-19.43.42.357746+330 E1360242G564 LEVEL: Error
PID : 11079 TID : 2567957408 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000
EDUID : 606 EDUNAME: db2logmgr (BKPTEST)
FUNCTION: DB2 UDB, data protection services, sqlpgArchiveLogFile, probe:3160
MESSAGE : ZRC=0x00000018=24
DIA8023C The input string "" does not conform to DATE/TIME
requirements.
DATA #1 : <preformatted>
Failed to archive log file S0000002.LOG to USEREXIT from /home/db2inst1/db2inst1/NODE0000/SQL00011/SQLOGDIR/.

2009-03-24-19.43.42.357943+330 I1360807G427 LEVEL: Warning
PID : 11079 TID : 2567957408 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000
EDUID : 606 EDUNAME: db2logmgr (BKPTEST)
FUNCTION: DB2 UDB, data protection services, sqlpgRetryFailedArchive, probe:4780
MESSAGE : Still unable to archive log file 2 due to rc 24 for LOGARCHMETH1
using method 4 and target .

2009-03-24-19.43.51.115057+330 I1361235G390 LEVEL: Info
PID : 11079 TID : 2225073056 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000
EDUID : 607 EDUNAME: db2loggr (BKPTEST)
FUNCTION: DB2 UDB, data protection services, sqlpghck, probe:1390
DATA #1 : <preformatted>
ExtNum 3 state 10101 baselsn 0000000002388000 nextlsn 0000000002614831

2009-03-24-19.43.52.408869+330 I1361626G502 LEVEL: Event
PID : 11079 TID : 2575297440 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000 DB : BKPTEST
APPHDL : 0-552 APPID: *LOCAL.db2inst1.090324141352
AUTHID : ROOT
EDUID : 584 EDUNAME: db2agent (BKPTEST)
FUNCTION: DB2 UDB, config/install, sqlfLogUpdateCfgParam, probe:20
CHANGE : CFG DB BKPTEST : "Database_memory" From: "41040" <automatic> To: "27232" <automatic>

2009-03-24-19.43.56.227058+330 E1362129G495 LEVEL: Info
PID : 11079 TID : 2575297440 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000 DB : BKPTEST
APPHDL : 0-552 APPID: *LOCAL.db2inst1.090324141352
AUTHID : ROOT
EDUID : 584 EDUNAME: db2agent (BKPTEST)
FUNCTION: DB2 UDB, database utilities, sqludPrintStartingMsg, probe:1292
DATA #1 : <preformatted>
Starting an incremental database restore.
Agent EDU ID: 584

2009-03-24-19.43.56.230052+330 E1362625G542 LEVEL: Warning
PID : 11079 TID : 2575297440 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000 DB : BKPTEST
APPHDL : 0-552 APPID: *LOCAL.db2inst1.090324141352
AUTHID : ROOT
EDUID : 584 EDUNAME: db2agent (BKPTEST)
FUNCTION: DB2 UDB, database utilities, sqluCompareDB, probe:1437
DATA #1 : Sqlcode, PD_TYPE_SQLCODE, 4 bytes
2539
DATA #2 : Hexdump, 4 bytes
0xA197AB5C : EB09 0000 ....

2009-03-24-19.43.56.244031+330 E1363168G993 LEVEL: Warning
PID : 11079 TID : 2575297440 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000 DB : BKPTEST
APPHDL : 0-552 APPID: *LOCAL.db2inst1.090324141352
AUTHID : ROOT
EDUID : 584 EDUNAME: db2agent (BKPTEST)
FUNCTION: DB2 UDB, database utilities, sqluCompareDB, probe:1437
MESSAGE : SQL2539W Warning! Restoring to an existing database that is the
same as the backup image database. The database files will be
deleted.
DATA #1 : SQLCA, PD_DB2_TYPE_SQLCA, 136 bytes
sqlcaid : SQLCA sqlcabc: 136 sqlcode: 2539 sqlerrml: 0
sqlerrmc:
sqlerrp : sqluComp
sqlerrd : (1) 0x00000000 (2) 0x00000000 (3) 0x00000000
(4) 0x00000000 (5) 0x00000000 (6) 0x00000000
sqlwarn : (1) (2) (3) (4) (5) (6)
(7) (8) (9) (10) (11)
sqlstate:

2009-03-24-19.43.59.673740+330 E1364162G374 LEVEL: Warning
PID : 11079 TID : 3077565344 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000
EDUID : 19 EDUNAME: db2logmgr (PR_MIG_P)
FUNCTION: DB2 UDB, data protection services, sqlpgArchiveLogFile, probe:3108
MESSAGE : ADM1844I Started archive for log file "S0000000.LOG"

2009-03-24-19.44.00.136783+330 E1364537G555 LEVEL: Error
PID : 11079 TID : 3077565344 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000
EDUID : 19 EDUNAME: db2logmgr (PR_MIG_P)
FUNCTION: DB2 UDB, data protection services, sqlpgInvokeUserexit, probe:1140
MESSAGE : ADM1832E DB2 was unable to find the user exit program when archiving
log file "S0000000.LOG" from
"/home/db2inst1/db2inst1/NODE0000/SQL00008/SQLOGDIR/" for database
"PR_MIG_P". The error code was "24".

2009-03-24-19.44.00.139346+330 E1365093G457 LEVEL: Warning
PID : 11079 TID : 3077565344 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000
EDUID : 19 EDUNAME: db2logmgr (PR_MIG_P)
FUNCTION: DB2 UDB, data protection services, sqlpgArchiveLogFile, probe:3150
MESSAGE : ADM1848W Failed to archive log file "S0000000.LOG" to "USEREXIT"
from "/home/db2inst1/db2inst1/NODE0000/SQL00008/SQLOGDIR/".

2009-03-24-19.44.00.140324+330 E1365551G565 LEVEL: Error
PID : 11079 TID : 3077565344 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000
EDUID : 19 EDUNAME: db2logmgr (PR_MIG_P)
FUNCTION: DB2 UDB, data protection services, sqlpgArchiveLogFile, probe:3160
MESSAGE : ZRC=0x00000018=24
DIA8023C The input string "" does not conform to DATE/TIME
requirements.
DATA #1 : <preformatted>
Failed to archive log file S0000000.LOG to USEREXIT from /home/db2inst1/db2inst1/NODE0000/SQL00008/SQLOGDIR/.

2009-03-24-19.44.00.140764+330 I1366117G428 LEVEL: Warning
PID : 11079 TID : 3077565344 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000
EDUID : 19 EDUNAME: db2logmgr (PR_MIG_P)
FUNCTION: DB2 UDB, data protection services, sqlpgRetryFailedArchive, probe:4780
MESSAGE : Still unable to archive log file 0 due to rc 24 for LOGARCHMETH1
using method 4 and target .

2009-03-24-19.44.09.039276+330 E1366546G424 LEVEL: Info
PID : 11079 TID : 2575297440 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000 DB : BKPTEST
APPHDL : 0-552 APPID: *LOCAL.db2inst1.090324141352
AUTHID : ROOT
EDUID : 584 EDUNAME: db2agent (BKPTEST)
FUNCTION: DB2 UDB, database utilities, sqludrsa, probe:774
MESSAGE : Restore Complete.

I tried the following before this ....

create db bkptest
create table t
insert into t values

db2 UPDATE DATABASE CONFIGURATION USING LOGRETAIN Recovery DEFERRED ;
db2 UPDATE DATABASE CONFIGURATION USING USEREXIT On DEFERRED ;
db2 UPDATE DATABASE CONFIGURATION USING TRACKMOD Yes DEFERRED ;

taken full backup then

db2 'backup db bkptest to '/home/db2inst1' compress util_impact_priority 30 without prompting'

insert some values

taken full online backup

db2 'backup db bkptest online to '/home/db2inst1/online' compress util_impact_priority 30 include logs without prompting'

insert some values

db2 'BACKUP DB bkptest ONLINE INCREMENTAL TO "/home/db2inst1/incremental" COMPRESS UTIL_IMPACT_PRIORITY 30 INCLUDE LOGS WITHOUT PROMPTING'

insert some values

db2 'BACKUP DB bkptest ONLINE INCREMENTAL TO "/home/db2inst1/incremental" COMPRESS UTIL_IMPACT_PRIORITY 30 INCLUDE LOGS WITHOUT PROMPTING'

insert some values

db2 'BACKUP DB bkptest ONLINE INCREMENTAL TO "/home/db2inst1/incremental" COMPRESS UTIL_IMPACT_PRIORITY 30 INCLUDE LOGS WITHOUT PROMPTING'

then dropped the db
db2 'drop db bkptest'

restored the db using the full online backup image

1.> db2 'restore db bkptest from /home/db2inst1/online logtarget /home/db2inst1/online'

2.> db2 'rollforward db bkptest to end of logs and complete OVERFLOW LOG PATH ("/home/db2inst1/online")' -- i got some error msg ..ignored that

3.> db2 'rollforward db bkptest complete OVERFLOW LOG PATH ("/home/db2inst1/online")'

Only if I follow above 3 in sequence then I get the db restored back in consistent state, if I run 1,3 ... it didnt work, db remains in roll-forward mode

ok now after 1,2,3 i hv db in good state and i could see the inserted values before i had taken full online backup....

now I wanted to apply one of the incremental(no delta) backup ....

db2 'restore db bkptest INCREMENTAL from /home/db2inst1/incremental taken at 20090324162633 logtarget /home/db2inst1/incremental'

and it asks me -=>
{{{
Warning! Restoring to an existing database that is the
same as the backup image database. The database files will be
deleted. do you want to continue (yes/no)
}}}

I said yes... this above command has generated the above log....

After this I could not access the db with errors

1. db2 connect to bkptest

SQL1119N A connection to or activation of database "BKPTEST" cannot be made
because a previous restore is incomplete or still in progress. SQLSTATE=57019

amol17 · #2 (**permalink**) 03-25-09, 03:19

spent some more time doing the following

db2 UPDATE DATABASE CONFIGURATION USING USEREXIT Off DEFERRED ;

db2 UPDATE DATABASE CONFIGURATION USING LOGARCHMETH1 DISK:/home/db2inst1/backup/archived_logs IMMEDIATE ;

restarted the db manager

again tried the

db2 'restore db bkptest INCREMENTAL from /home/db2inst1/incremental taken at 20090324162633 logtarget /home/db2inst1/incremental'

and got the same behavior now with different logs ...removed out errors of archiving

2009-03-25-11.39.02.643552+330 E606328G494 LEVEL: Info
PID : 31975 TID : 3079662496 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000 DB : BKPTEST
APPHDL : 0-13 APPID: *LOCAL.db2inst1.090325060902
AUTHID : ROOT
EDUID : 17 EDUNAME: db2agent (BKPTEST)
FUNCTION: DB2 UDB, database utilities, sqludPrintStartingMsg, probe:1292
DATA #1 : <preformatted>
Starting an incremental database restore.
Agent EDU ID: 17

2009-03-25-11.39.02.643808+330 E606823G542 LEVEL: Warning
PID : 31975 TID : 3079662496 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000 DB : BKPTEST
APPHDL : 0-13 APPID: *LOCAL.db2inst1.090325060902
AUTHID : ROOT
EDUID : 17 EDUNAME: db2agent (BKPTEST)
FUNCTION: DB2 UDB, database utilities, sqluCompareDB, probe:1437
DATA #1 : Sqlcode, PD_TYPE_SQLCODE, 4 bytes
2539
DATA #2 : Hexdump, 4 bytes
0xADA32B5C : EB09 0000 ....

2009-03-25-11.39.02.644089+330 E607366G993 LEVEL: Warning
PID : 31975 TID : 3079662496 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000 DB : BKPTEST
APPHDL : 0-13 APPID: *LOCAL.db2inst1.090325060902
AUTHID : ROOT
EDUID : 17 EDUNAME: db2agent (BKPTEST)
FUNCTION: DB2 UDB, database utilities, sqluCompareDB, probe:1437
MESSAGE : SQL2539W Warning! Restoring to an existing database that is the
same as the backup image database. The database files will be
deleted.
DATA #1 : SQLCA, PD_DB2_TYPE_SQLCA, 136 bytes
sqlcaid : SQLCA sqlcabc: 136 sqlcode: 2539 sqlerrml: 0
sqlerrmc:
sqlerrp : sqluComp
sqlerrd : (1) 0x00000000 (2) 0x00000000 (3) 0x00000000
(4) 0x00000000 (5) 0x00000000 (6) 0x00000000
sqlwarn : (1) (2) (3) (4) (5) (6)
(7) (8) (9) (10) (11)
sqlstate:

2009-03-25-11.39.08.413416+330 E608360G424 LEVEL: Info
PID : 31975 TID : 3079662496 PROC : db2sysc
INSTANCE: db2inst1 NODE : 000 DB : BKPTEST
APPHDL : 0-13 APPID: *LOCAL.db2inst1.090325060902
AUTHID : ROOT
EDUID : 17 EDUNAME: db2agent (BKPTEST)
FUNCTION: DB2 UDB, database utilities, sqludrsa, probe:774
MESSAGE : Restore Complete.

no error found between it started and finished... now what ...

Have I missed any thing...

please help...

my db info is as

DB21085I Instance "db2inst1" uses "32" bits and DB2 code release "SQL09050"
with level identifier "03010107".
Informational tokens are "DB2 v9.5.0.0", "s071001", "LINUXIA3295", and Fix Pack
"0".
Product is installed at "/opt/ibm/db2/V9.5".

thanks