Windows IDS 11.50FC6, logical restore aborted

Hi All, I am trying to run a restore using onbar -r and the physical restore is successful but the log restore is aborted. The onbar dbug logs snippet: 2011-09-15 16:41:23 41360 41360 read_bar_debug_value: enter 2011-09-15 16:41:23 41360 41360 read_bar_debug_value: return 0 (0x00) 2011-09-15 16:41:23 41360 41360 do_log_restore: looking for log 31 in ccb->objlist 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = rootdbs, obj_type = R 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = rootdbs, obj_type = R 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = sbspace, obj_type = ND 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = sbspace, obj_type = ND 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = ol_ids_1150_1, obj_type = ND 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = ol_ids_1150_1, obj_type = ND 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 3, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 4, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 5, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 6, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 7, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 8, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 9, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 10, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 11, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 12, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 13, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 14, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 15, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 16, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 17, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 18, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 19, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 20, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 21, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 22, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 23, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 24, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 25, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 26, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 27, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 28, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 29, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = 30, obj_type = L 2011-09-15 16:41:23 41360 41360 do_log_restore: cur_log_found = 0 2011-09-15 16:41:23 41360 41360 init_objdesc: enter 2011-09-15 16:41:23 41360 41360 init_objnode: return 2011-09-15 16:41:23 41360 41360 barBeginTxn: enter 2011-09-15 16:41:23 41360 41360 bar_alarm: enter 2011-09-15 16:41:23 41360 41360 bar_alarm: return 2011-09-15 16:41:23 41360 41360 bar_loadXBSA: return 0 2011-09-15 16:41:23 41360 41360 barBeginTxn: return 0 (0x00) 2011-09-15 16:41:23 41360 41360 barQueryObject: enter 2011-09-15 16:41:23 41360 41360 barQueryObject input: bar_objdesc obj_id 0 obj_name '31' obj_type 'L' act_id 0 act_type 0 act_status 0 act_start '' act_end '' ins_time 0 rsam_time 0 seal_time 0 prev_seal_time 0 level 0 copyid hi:lo 0:0 req_act_id 0 logstream 0 est_pages hi:lo 0:0 first_log 0 chpt_log 0 last_log 0 partial_flag 0 do_query 1 ins_sm_id 0 ins_sm_name '' ins_verify 0 ins_verify_date '' restore order 0:0 objInfo '' retry 0 in_catalog 0 in_bootfile 0 child_pid 0 child_state 0 bkup_host '' backup_order 0 2011-09-15 16:41:23 41360 41360 initQryDesc: enter 2011-09-15 16:41:23 41360 41360 initQryDesc: output: object owner name 2011-09-15 16:41:23 41360 41360 initQryDesc: output: INFORMIX informix \\\\ 2011-09-15 16:41:23 41360 41360 initQryDesc: output: createTimeLB createTimeUB expireTimeLB expireTimeUB copytype LGName cGName resource type status desc 2011-09-15 16:41:23 41360 41360 initQryDesc: output: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 3 4 0 2011-09-15 16:41:23 41360 41360 initQryDesc: return 2011-09-15 16:41:23 41360 41360 buildObjName: enter 2011-09-15 16:41:23 41360 41360 buildObjName: bar_objdesc obj_id 0 obj_name '31' obj_type 'L' act_id 0 act_type 0 act_status 0 act_start '' act_end '' ins_time 0 rsam_time 0 seal_time 0 prev_seal_time 0 level 0 copyid hi:lo 0:0 req_act_id 0 logstream 0 est_pages hi:lo 0:0 first_log 0 chpt_log 0 last_log 0 partial_flag 0 do_query 2 ins_sm_id 0 ins_sm_name '' ins_verify 0 ins_verify_date '' restore order 0:0 objInfo '' retry 0 in_catalog 0 in_bootfile 0 child_pid 0 child_state 0 bkup_host '' backup_order 0 2011-09-15 16:41:23 41360 41360 buildObjName: set server_name to ol_ids_1150_1 2011-09-15 16:41:23 41360 41360 buildObjName: output objectSpaceName \\\\ol_ids_1150_1, pathName \\\\ol_ids_1150_1\\\\0\\\\31 2011-09-15 16:41:23 41360 41360 buildObjName: return 0 (0x00) 2011-09-15 16:41:23 41360 41360 bar_loadXBSA: return 0 2011-09-15 16:41:23 41360 41360 bar_except_hdlr: enter 2011-09-15 16:41:23 41360 41360 barEndTxn: enter 2011-09-15 16:41:23 41360 41360 bar_loadXBSA: return 0 2011-09-15 16:41:23 41360 41360 barEndTxn: return 0 (0x00) 2011-09-15 16:41:23 41360 41360 barTerminate: enter 2011-09-15 16:41:23 41360 41360 bar_loadXBSA: return 0 2011-09-15 16:41:30 41360 41360 barTerminate: return 0 (0x00) 2011-09-15 16:41:30 41360 41360 NT_KillAllChildren: enter The server server logs: 17:27:34 Logical Recovery ABORTED. Aborted by client. 17:27:34 Assert Failed: Logical Recovery ABORTED. Dynamic Server must abort 17:27:34 IBM Informix Dynamic Server Version 11.50.FC3 17:27:34 Who: Session(12, Administ@parvez-info2k8n, 42344, 0000000000000000) Thread(31, ontape, 0, 1) File: rslgr.c Line: 1305 17:27:34 stack trace for pid 42820 written to C:\\\\PROGRA~1\\\\IBM\\\\IBMINF~1\\\\11.50\\\\tmp\\\\af.407e82e 17:27:36 See Also: C:\\\\PROGRA~1\\\\IBM\\\\IBMINF~1\\\\11.50\\\\tmp\\\\af.407e82e, shmem.407e82e.0 17:27

Hello Ravi, I don´t think it´s a restore problem, since yours onbar dbug file doesn´t have other return codes than 0 (success). (Onbar errors should have some error codes other than 0) But as your online log says, there´s a system failure (AF files generated). So I´d suggest you open a PMR with IBM support, and send them your af files, and your shared memory dump. Em 15/09/2011 08:14, RAVI THATI escreveu: > Hi All, > > I am trying to run a restore using onbar -r and the physical restore is > successful but the log restore is aborted. > > The onbar dbug logs snippet: > 2011-09-15 16:41:23 41360 41360 read_bar_debug_value: enter > 2011-09-15 16:41:23 41360 41360 read_bar_debug_value: return 0 (0x00) > 2011-09-15 16:41:23 41360 41360 do_log_restore: looking for log 31 in > ccb->objlist > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > rootdbs, obj_type = R > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > rootdbs, obj_type = R > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > sbspace, obj_type = ND > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > sbspace, obj_type = ND > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > ol_ids_1150_1, obj_type = ND > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > ol_ids_1150_1, obj_type = ND > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 3, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 4, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 5, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 6, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 7, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 8, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 9, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 10, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 11, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 12, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 13, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 14, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 15, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 16, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 17, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 18, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 19, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 20, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 21, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 22, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 23, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 24, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 25, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 26, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 27, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 28, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 29, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: examining obj_desc->obj_name = > 30, obj_type = L > 2011-09-15 16:41:23 41360 41360 do_log_restore: cur_log_found = 0 > 2011-09-15 16:41:23 41360 41360 init_objdesc: enter > 2011-09-15 16:41:23 41360 41360 init_objnode: return > 2011-09-15 16:41:23 41360 41360 barBeginTxn: enter > 2011-09-15 16:41:23 41360 41360 bar_alarm: enter > 2011-09-15 16:41:23 41360 41360 bar_alarm: return > 2011-09-15 16:41:23 41360 41360 bar_loadXBSA: return 0 > 2011-09-15 16:41:23 41360 41360 barBeginTxn: return 0 (0x00) > 2011-09-15 16:41:23 41360 41360 barQueryObject: enter > 2011-09-15 16:41:23 41360 41360 barQueryObject input: bar_objdesc > > obj_id 0 obj_name '31' obj_type 'L' act_id 0 act_type 0 act_status 0 > > act_start '' act_end '' > > ins_time 0 rsam_time 0 seal_time 0 prev_seal_time 0 level 0 copyid hi:lo 0:0 > req_act_id 0 > > logstream 0 est_pages hi:lo 0:0 first_log 0 chpt_log 0 last_log 0 > > partial_flag 0 do_query 1 ins_sm_id 0 ins_sm_name '' > > ins_verify 0 ins_verify_date '' restore order 0:0 > > objInfo '' > > retry 0 in_catalog 0 in_bootfile 0 child_pid 0 child_state 0 > > bkup_host '' backup_order 0 > 2011-09-15 16:41:23 41360 41360 initQryDesc: enter > 2011-09-15 16:41:23 41360 41360 initQryDesc: output: object owner name > 2011-09-15 16:41:23 41360 41360 initQryDesc: output: INFORMIX informix \\\\ > 2011-09-15 16:41:23 41360 41360 initQryDesc: output: createTimeLB createTimeUB > expireTimeLB expireTimeUB copytype LGName cGName resource type status desc > 2011-09-15 16:41:23 41360 41360 initQryDesc: output: 0 0 0 0 0 0 0 0 0 0 0 0 0 > 0 0 0 0 0 0 0 0 0 0 0 3 4 0 > 2011-09-15 16:41:23 41360 41360 initQryDesc: return > 2011-09-15 16:41:23 41360 41360 buildObjName: enter > 2011-09-15 16:41:23 41360 41360 buildObjName: bar_objdesc > > obj_id 0 obj_name '31' obj_type 'L' act_id 0 act_type 0 act_status 0 > > act_start '' act_end '' > > ins_time 0 rsam_time 0 seal_time 0 prev_seal_time 0 level 0 copyid hi:lo 0:0 > req_act_id 0 > > logstream 0 est_pages hi:lo 0:0 first_log 0 chpt_log 0 last_log 0 > > partial_flag 0 do_query 2 ins_sm_id 0 ins_sm_name '' > > ins_verify 0 ins_verify_date '' restore order 0:0 > > objInfo '' > > retry 0 in_catalog 0 in_bootfile 0 child_pid 0 child_state 0 > > bkup_host '' backup_order 0 > 2011-09-15 16:41:23 41360 41360 buildObjName: set server_name to ol_ids_1150_1 > 2011-09-15 16:41:23 41360 41360 buildObjName: output objectSpaceName > \\\\ol_ids_1150_1, pathName \\\\ol_ids_1150_1\\\\0\\\\31 > 2011-09-15 16:41:23 41360 41360 buildObjName: return 0 (0x00) > 2011-09-15 16:41:23 41360 41360 bar_loadXBSA: return 0 > 2011-09-15 16:41:23 41360 41360 bar_except_hdlr: enter > 2011-09-15 16:41:23 41360 41360 barEndTxn: enter > 2011-09-15 16:41:23 41360 41360 bar_loadXBSA: return 0 > 2011-09-15

Hi Marini, Thank you for the response. I have some more information on this. The restore is trying log 31 which does not exists in the ixbar file at all ( reason is that latest log file is only 30 that is backedup). why is it trying to restore a log that never exists?

I have attempted another restore and observed this: it is looking for log file 48 which is not actually there in finalized list and observes as do_log_restore: cur_log_found = 0 //failed to find. and still continues to restore it resulting in unwanted shutdown of onbar and IDS server. 2011-09-16 14:42:27 29916 29916 read_bar_debug_value: enter 2011-09-16 14:42:27 29916 29916 read_bar_debug_value: return 0 (0x00) 2011-09-16 14:42:27 29916 29916 do_log_restore: looking for log 48 in ccb->objlist 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = rootdbs, obj_type = R 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = dbspace1, obj_type = ND 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 1, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 2, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 3, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 4, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 5, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 6, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 7, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 8, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 9, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 10, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 11, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 12, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 13, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 14, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 15, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 16, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 17, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 18, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 19, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 20, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 21, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 22, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 23, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 24, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 25, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 26, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 27, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 28, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 29, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 30, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 31, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 32, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 33, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 34, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 35, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 36, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 37, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 38, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 39, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 40, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 41, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 42, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 43, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 44, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 45, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 46, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = 47, obj_type = L 2011-09-16 14:42:27 29916 29916 do_log_restore: cur_log_found = 0 2011-09-16 14:42:27 29916 29916 init_objdesc: enter 2011-09-16 14:42:27 29916 29916 init_objnode: return 2011-09-16 14:42:27 29916 29916 barBeginTxn: enter 2011-09-16 14:42:27 29916 29916 bar_alarm: enter 2011-09-16 14:42:27 29916 29916 bar_alarm: return 2011-09-16 14:42:27 29916 29916 bar_loadXBSA: return 0 2011-09-16 14:42:27 29916 29916 barBeginTxn: return 0 (0x00) 2011-09-16 14:42:27 29916 29916 barQueryObject: enter 2011-09-16 14:42:27 29916 29916 barQueryObject input: bar_objdesc obj_id 0 obj_name '48' obj_type 'L' act_id 0 act_type 0 act_status 0 act_start '' act_end '' ins_time 0 rsam_time 0 seal_time 0 prev_seal_time 0 level 0 copyid hi:lo 0:0 req_act_id 0 logstream 0 est_pages hi:lo 0:0 first_log 0 chpt_log 0 last_log 0 partial_flag 0 do_query 1 ins_sm_id 0 ins_sm_name '' ins_verify 0 ins_verify_date '' restore order 0:0 objInfo '' retry 0 in_catalog 0 in_bootfile 0 child_pid 0 child_state 0 bkup_host '' backup_order 0 2011-09-16 14:42:27 29916 29916 initQryDesc: enter 2011-09-16 14:42:27 29916 29916 initQryDesc: output: object owner name 2011-09-16 14:42:27 29916 29916 initQryDesc: output: INFORMIX informix \\\\ 2011-09-16 14:42:27 29916 29916 initQryDesc: output: createTimeLB createTimeUB expireTimeLB expireTimeUB copytype LGName cGName resource type status desc 2011-09-16 14:42:27 29916 29916 initQryDesc: output: 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 3 4 0 2011-09-16 14:42:27 29916 29916 initQryDesc: return 2011-09-16 14:42:27 29916 29916 buildObjName: enter 2011-09-16 14:42:27 29916 29916 buildObjName: bar_objdesc obj_id 0 obj_name '48' obj_type 'L' act_id 0 act_type 0 act_status 0 act_start '' act_end '' ins_time 0 rsam_time 0 seal_time 0 prev_seal_time 0 level 0 copyid hi:lo 0:0 req_act_id 0 logstream 0 est_pages hi:lo 0:0 first_log 0 chpt_log 0 last_log 0 partial_fla

Ravi, I think you should have all logical log files required by the restore. If that log doesn´t appear on your ixbar.[servernum] file, it´s a very strange situation. But one doubt raised on me. Had you dropped some logical-log files on your previous system, after that archive you´re trying to restore? (I know that your engine should not allow the logical log drop, until a new level zero is completed, but......) Also you could check the previous system sysutils database, searching for that logical log record, I think. Another thing, wouldn´t you do a physical restore first, and then start the logical log until your log 47? If that´s the last logical log really, and it´s not worth, that would bring your engine to a "fine" state....? Regards. Em 16/09/2011 05:54, RAVI THATI escreveu: > I have attempted another restore and observed this: > > it is looking for log file 48 which is not actually there in finalized list > and observes as do_log_restore: cur_log_found = 0 //failed to find. > and still continues to restore it resulting in unwanted shutdown of onbar and > IDS server. > > 2011-09-16 14:42:27 29916 29916 read_bar_debug_value: enter > 2011-09-16 14:42:27 29916 29916 read_bar_debug_value: return 0 (0x00) > 2011-09-16 14:42:27 29916 29916 do_log_restore: looking for log 48 in > ccb->objlist > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > rootdbs, obj_type = R > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > dbspace1, obj_type = ND > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 1, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 2, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 3, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 4, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 5, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 6, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 7, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 8, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 9, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 10, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 11, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 12, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 13, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 14, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 15, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 16, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 17, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 18, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 19, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 20, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 21, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 22, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 23, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 24, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 25, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 26, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 27, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 28, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 29, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 30, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 31, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 32, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 33, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 34, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 35, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 36, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 37, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 38, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 39, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 40, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 41, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 42, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 43, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 44, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 45, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 46, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: examining obj_desc->obj_name = > 47, obj_type = L > 2011-09-16 14:42:27 29916 29916 do_log_restore: cur_log_found = 0 > 2011-09-16 14:42:27 29916 29916 init_objdesc: enter > 2011-09-16 14:42:27 29916 29916 init_objnode: return > 2011-09-16 14:42:27 29916 29916 barBeginTxn: enter > 2011-09-16 14:42:27 29916 29916 bar_alarm: enter > 2011-09-16 14:42:27 29916 29916 bar_alarm: return > 2011-09-16 14:42:27 29916 29916 bar_loadXBSA: return 0 > 2011-09-16 14:42:27 29916 29916 barBeginTxn: return 0 (0x00) > 2011-09-16 14:42:27 29916 29916 barQueryObject: enter > 2011-09-16 14:42:27 29916 29916 barQueryObject input: bar_objdesc > > obj_id 0 obj_name '48' obj_type 'L' act_id 0 act_type 0 act_status 0 > > act_start '' act_end '' > > ins_time 0

Hi >>I think you should have all logical log files required by the restore. >>If that log doesn´t appear on your ixbar.[servernum] file, it´s a very >>strange situation. yes, it is not present in ixbar file also and still onbar is trying to restore. Remember this log N+1 is not there in fianlized list from the bar_dbg logs. >>But one doubt raised on me. >>Had you dropped some logical-log files on your previous system, after >>that archive you´re trying to restore? >>(I know that your engine should not allow the logical log drop, until a >>new level zero is completed, but......) no,i have not dropped anything. >>Also you could check the previous system sysutils database, searching >>for that logical log record, I think. >>Another thing, wouldn´t you do a physical restore first, and then start >>the logical log until your log 47? >>If that´s the last logical log really, and it´s not worth, that would >>bring your engine to a "fine" state....? Yes, i have already tried onbar -b -n 47 and it works well. Brings all data and server On-Line. It is happening across versions 11.10,11.50,11.7 on windows.