Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250914060008\orcl_j000_23544_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:34753M/63366M, Ph+PgF:39359M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 23544, image: ORACLE.EXE (J000) *** 2025-09-14 06:00:08.746 *** SESSION ID:(201.2897) 2025-09-14 06:00:08.746 *** 2025-09-14 06:00:08.746 Process diagnostic dump for ORACLE.EXE (J000), OS id=23544, pid: 25, proc_ser: 253, sid: 201, sess_ser: 2897 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 23544 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J000) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.053906 sec, exc=0.053906 sec, total=0.053906 sec wait times: max=0.500000 sec, heur=6.553688 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.000015 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.500019 sec, exc=0.500019 sec, total=0.500019 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.500166 sec, exc=0.500166 sec, total=0.500166 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499973 sec, exc=0.499973 sec, total=0.499973 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.500022 sec, exc=0.500022 sec, total=0.500022 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 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.000008 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500186 sec, exc=0.500186 sec, total=0.500186 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=6 seq_num=7 snap_id=1 wait times: snap=0.509222 sec, exc=0.509222 sec, total=0.509222 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 7: 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.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.499990 sec, exc=0.499990 sec, total=0.499990 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time Sampled Session History of session 201 serial 2897 --------------------------------------------------- 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, 06:00:02 - 06:00:08] idle wait at each sample [session created at: 06:00:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-14 06:00:08.746 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 23544, J000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-14 05:50:22.664359 :CD2C03C8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 05:50:22.652 2025-09-14 05:50:22.664361 :CD2C03C9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25180 pso_num=25 pso_serial#=241 2025-09-14 05:50:22.664489 :CD2C03CA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25180 osp_idx=23 osp_ver=297584 osp_pg=0 (spawn #590258) 2025-09-14 05:50:22.664490 :CD2C03CB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 05:50:22.664490 :CD2C03CC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=222 time=2214673955 2025-09-14 05:50:22.664491 :CD2C03CD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 05:50:22.664491 :CD2C03CE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 05:50:22.664491 :CD2C03CF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 05:50:22.664491 :CD2C03D0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 05:50:22.664492 :CD2C03D1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 05:50:22.664977 :CD2C03D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 05:50:52.723362 :CD2C0D98:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 05:51:22.744710 :CD2C174A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 05:51:22.733 2025-09-14 05:51:22.744713 :CD2C174B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23816 pso_num=25 pso_serial#=242 2025-09-14 05:51:22.744833 :CD2C174C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23816 osp_idx=23 osp_ver=297585 osp_pg=0 (spawn #590260) 2025-09-14 05:51:22.744834 :CD2C174D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 05:51:22.744834 :CD2C174E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=223 time=2214734049 2025-09-14 05:51:22.744834 :CD2C174F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 05:51:22.744835 :CD2C1750:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 05:51:22.744835 :CD2C1751:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 05:51:22.744836 :CD2C1752:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 05:51:22.744836 :CD2C1753:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 05:51:22.745367 :CD2C1754:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 05:51:52.802654 :CD2C20E9:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 05:52:22.827878 :CD2C2A88:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 05:52:22.817 2025-09-14 05:52:22.827880 :CD2C2A89:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=21040 pso_num=25 pso_serial#=243 2025-09-14 05:52:22.828014 :CD2C2A8A:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=21040 osp_idx=23 osp_ver=297586 osp_pg=0 (spawn #590262) 2025-09-14 05:52:22.828015 :CD2C2A8B:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 05:52:22.828015 :CD2C2A8C:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=224 time=2214794127 2025-09-14 05:52:22.828016 :CD2C2A8D:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 05:52:22.828016 :CD2C2A8E:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 05:52:22.828016 :CD2C2A8F:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 05:52:22.828017 :CD2C2A90:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 05:52:22.828017 :CD2C2A91:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 05:52:22.828516 :CD2C2A92:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 05:52:52.911428 :CD2C3432:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 05:53:07.915066 :CD2C3972:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 05:53:07.904 2025-09-14 05:53:07.915068 :CD2C3973:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24788 pso_num=25 pso_serial#=244 2025-09-14 05:53:07.915206 :CD2C3974:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24788 osp_idx=23 osp_ver=297587 osp_pg=0 (spawn #590264) 2025-09-14 05:53:07.915207 :CD2C3975:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 05:53:07.915207 :CD2C3976:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=225 time=2214839205 2025-09-14 05:53:07.915208 :CD2C3977:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 05:53:07.915208 :CD2C3978:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 05:53:07.915208 :CD2C3979:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 05:53:07.915209 :CD2C397A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 05:53:07.915209 :CD2C397B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 05:53:07.915687 :CD2C397C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 05:53:22.918295 :CD2C3E43:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-14 05:53:53.023267 :CD2C480A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 05:54:23.034867 :CD2C519D:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 05:54:23.023 2025-09-14 05:54:23.034869 :CD2C519E:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24344 pso_num=25 pso_serial#=245 2025-09-14 05:54:23.035005 :CD2C519F:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24344 osp_idx=23 osp_ver=297588 osp_pg=0 (spawn #590266) 2025-09-14 05:54:23.035006 :CD2C51A0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 05:54:23.035006 :CD2C51A1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=226 time=2214914330 2025-09-14 05:54:23.035007 :CD2C51A2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 05:54:23.035007 :CD2C51A3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 05:54:23.035007 :CD2C51A4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 05:54:23.035008 :CD2C51A5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 05:54:23.035008 :CD2C51A6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 05:54:23.035473 :CD2C51A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 05:54:53.079811 :CD2C5C25:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 05:55:23.127348 :CD2C65B0:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 05:55:23.115 2025-09-14 05:55:23.127350 :CD2C65B1:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24648 pso_num=25 pso_serial#=246 2025-09-14 05:55:23.127510 :CD2C65B2:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24648 osp_idx=23 osp_ver=297589 osp_pg=0 (spawn #590268) 2025-09-14 05:55:23.127512 :CD2C65B3:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 05:55:23.127512 :CD2C65B4:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=227 time=2214974424 2025-09-14 05:55:23.127512 :CD2C65B5:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 05:55:23.127512 :CD2C65B6:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 05:55:23.127513 :CD2C65B7:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 05:55:23.127513 :CD2C65B8:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 05:55:23.127514 :CD2C65B9:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 05:55:23.128048 :CD2C65BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 05:55:53.201646 :CD2C6FD0:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 05:56:23.206281 :CD2C7981:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 05:56:23.195 2025-09-14 05:56:23.206283 :CD2C7982:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25132 pso_num=25 pso_serial#=247 2025-09-14 05:56:23.206398 :CD2C7983:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25132 osp_idx=23 osp_ver=297590 osp_pg=0 (spawn #590272) 2025-09-14 05:56:23.206399 :CD2C7984:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 05:56:23.206399 :CD2C7985:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=228 time=2215034502 2025-09-14 05:56:23.206400 :CD2C7986:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 05:56:23.206400 :CD2C7987:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 05:56:23.206400 :CD2C7988:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 05:56:23.206401 :CD2C7989:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 05:56:23.206401 :CD2C798A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 05:56:23.206876 :CD2C798B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 05:56:53.297718 :CD2C833F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 05:57:23.311451 :CD2C8D3F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 05:57:23.299 2025-09-14 05:57:23.311453 :CD2C8D40:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24348 pso_num=25 pso_serial#=248 2025-09-14 05:57:23.311576 :CD2C8D41:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24348 osp_idx=23 osp_ver=297591 osp_pg=0 (spawn #590274) 2025-09-14 05:57:23.311577 :CD2C8D42:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 05:57:23.311577 :CD2C8D43:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=229 time=2215094612 2025-09-14 05:57:23.311577 :CD2C8D44:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 05:57:23.311578 :CD2C8D45:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 05:57:23.311578 :CD2C8D46:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 05:57:23.311579 :CD2C8D47:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 05:57:23.311579 :CD2C8D48:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 05:57:23.312101 :CD2C8D49:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 05:57:53.403509 :CD2C973F:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 05:58:23.398341 :CD2CA0E3:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 05:58:23.387 2025-09-14 05:58:23.398343 :CD2CA0E4:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=24800 pso_num=25 pso_serial#=249 2025-09-14 05:58:23.398467 :CD2CA0E5:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=24800 osp_idx=23 osp_ver=297592 osp_pg=0 (spawn #590277) 2025-09-14 05:58:23.398467 :CD2CA0E6:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 05:58:23.398468 :CD2CA0E7:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=230 time=2215154690 2025-09-14 05:58:23.398468 :CD2CA0E8:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 05:58:23.398468 :CD2CA0E9:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 05:58:23.398469 :CD2CA0EA:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 05:58:23.398469 :CD2CA0EB:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 05:58:23.398469 :CD2CA0EC:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 05:58:23.398992 :CD2CA0ED:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 05:58:53.507494 :CD2CAA8C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 05:59:23.503959 :CD2CB436:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 05:59:23.492 2025-09-14 05:59:23.503961 :CD2CB437:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25100 pso_num=25 pso_serial#=250 2025-09-14 05:59:23.504103 :CD2CB438:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25100 osp_idx=23 osp_ver=297593 osp_pg=0 (spawn #590279) 2025-09-14 05:59:23.504103 :CD2CB439:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 05:59:23.504104 :CD2CB43A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=231 time=2215214799 2025-09-14 05:59:23.504104 :CD2CB43B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 05:59:23.504104 :CD2CB43C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 05:59:23.504105 :CD2CB43D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 05:59:23.504105 :CD2CB43E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 05:59:23.504105 :CD2CB43F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 05:59:23.504578 :CD2CB440:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 05:59:53.559989 :CD2CBE70:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 06:00:00.046947 :CD2CC0BC:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 06:00:00.038 2025-09-14 06:00:00.046949 :CD2CC0BD:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=25248 pso_num=25 pso_serial#=251 2025-09-14 06:00:00.047087 :CD2CC0BE:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=25248 osp_idx=23 osp_ver=297594 osp_pg=0 (spawn #590281) 2025-09-14 06:00:00.047088 :CD2CC0BF:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 06:00:00.047088 :CD2CC0C0:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=232 time=2215251346 2025-09-14 06:00:00.047089 :CD2CC0C1:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 06:00:00.047089 :CD2CC0C2:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 06:00:00.047089 :CD2CC0C3:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 06:00:00.047090 :CD2CC0C4:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 06:00:00.047090 :CD2CC0C5:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 06:00:00.047568 :CD2CC0C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 06:00:00.047625 :CD2CC0CD:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-14 06:00:00.047737 :CD2CC0E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-14 06:00:00.052840 :CD2CC0EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-14 06:00:00.052883 :CD2CC0FA:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-14 06:00:00.054646 :CD2CC100:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-14 06:00:00.054685 :CD2CC10B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-14 06:00:00.081669 :CD2CC11C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-14 06:00:00.082070 :CD2CC12B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-09-14 06:00:00.082083 :CD2CC12C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-09-14 06:00:00.084843 :CD2CC12D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-14 06:00:00.085181 :CD2CC13C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-09-14 06:00:00.085196 :CD2CC13D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-09-14 06:00:00.087959 :CD2CC13E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-14 06:00:00.088250 :CD2CC14D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:ktcc.c:879 2025-09-14 06:00:00.088262 :CD2CC14E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:ktcc.c:879 2025-09-14 06:00:00.088438 :CD2CC14F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-14 06:00:00.088463 :CD2CC15B:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-14 06:00:00.144254 :CD2CC16C:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-14 06:00:00.145214 :CD2CC173:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 06:00:00.718886 :CD2CC1A5:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 06:00:00.709 2025-09-14 06:00:00.718888 :CD2CC1A6:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=22940 pso_num=25 pso_serial#=252 2025-09-14 06:00:00.719024 :CD2CC1A7:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=22940 (legacy spawn) 2025-09-14 06:00:00.725529 :CD2CC1A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 06:00:00.725700 :CD2CC1B1:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-14 06:00:00.726512 :CD2CC1B2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-09-14 06:00:02.052126 :CD2CC266:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 09-14 06:00:02.046 2025-09-14 06:00:02.052129 :CD2CC267:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=23544 pso_num=25 pso_serial#=253 2025-09-14 06:00:02.052268 :CD2CC268:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=23544 osp_idx=23 osp_ver=297596 osp_pg=0 (spawn #590282) 2025-09-14 06:00:02.052269 :CD2CC269:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 06:00:02.052269 :CD2CC26A:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=233 time=2215253362 2025-09-14 06:00:02.052270 :CD2CC26B:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 06:00:02.052270 :CD2CC26C:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 06:00:02.052271 :CD2CC26D:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 06:00:02.052271 :CD2CC26E:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 06:00:02.052272 :CD2CC26F:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 06:00:02.052767 :CD2CC270:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 06:00:02.058219 :CD2CC29D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 06:00:02.058267 :CD2CC2A0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-09-14 06:00:02.069773 :CD2CC2FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 06:00:02.074996 :CD2CC320:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 06:00:02.075921 :CD2CC33D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 06:00:02.081423 :CD2CC358:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-09-14 06:00:02.081599 :CD2CC372:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 06:00:02.082319 :CD2CC37A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 06:00:02.082520 :CD2CC382:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-09-14 06:00:02.083950 :CD2CC383:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 06:00:02.083997 :CD2CC386:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-09-14 06:00:02.084097 :CD2CC393:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-09-14 06:00:02.187600 :CD2CC5F8:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-14 06:00:02.189777 :CD2CC5FC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 06:00:02.189828 :CD2CC5FF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:jslv.c:9203 2025-09-14 06:00:02.192309 :CD2CC605:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:jslv.c:9203 2025-09-14 06:00:02.195937 :CD2CC606:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 06:00:02.196032 :CD2CC609:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=59 loc='kkj.h LINE:928 ID:jslve_execute' id1=0 id2=0 name= type=0 2025-09-14 06:00:02.196107 :CD2CC616:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 23544, J000)