Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250801220006\orcl_j001_18292_bucket.trc Oracle Database 11g Enterprise Edition Release 11.2.0.1.0 - 64bit Production With the Partitioning, OLAP, Data Mining and Real Application Testing options Windows NT Version V6.2 CPU : 32 - type 8664, 16 Physical Cores Process Affinity : 0x0x0000000000000000 Memory (Avail/Total): Ph:35842M/63366M, Ph+PgF:41417M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 18292, image: ORACLE.EXE (J001) *** 2025-08-01 22:00:07.002 *** SESSION ID:(193.1537) 2025-08-01 22:00:07.002 *** 2025-08-01 22:00:07.002 Process diagnostic dump for ORACLE.EXE (J001), OS id=18292, pid: 24, proc_ser: 41, sid: 193, sess_ser: 1537 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 18292 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.494294 sec, exc=0.494294 sec, total=0.494294 sec wait times: max=0.500000 sec, heur=6.669611 sec wait counts: calls=1 os=1 in_wait=1 iflags=0x5a8 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000010 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.510111 sec, exc=0.510111 sec, total=0.510111 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499994 sec, exc=0.499994 sec, total=0.499994 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.499186 sec, exc=0.499186 sec, total=0.499186 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000003 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.063314 sec, exc=0.063314 sec, total=0.063314 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.510098 sec, exc=0.510098 sec, total=0.510098 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000005 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time Sampled Session History of session 193 serial 1537 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [7 samples, 22:00:00 - 22:00:07] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.015000 sec (max dump time=30.000000 sec) *** 2025-08-01 22:00:07.018 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 18292, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-01 21:46:24.273562 :BA0D2A39:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-01 21:46:54.304257 :BA0D340A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-01 21:46:54.293 2025-08-01 21:46:54.304259 :BA0D340B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18492 pso_num=24 pso_serial#=28 2025-08-01 21:46:54.304374 :BA0D340C:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18492 osp_idx=22 osp_ver=653093 osp_pg=0 (spawn #445769) 2025-08-01 21:46:54.304374 :BA0D340D:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-01 21:46:54.304375 :BA0D340E:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=97 time=2765483611 2025-08-01 21:46:54.304375 :BA0D340F:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:46:54.304375 :BA0D3410:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:46:54.304376 :BA0D3411:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:46:54.304376 :BA0D3412:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:46:54.304376 :BA0D3413:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:46:54.304903 :BA0D3414:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-01 21:47:24.343277 :BA0D3DD8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-01 21:47:54.376521 :BA0D47BD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-01 21:47:54.366 2025-08-01 21:47:54.376523 :BA0D47BE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16116 pso_num=24 pso_serial#=29 2025-08-01 21:47:54.376645 :BA0D47BF:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16116 osp_idx=22 osp_ver=653094 osp_pg=0 (spawn #445771) 2025-08-01 21:47:54.376646 :BA0D47C0:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-01 21:47:54.376647 :BA0D47C1:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=98 time=2765543673 2025-08-01 21:47:54.376647 :BA0D47C2:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:47:54.376647 :BA0D47C3:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:47:54.376648 :BA0D47C4:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:47:54.376648 :BA0D47C5:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:47:54.376649 :BA0D47C6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:47:54.377169 :BA0D47C7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-01 21:48:24.456006 :BA0D513E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-01 21:48:54.448820 :BA0D5B0B:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-01 21:48:54.438 2025-08-01 21:48:54.448822 :BA0D5B0C:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10444 pso_num=24 pso_serial#=30 2025-08-01 21:48:54.448946 :BA0D5B0D:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10444 osp_idx=22 osp_ver=653095 osp_pg=0 (spawn #445773) 2025-08-01 21:48:54.448947 :BA0D5B0E:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-01 21:48:54.448948 :BA0D5B0F:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=99 time=2765603751 2025-08-01 21:48:54.448948 :BA0D5B10:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:48:54.448949 :BA0D5B11:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:48:54.448949 :BA0D5B12:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:48:54.448949 :BA0D5B13:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:48:54.448950 :BA0D5B14:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:48:54.449432 :BA0D5B15:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-01 21:49:24.510183 :BA0D6515:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-01 21:49:54.557291 :BA0D6EF0:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-01 21:49:54.546 2025-08-01 21:49:54.557293 :BA0D6EF1:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14152 pso_num=24 pso_serial#=31 2025-08-01 21:49:54.557411 :BA0D6EF2:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14152 osp_idx=22 osp_ver=653096 osp_pg=0 (spawn #445776) 2025-08-01 21:49:54.557411 :BA0D6EF3:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-01 21:49:54.557412 :BA0D6EF4:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=100 time=2765663861 2025-08-01 21:49:54.557412 :BA0D6EF5:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:49:54.557412 :BA0D6EF6:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:49:54.557413 :BA0D6EF7:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:49:54.557413 :BA0D6EF8:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:49:54.557413 :BA0D6EF9:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:49:54.557972 :BA0D6EFA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-01 21:50:24.618561 :BA0D789F:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-01 21:50:54.644864 :BA0D8250:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-01 21:50:54.633 2025-08-01 21:50:54.644866 :BA0D8251:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19592 pso_num=24 pso_serial#=32 2025-08-01 21:50:54.644985 :BA0D8252:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19592 osp_idx=22 osp_ver=653097 osp_pg=0 (spawn #445778) 2025-08-01 21:50:54.644985 :BA0D8253:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-01 21:50:54.644986 :BA0D8254:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=101 time=2765723939 2025-08-01 21:50:54.644986 :BA0D8255:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:50:54.644986 :BA0D8256:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:50:54.644987 :BA0D8257:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:50:54.644987 :BA0D8258:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:50:54.644987 :BA0D8259:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:50:54.645485 :BA0D825A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-01 21:51:24.721150 :BA0D8D08:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-01 21:51:54.707471 :BA0D96FB:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-01 21:51:54.696 2025-08-01 21:51:54.707473 :BA0D96FC:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19744 pso_num=24 pso_serial#=33 2025-08-01 21:51:54.707597 :BA0D96FD:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19744 osp_idx=22 osp_ver=653098 osp_pg=0 (spawn #445780) 2025-08-01 21:51:54.707598 :BA0D96FE:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-01 21:51:54.707598 :BA0D96FF:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=102 time=2765784001 2025-08-01 21:51:54.707598 :BA0D9700:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:51:54.707599 :BA0D9701:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:51:54.707599 :BA0D9702:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:51:54.707600 :BA0D9703:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:51:54.707600 :BA0D9704:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:51:54.708165 :BA0D9705:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-01 21:52:09.725717 :BA0D9C1C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-01 21:52:39.827626 :BA0DA576:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-01 21:52:54.789712 :BA0DAAAF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-01 21:52:54.778 2025-08-01 21:52:54.789714 :BA0DAAB0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17204 pso_num=24 pso_serial#=34 2025-08-01 21:52:54.789849 :BA0DAAB1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=17204 osp_idx=22 osp_ver=653099 osp_pg=0 (spawn #445782) 2025-08-01 21:52:54.789850 :BA0DAAB2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-01 21:52:54.789850 :BA0DAAB3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=103 time=2765844095 2025-08-01 21:52:54.789851 :BA0DAAB4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:52:54.789851 :BA0DAAB5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:52:54.789851 :BA0DAAB6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:52:54.789852 :BA0DAAB7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:52:54.789852 :BA0DAAB8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:52:54.790331 :BA0DAAB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-01 21:53:24.886555 :BA0DB477:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-01 21:53:54.886477 :BA0DBE61:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-01 21:53:54.875 2025-08-01 21:53:54.886479 :BA0DBE62:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19208 pso_num=24 pso_serial#=35 2025-08-01 21:53:54.886646 :BA0DBE63:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=19208 osp_idx=22 osp_ver=653100 osp_pg=0 (spawn #445785) 2025-08-01 21:53:54.886648 :BA0DBE64:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-01 21:53:54.886649 :BA0DBE65:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=104 time=2765904189 2025-08-01 21:53:54.886649 :BA0DBE66:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:53:54.886650 :BA0DBE67:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:53:54.886650 :BA0DBE68:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:53:54.886650 :BA0DBE69:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:53:54.886650 :BA0DBE6A:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:53:54.887189 :BA0DBE6B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-01 21:54:24.918594 :BA0DC841:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-01 21:54:54.939078 :BA0DD1F4:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-01 21:54:54.927 2025-08-01 21:54:54.939080 :BA0DD1F5:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=4148 pso_num=24 pso_serial#=36 2025-08-01 21:54:54.939199 :BA0DD1F6:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=4148 osp_idx=22 osp_ver=653101 osp_pg=0 (spawn #445787) 2025-08-01 21:54:54.939200 :BA0DD1F7:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-01 21:54:54.939200 :BA0DD1F8:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=105 time=2765964236 2025-08-01 21:54:54.939201 :BA0DD1F9:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:54:54.939201 :BA0DD1FA:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:54:54.939201 :BA0DD1FB:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:54:54.939202 :BA0DD1FC:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:54:54.939202 :BA0DD1FD:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:54:54.939704 :BA0DD1FE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-01 21:55:25.036905 :BA0DDBB4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-01 21:55:55.017331 :BA0DE58F:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-01 21:55:55.006 2025-08-01 21:55:55.017333 :BA0DE590:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=15328 pso_num=24 pso_serial#=37 2025-08-01 21:55:55.017457 :BA0DE591:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=15328 osp_idx=22 osp_ver=653102 osp_pg=0 (spawn #445789) 2025-08-01 21:55:55.017458 :BA0DE592:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-01 21:55:55.017459 :BA0DE593:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=106 time=2766024314 2025-08-01 21:55:55.017460 :BA0DE594:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:55:55.017460 :BA0DE595:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:55:55.017461 :BA0DE596:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:55:55.017461 :BA0DE597:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:55:55.017462 :BA0DE598:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:55:55.017957 :BA0DE599:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-01 21:56:25.102914 :BA0DF01A:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-01 21:56:55.111660 :BA0DF9B7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-01 21:56:55.100 2025-08-01 21:56:55.111662 :BA0DF9B8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=7196 pso_num=24 pso_serial#=38 2025-08-01 21:56:55.111793 :BA0DF9B9:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=7196 osp_idx=22 osp_ver=653103 osp_pg=0 (spawn #445791) 2025-08-01 21:56:55.111794 :BA0DF9BA:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-01 21:56:55.111794 :BA0DF9BB:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=107 time=2766084408 2025-08-01 21:56:55.111795 :BA0DF9BC:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:56:55.111795 :BA0DF9BD:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:56:55.111796 :BA0DF9BE:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:56:55.111796 :BA0DF9BF:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:56:55.111796 :BA0DF9C0:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:56:55.112338 :BA0DF9C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-01 21:57:25.206564 :BA0E03B6:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-01 21:57:55.191174 :BA0E0D8D:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-01 21:57:55.180 2025-08-01 21:57:55.191176 :BA0E0D8E:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10992 pso_num=24 pso_serial#=39 2025-08-01 21:57:55.191299 :BA0E0D8F:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=10992 osp_idx=22 osp_ver=653104 osp_pg=0 (spawn #445793) 2025-08-01 21:57:55.191300 :BA0E0D90:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-01 21:57:55.191301 :BA0E0D91:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=108 time=2766144486 2025-08-01 21:57:55.191301 :BA0E0D92:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:57:55.191301 :BA0E0D93:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:57:55.191302 :BA0E0D94:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:57:55.191302 :BA0E0D95:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:57:55.191302 :BA0E0D96:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:57:55.191811 :BA0E0D97:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-01 21:58:25.244642 :BA0E1756:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-01 21:58:55.254234 :BA0E2144:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-01 21:58:55.242 2025-08-01 21:58:55.254236 :BA0E2145:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14712 pso_num=24 pso_serial#=40 2025-08-01 21:58:55.254373 :BA0E2146:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=14712 osp_idx=22 osp_ver=653105 osp_pg=0 (spawn #445796) 2025-08-01 21:58:55.254373 :BA0E2147:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-01 21:58:55.254374 :BA0E2148:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=109 time=2766204548 2025-08-01 21:58:55.254374 :BA0E2149:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:58:55.254374 :BA0E214A:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:58:55.254375 :BA0E214B:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:58:55.254375 :BA0E214C:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:58:55.254375 :BA0E214D:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:58:55.254942 :BA0E214E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-01 21:59:25.330234 :BA0E2B86:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-01 21:59:55.333017 :BA0E3507:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-01 21:59:55.322 2025-08-01 21:59:55.333019 :BA0E3508:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=18292 pso_num=24 pso_serial#=41 2025-08-01 21:59:55.333130 :BA0E3509:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=18292 osp_idx=22 osp_ver=653106 osp_pg=0 (spawn #445799) 2025-08-01 21:59:55.333131 :BA0E350A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-01 21:59:55.333131 :BA0E350B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=110 time=2766264626 2025-08-01 21:59:55.333132 :BA0E350C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-01 21:59:55.333132 :BA0E350D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-01 21:59:55.333132 :BA0E350E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-01 21:59:55.333133 :BA0E350F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-01 21:59:55.333133 :BA0E3510:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-01 21:59:55.333626 :BA0E3511:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-01 22:00:00.316838 :BA0E384C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-01 22:00:00.316838*:BA0E385C:sql_mon_query:keswx.c@6099:keswxCurPushMonitoring(begin): xsc=0x0000000024D5C718 estart=1754056799 eid=16785283 ctx=0x000000077FD6A000 cwh=0x0000000024D50068 cpu=0 buffg=1 interb=0 r=0 rb=0 w=0 wb=0 2025-08-01 22:00:00.316838*:BA0E385D:sql_mon_query:keswx.c@2839:keswxCurPrepare(): Allocate monitor context: xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 pubTabIdxCnt=0 2025-08-01 22:00:00.316838*:BA0E385E:sql_mon_query:keswx.c@6131:keswxCurPushMonitoring(): clear monitoring flag for xsc=0x0000000024D5C718 2025-08-01 22:00:00.316838*:BA0E385F:sql_mon_query:keswx.c@6263:keswxCurPushMonitoring(end): full monitoring not yet started for xsc=0x0000000024D5C718 2025-08-01 22:00:00.316838*:BA0E3860:sql_mon_query:keswx.c@3282:keswxCurEndPlanMonitoringCb(begin): xsc=0x0000000024D5C718 curCtx=0x0000000024D58938 xsc->flg4=65792 flags=2 2025-08-01 22:00:00.316838*:BA0E3861:sql_mon_query:keswx.c@3389:keswxCurEndPlanMonitoringCb(end): monitoring has ended for xsc=0x0000000024D5C718 2025-08-01 22:00:00.320564 :BA0E3862:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-01 22:00:00.320595 :BA0E3865:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:jslv.c:9203 2025-08-01 22:00:00.332202 :BA0E38C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-01 22:00:00.337570 :BA0E3A2B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-01 22:00:00.338453 :BA0E3A34:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-01 22:00:00.344337 :BA0E3A7A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-08-01 22:00:00.344491 :BA0E3A89:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-01 22:00:00.345278 :BA0E3A91:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-01 22:00:00.345496 :BA0E3A9C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:jslv.c:9203 2025-08-01 22:00:00.347161 :BA0E3AAA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-01 22:00:00.347203 :BA0E3AAD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-08-01 22:00:00.347296 :BA0E3ABE:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-01 22:00:00.441562 :BA0E3BCF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-01 22:00:04.013617 :BA0E4168:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 18292, J001)