ONBAR DEBUG LOG

Below is the OnBar debug log from a small dbspace level 0 backup with BAR_DEBUG = 7. The only thing missing here if we had BAR_DEBUG=9 is the page headers of all the pages that were archived and various internal structure contents when the sturcture are being initialized.

Some important things to note below is that we dump out most SQL statements that we issue to the online engine. A lot of times Onbar will fail due to these SQL statements returning an error. Setting BAR_DEBUG is the only way to find exectly what SQL statment is failing.

This output also displays the return codes of every function we return from. This is helpful when trying to isolate exactly when OnBar is running in to a problem.

Remeber: This file will grow very large very fast depending on what setting you use. BAR_DEBUG & BAR_DEBUG_LOG should not be a permanent onconfig setting.

For more information of using this output, go to the debugging Onbar section.

Home   Index

 1997-04-30 09:35:52 11200  8034 read_config:server  key721ud1:21  max_backup 0  retry    1  actfd        3
 1997-04-30 09:35:52 11200  8034             debugfd 5  debug      7  bootfd   4  log_blks     500
 1997-04-30 09:35:52 11200  8034             buf size   63488  buf count 10
 1997-04-30 09:35:52 11200  8034 read_config: return 0
 1997-04-30 09:35:52 11200  8034 bar_main: server status 5
 1997-04-30 09:35:52 11200  8034 bar_loadXBSA: enter
 1997-04-30 09:35:52 11200  8034 bar_loadXBSA: return 0
 1997-04-30 09:35:52 11200  8034 do_args: enter
 1997-04-30 09:35:52 11200  8034 do_args: got -b command
 1997-04-30 09:35:52 11200  8034 do_args: got -L 0 command
 1997-04-30 09:35:52 11200  8034 bar_add_dbslist: enter dbs_name dbspace2
 1997-04-30 09:35:52 11200  8034 bar_add_dbslist: return 0
 1997-04-30 09:35:52 11200  8034 do_args: workflags 1
 1997-04-30 09:35:52 11200  8034 do_args: return 0
 1997-04-30 09:35:52 11200  8034 bar_siginit: enter
 1997-04-30 09:35:52 11200  8034 bar_siginit: return
 1997-04-30 09:35:52 11200  8034 get_server_pid: enter
 1997-04-30 09:35:53 11200  8034 get_server_pid: pid 11176
 1997-04-30 09:35:53 11200  8034 get_server_pid: pid 11177
 1997-04-30 09:35:53 11200  8034 get_server_pid: pid 11178
 1997-04-30 09:35:53 11200  8034 get_server_pid: pid 11179
 1997-04-30 09:35:53 11200  8034 get_server_pid: pid 11180
 1997-04-30 09:35:53 11200  8034 get_server_pid: pid 11181
 1997-04-30 09:35:53 11200  8034 get_server_pid: pid 11182
 1997-04-30 09:35:53 11200  8034 get_server_pid: pid 11183
 1997-04-30 09:35:53 11200  8034 get_server_pid: pid 11184
 1997-04-30 09:35:53 11200  8034 get_server_pid: pid 11185
 1997-04-30 09:35:53 11200  8034 get_server_pid: pid 11186
 1997-04-30 09:35:53 11200  8034 bar_sighdlr: enter signal: 18
 1997-04-30 09:35:53 11200  8034 wait_on_child: enter
 1997-04-30 09:35:53 11200  8034 wait_on_child: child 11201 exited with status 0
 1997-04-30 09:35:53 11200  8034 get_wait_status: enter with cpid = 11201, status = 0
 1997-04-30 09:35:53 11200  8034 get_wait_status: return -1
 1997-04-30 09:35:53 11200  8034 wait_on_child: return -1 and child_exit_code -1
 1997-04-30 09:35:53 11200  8034 bar_sighdlr: return
 1997-04-30 09:35:53 11200  8034 get_server_pid: return
 1997-04-30 09:35:53 11200  8034 bar_exec: enter
 1997-04-30 09:35:53 11200  8034 get_a_connection: enter
 1997-04-30 09:35:53 11200  8034 bar_api_connect: enter
 1997-04-30 09:35:53 11200  8034 bar_alarm: enter 600 seconds
 1997-04-30 09:35:53 11200  8034 bar_alarm: return
 1997-04-30 09:35:53 11200  8034 bar_alarm: enter 0 seconds
 1997-04-30 09:35:53 11200  8034 bar_alarm: return
 1997-04-30 09:35:53 11200  8034 bar_api_connect: return 0
 1997-04-30 09:35:53 11200  8034 get_a_connection: return 0
 1997-04-30 09:35:53 11200  8034 bar_full_logs: enter
 1997-04-30 09:35:54 11200  8034 bar_full_logs: log_flag 0 return 0
 1997-04-30 09:35:54 11200  8034 bar_check_dbslist: enter
 1997-04-30 09:35:54 11200  8034 bar_check_dbslist: check dbspace2
 1997-04-30 09:35:54 11200  8034 bar_critical_space: enter name dbspace2
 1997-04-30 09:35:54 11200  8034 bar_critical_space: return 0 crit_flag 0
 1997-04-30 09:35:54 11200  8034 bar_add_objlist: enter objname dbspace2 objtype ND level 0
 1997-04-30 09:35:54 11200  8034 init_objdesc: enter
 1997-04-30 09:35:54 11200  8034 init_objnode: return
 1997-04-30 09:35:54 11200  8034 bar_add_objlist: return 0
 1997-04-30 09:35:54 11200  8034 bar_check_dbslist: return 0
 1997-04-30 09:35:54 11200  8034 barQueryApi: enter
 1997-04-30 09:35:54 11200  8034 bar_ver_init: enter
 1997-04-30 09:35:54 11200  8034 bar_ver_init: return
 1997-04-30 09:35:54 11200  8034 BSAQueryApiVersion: enter
 1997-04-0 09:35:54 11200  8034 BSAQueryApiVersion: return
 1997-04-30 09:35:54 11200  8034 barQueryApi: BSA version 1.0.1
 1997-04-30 09:35:54 11200  8034 barQueryApi: select * from systables where tabname = bar_version
 1997-04-30 09:35:54 11200  8034 bar_ver_sel: enter
 1997-04-30 09:35:54 11200  8034 bar_ver_sel: input bar 1 bsa 1.0.1 sm name  version
 1997-04-30 09:35:54 11200  8034 bar_ver_where: enter
 1997-04-30 09:35:54 11200  8034 bar_ver_init: input bar 1 bsa 1.0.1 sm  sm version
 1997-04-30 09:35:54 11200  8034 bar_ver_where: return  where  bar_version = '1'  and  bsa_version = '1.0.1'
 1997-04-30 09:35:54 11200  8034 bar_ver_sel: select * from bar_version  where  bar_version = '1'  and  bsa_version = '1.0.1'
 1997-04-30 09:35:54 11200  8034 bar_ver_sel: bar 1 bsa 1.0.1 sm name nsm version 0
 1997-04-30 09:35:54 11200  8034 bar_ver_sel: return 0
 1997-04-30 09:35:54 11200  8034 barQueryApi: return 0 (0x0)
 1997-04-30 09:35:54 11200  8034 bar_get_backup: enter
 1997-04-30 09:35:54 11200  8034 bar_get_backup: return 0
 1997-04-30 09:35:54 11200  8034 do_archive_backup: enter
 1997-04-30 09:35:54 11200  8034 bar_open_archive: enter
 1997-04-30 09:35:54 11200  8034 bar_open_archive: 1st_dbspace_in_list level tstamp wholeflag rootflag bufsize bufuse
 1997-04-30 09:35:54 11200  8034 bar_open_archive: dbspace2            0     0      0         0        63488   10
 1997-04-30 09:35:54 11200  8034 bar_alarm: enter 600 seconds
 1997-04-30 09:35:54 11200  8034 bar_alarm: return
 1997-04-30 09:35:57 11200  8034 bar_alarm: enter 0 seconds
 1997-04-30 09:35:57 11200  8034 bar_alarm: return
 1997-04-30 09:35:57 11200  8034 bar_open_archive: return 0	est_blks -268438064 logid -268438040
 1997-04-30 09:35:57 11200  8034 bar_read_archive: enter	buf 0x00000000
 1997-04-30 09:35:57 11200  8034 bar_alarm: enter 600 seconds
 1997-04-30 09:35:57 11200  8034 bar_alarm: return
 1997-04-30 09:35:57 11200  8034 bar_alarm: enter 0 seconds
 1997-04-30 09:35:57 11200  8034 bar_alarm: return
 1997-04-30 09:35:57 11200  8034 bar_read_archive: buf        nbytes events dbspace            lolog hilog
 1997-04-30 09:35:57 11200  8034 bar_read_archive: 0xeef78400 63488  66     dbspace2           0     0
 1997-04-30 09:35:57 11200  8034 bar_read_archive: return 0
 1997-04-30 09:35:57 11200  8034 barBeginTxn: enter
 1997-04-30 09:35:57 11200  8034 barBsaInit: enter
 1997-04-30 09:35:57 11200  8034 initObjOwner: enter
 1997-04-30 09:35:57 11200  8034 initObjOwner:bsa owner INFORMIX app owner root
 1997-04-30 09:35:57 11200  8034 initObjOwner: return
 1997-04-30 09:35:57 11200  8034 bar_full_logs: enter
 1997-04-30 09:35:57 11200  8034 bar_full_logs: log_flag 0 return 0
 1997-04-30 09:35:57 11200  8034 BSAInit: enter
 1997-04-30 09:35:58 11200  8034 BSAInit: return 0 (0x0) and bsaHandle 0x001c07f8
 1997-04-30 09:35:58 11200  8034 barBsaInit: return 0 (0x0)
 1997-04-30 09:35:58 11200  8034 BSABeginTxn: enter with bsaHandle 0x001c07f8
 1997-04-30 09:35:58 11200  8034 BSABeginTxn: return 0 (0x0)
 1997-04-30 09:35:58 11200  8034 barBeginTxn: return 0 (0x0)
 1997-04-30 09:35:58 11200  8034 barCreateObject: enter obj_name dbspace2
 1997-04-30 09:35:58 11200  8034 initObjDesc: enter
 1997-04-30 09:35:58 11200  8034 initObjDesc: space name /key721ud1 copy type 3 obj type 4 status 2
 1997-04-30 09:35:58 11200  8034 initObjDesc: return
 1997-04-30 09:35:58 11200  8034 barCreateObject: object owner      name                             copytype copyid                size      resource type status desc info
 1997-04-30 09:35:58 11200  8034 barCreateObject:  INFORMIX root     /key721ud1 /key721ud1/dbspace2/0          3 0          0          0    10240 ND       4     2
 1997-04-30 09:35:58 11200  8034 BSACreateObject: enter with bsaHandle 0x001c07f8
 1997-04-30 09:35:59 11200  8034 BSACreateObject: return 0 (0x0)
 1997-04-30 09:35:59 11200  8034 barCreateObject: return 0 (0x0)
 1997-04-30 09:35:59 11200  8034 barSendData: enter
 1997-04-30 09:35:59 11200  8034 barSendData: bufferLen = 63488, numBytes = 63488
 1997-04-30 09:35:59 11200  8034 BSASendData: enter with bsaHandle 0x001c07f8
 1997-04-30 09:35:59 11200  8034 BSASendData: return 0 (0x0)
 1997-04-30 09:35:59 11200  8034 barSendData: return 0 (0x0)
 1997-04-30 09:35:59 11200  8034 bar_read_archive: enter	buf 0xeef78400
 1997-04-30 09:35:59 11200  8034 bar_alarm: enter 600 seconds
 1997-04-30 09:35:59 11200  8034 bar_alarm: return
 1997-04-30 09:35:59 11200  8034 bar_alarm: enter 0 seconds
 1997-04-30 09:35:59 11200  8034 bar_alarm: return
 1997-04-30 09:35:59 11200  8034 bar_read_archive: buf        nbytes events dbspace            lolog hilog
 1997-04-30 09:35:59 11200  8034 bar_read_archive: 0xeef88400 63488  0                         0     0
 1997-04-30 09:35:59 11200  8034 bar_read_archive: return 0
 1997-04-30 09:35:59 11200  8034 barSendData: enter
 1997-04-30 09:35:59 11200  8034 barSendData: bufferLen = 63488, numBytes = 63488
 1997-04-30 09:35:59 11200  8034 BSASendData: enter with bsaHandle 0x001c07f8
 1997-04-30 09:35:59 11200  8034 BSASendData: return 0 (0x0)
 1997-04-30 09:35:59 11200  8034 barSendData: return 0 (0x0)
 1997-04-30 09:35:59 11200  8034 bar_read_archive: enter	buf 0xeef88400
 1997-04-30 09:35:59 11200  8034 bar_alarm: enter 600 seconds
 1997-04-30 09:35:59 11200  8034 bar_alarm: return
 1997-04-30 09:35:59 11200  8034 bar_alarm: enter 0 seconds
 1997-04-30 09:35:59 11200  8034 bar_alarm: return
 1997-04-30 09:35:59 11200  8034 bar_read_archive: buf        nbytes events dbspace            lolog hilog
 1997-04-30 09:35:59 11200  8034 bar_read_archive: 0xeef98400 63488  0                         0     0
 1997-04-30 09:35:59 11200  8034 bar_read_archive: return 0
 1997-04-30 09:35:59 11200  8034 barSendData: enter
 1997-04-30 09:35:59 11200  8034 barSendData: bufferLen = 63488, numBytes = 63488
 1997-04-30 09:35:59 11200  8034 BSASendData: enter with bsaHandle 0x001c07f8
 1997-04-30 09:35:59 11200  8034 BSASendData: return 0 (0x0)
 1997-04-30 09:35:59 11200  8034 barSendData: return 0 (0x0)
 1997-04-30 09:35:59 11200  8034 bar_read_archive: enter	buf 0xeef98400
 1997-04-30 09:35:59 11200  8034 bar_alarm: enter 600 seconds
 1997-04-30 09:35:59 11200  8034 bar_alarm: return
 1997-04-30 09:35:59 11200  8034 bar_alarm: enter 0 seconds
 1997-04-30 09:35:59 11200  8034 bar_alarm: return
 1997-04-30 09:35:59 11200  8034 bar_read_archive: buf        nbytes events dbspace            lolog hilog
 1997-04-30 09:35:59 11200  8034 bar_read_archive: 0xeefa8400 63488  0                         0     0
 1997-04-30 09:35:59 11200  8034 bar_read_archive: return 0
 1997-04-30 09:35:59 11200  8034 barSendData: enter
 1997-04-30 09:35:59 11200  8034 barSendData: bufferLen = 63488, numBytes = 63488
 1997-04-30 09:35:59 11200  8034 BSASendData: enter with bsaHandle 0x001c07f8
 1997-04-30 09:35:59 11200  8034 BSASendData: return 0 (0x0)
 1997-04-30 09:35:59 11200  8034 barSendData: return 0 (0x0)
 1997-04-30 09:35:59 11200  8034 bar_read_archive: enter	buf 0xeefa8400
 1997-04-30 09:35:59 11200  8034 bar_alarm: enter 600 seconds
 1997-04-30 09:35:59 11200  8034 bar_alarm: return
 1997-04-30 09:35:59 11200  8034 bar_alarm: enter 0 seconds
 1997-04-30 09:35:59 11200  8034 bar_alarm: return
 1997-04-30 09:35:59 11200  8034 bar_read_archive: buf        nbytes events dbspace            lolog hilog
 1997-04-30 09:35:59 11200  8034 bar_read_archive: 0xeefb8400 63488  4                         0     0
 1997-04-30 09:35:59 11200  8034 bar_read_archive: return 0
 1997-04-30 09:35:59 11200  8034 barSendData: enter
 1997-04-30 09:35:59 11200  8034 barSendData: bufferLen = 63488, numBytes = 63488
 1997-04-30 09:35:59 11200  8034 BSASendData: enter with bsaHandle 0x001c07f8
 1997-04-30 09:35:59 11200  8034 BSASendData: return 0 (0x0)
 1997-04-30 09:35:59 11200  8034 barSendData: return 0 (0x0)
 1997-04-30 09:35:59 11200  8034 bar_read_archive: enter	buf 0xeefb8400
 1997-04-30 09:35:59 11200  8034 bar_alarm: enter 600 seconds
 1997-04-30 09:35:59 11200  8034 bar_alarm: return
 1997-04-30 09:35:59 11200  8034 bar_alarm: enter 0 seconds
 1997-04-30 09:35:59 11200  8034 bar_alarm: return
 1997-04-30 09:35:59 11200  8034 bar_read_archive: buf        nbytes events dbspace            lolog hilog
 1997-04-30 09:35:59 11200  8034 bar_read_archive: 0xeefc8400 63488  1                         0     0
 1997-04-30 09:35:59 11200  8034 bar_read_archive: return 0
 1997-04-30 09:35:59 11200  8034 do_archive_backup: Sent 5 buffers for dbspace2
 1997-04-30 09:35:59 11200  8034 barEndTxn: enter
 1997-04-30 09:35:59 11200  8034 BSAEndData: enter with bsaHandle 0x001c07f8
 1997-04-30 09:35:59 11200  8034 BSAEndData: return 0 (0x0)
 1997-04-30 09:35:59 11200  8034 BSAEndTxn: enter with bsaHandle 0x001c07f8 and vote 1
 1997-04-30 09:36:00 11200  8034 BSAEndTxn: return 0 (0x0)
 1997-04-30 09:36:00 11200  8034 bar_upd_sysutils: enter
 1997-04-30 09:36:00 11200  8034 bar_upd_sysutils: obj_id name               level type act_id type status child_pid state retry in_catalog bootfile
 1997-04-30 09:36:00 11200  8034 bar_upd_sysutils: 0      dbspace2           0     ND   0      1    0      0         1     0     0          0
 1997-04-30 09:36:00 11200  8034 bar_full_logs: enter
 1997-04-30 09:36:00 11200  8034 bar_full_logs: log_flag 0 return 0
 1997-04-30 09:36:00 11200  8034 bar_obj_init: enter
 1997-04-30 09:36:00 11200  8034 bar_obj_init: return
 1997-04-30 09:36:00 11200  8034 bar_act_init: enter
 1997-04-30 09:36:00 11200  8034 bar_act_init: return
 1997-04-30 09:36:00 11200  8034 bar_ins_init: enter
 1997-04-30 09:36:00 11200  8034 bar_ins_init: return
 1997-04-30 09:36:00 11200  8034 bar_obj_ins: enter
 1997-04-30 09:36:00 11200  8034 bar_obj_ins: input srvname key721ud1 oid 0 name dbspace2 type ND
 1997-04-30 09:36:00 11200  8034 bar_obj_ins: select * from bar_object where obj_name = dbspace2 and obj_srv_name = key721ud1 and obj_type = ND 
 1997-04-30 09:36:00 11200  8034 Select Code : 0
 1997-04-30 09:36:00 11200  8034 bar_obj_ins: got object server key721ud1 id 6 name dbspace2 type ND
 1997-04-30 09:36:00 11200  8034 bar_obj_ins: return 0
 1997-04-30 09:36:00 11200  8034 bar_act_ins: enter
 1997-04-30 09:36:00 11200  8034 bar_act_ins: input aid 0 oid 6 type 1 status 0 start 1997-04-30 09:35:58 end 1997-04-30 09:36:00
 1997-04-30 09:36:00 11200  8034 bar_act_ins: instance aid 24 oid 6 time 0 level 0 copy 3678 0 version 1first log 0
 1997-04-30 09:36:00 11200  8034 bar_ins_ins: enter
 1997-04-30 09:36:00 11200  8034 bar_ins_ins: input oid 6 aid 24 time 0 level 0 copy 3678 0 version 1 first log 0
 1997-04-30 09:36:00 11200  8034 bar_ins_ins: return FUNCSUCC
 1997-04-30 09:36:00 11200  8034 bar_act_ins: return FUNCSUCC
 1997-04-30 09:36:00 11200  8034 writeboot: enter
 1997-04-30 09:36:00 11200  8034 writeboot: return
 1997-04-30 09:36:00 11200  8034 bar_upd_sysutils: return 0
 1997-04-30 09:36:00 11200  8034 barEndTxn: return 0 (0x0)
 1997-04-30 09:36:00 11200  8034 bar_close_archive: enter	commit_flag 0
 1997-04-30 09:36:00 11200  8034 bar_alarm: enter 600 seconds
 1997-04-30 09:36:00 11200  8034 bar_alarm: return
 1997-04-30 09:36:01 11200  8034 bar_alarm: enter 0 seconds
 1997-04-30 09:36:01 11200  8034 bar_alarm: return
 1997-04-30 09:36:01 11200  8034 bar_close_archive: return 0	tstamp 62055
 1997-04-30 09:36:01 11200  8034 bar_upd_time: enter
 1997-04-30 09:36:01 11200  8034 bar_ins_init: enter
 1997-04-30 09:36:01 11200  8034 bar_ins_init: return
 1997-04-30 09:36:01 11200  8034 bar_ins_init: enter
 1997-04-30 09:36:01 11200  8034 bar_ins_init: return
 1997-04-30 09:36:01 11200  8034 bar_ins_init: enter
 1997-04-30 09:36:01 11200  8034 bar_ins_init: return
 1997-04-30 09:36:01 11200  8034 bar_ins_upd: enter
 1997-04-30 09:36:01 11200  8034 bar_ins_upd input set: aid 0 oid 0 time 62055 lvl 0 copy 0 0 version  first log 15
 1997-04-30 09:36:01 11200  8034 bar_ins_upd input where : aid 24 oid 0 time -2147483648 level 0 copy 0 0 version  first log 0
 1997-04-30 09:36:01 11200  8034 bar_ins_where: enter
 1997-04-30 09:36:01 11200  8034 bar_ins_init: input oid 0 aid 24 time -2147483648 level 0 copy 0 0 version  first log 0
 1997-04-30 09:36:01 11200  8034 bar_ins_where: return  where  ins_aid = 24
 1997-04-30 09:36:01 11200  8034 bar_ins_upd: update bar_instance set ins_time = 62055 , ins_first_log = 15  where  ins_aid = 24
 1997-04-30 09:36:01 11200  8034 bar_ins_upd: return 0
 1997-04-30 09:36:01 11200  8034 bar_upd_time: return 0
 1997-04-30 09:36:01 11200  8034 barTerminate: enter
 1997-04-30 09:36:01 11200  8034 BSATerminate: enter with bsaHandle 0x001c07f8
 1997-04-30 09:36:01 11200  8034 BSATerminate: return 0 (0x0)
 1997-04-30 09:36:01 11200  8034 barTerminate: return 0 (0x0)
 1997-04-30 09:36:01 11200  8034 do_archive_backup return 0
 1997-04-30 09:36:01 11200  8034 bar_upd_list: entered
 1997-04-30 09:36:01 11200  8034 bar_upd_sysutils: enter
 1997-04-30 09:36:01 11200  8034 bar_upd_sysutils: obj_id name               level type act_id type status child_pid state retry in_catalog bootfile
 1997-04-30 09:36:01 11200  8034 bar_upd_sysutils: 6      dbspace2           0     ND   24     1    0      0         1     0     1          1
 1997-04-30 09:36:01 11200  8034 bar_upd_sysutils: return 0
 1997-04-30 09:36:01 11200  8034 bar_upd_list: return 0
 1997-04-30 09:36:01 11200  8034 return from bar_exec 0
 1997-04-30 09:36:01 11200  8034 bar_main: exiting with 0 (0x0)
 1997-04-30 09:36:01 11200  8034 bar_cleanup: enter
 1997-04-30 09:36:01 11200  8034 bar_api_disconnect: enter
 1997-04-30 09:36:01 11200  8034 bar_alarm: enter 600 seconds
 1997-04-30 09:36:01 11200  8034 bar_alarm: return
 1997-04-30 09:36:01 11200  8034 bar_alarm: enter 0 seconds
 1997-04-30 09:36:01 11200  8034 bar_alarm: return
 1997-04-30 09:36:01 11200  8034 bar_api_disconnect: return
 1997-04-30 09:36:01 11200  8034 bar_cleanup: return

Home   Index
1