Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250627080245\orcl_j001_16560_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:36315M/63366M, Ph+PgF:42431M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 16560, image: ORACLE.EXE (J001) *** 2025-06-27 08:02:45.593 *** SESSION ID:(201.50879) 2025-06-27 08:02:45.593 *** 2025-06-27 08:02:45.593 Process diagnostic dump for ORACLE.EXE (J001), OS id=16560, pid: 25, proc_ser: 58, sid: 201, sess_ser: 50879 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 16560 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J001) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=0.021582 sec, exc=0.021582 sec, total=0.021582 sec wait times: max=0.500000 sec, heur=16.065140 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=31 seq_num=32 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=0.500119 sec, exc=0.500119 sec, total=0.500119 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=29 seq_num=30 snap_id=1 wait times: snap=0.499976 sec, exc=0.499976 sec, total=0.499976 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=0.500013 sec, exc=0.500013 sec, total=0.500013 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=0.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=25 seq_num=26 snap_id=1 wait times: snap=0.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=24 seq_num=25 snap_id=1 wait times: snap=0.506252 sec, exc=0.506252 sec, total=0.506252 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000007 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=23 seq_num=24 snap_id=1 wait times: snap=0.500142 sec, exc=0.500142 sec, total=0.500142 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=22 seq_num=23 snap_id=1 wait times: snap=0.499989 sec, exc=0.499989 sec, total=0.499989 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 50879 --------------------------------------------------- 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 --------------------------------------------------- [17 samples, 08:02:29 - 08:02:45] idle wait at each sample [session created at: 08:02:29] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-27 08:02:45.593 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 16560, J001) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-27 07:51:58.695331 :AA3C6E8E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-27 07:52:28.695419 :AA3C7835:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-27 07:52:28.684 2025-06-27 07:52:28.695421 :AA3C7836:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17512 pso_num=25 pso_serial#=45 2025-06-27 07:52:28.695539 :AA3C7837:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17512 osp_idx=23 osp_ver=170752 osp_pg=0 (spawn #327198) 2025-06-27 07:52:28.695540 :AA3C7838:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 07:52:28.695542 :AA3C7839:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=224 time=3986491875 2025-06-27 07:52:28.695543 :AA3C783A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 07:52:28.695543 :AA3C783B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 07:52:28.695543 :AA3C783C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 07:52:28.695543 :AA3C783D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 07:52:28.695543 :AA3C783E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 07:52:28.696024 :AA3C783F: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-06-27 07:52:58.776846 :AA3C81DC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-27 07:53:20.085517 :AA3C8951:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-27 07:53:20.073 2025-06-27 07:53:20.085519 :AA3C8952:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16488 pso_num=25 pso_serial#=46 2025-06-27 07:53:20.085673 :AA3C8953:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16488 osp_idx=23 osp_ver=170753 osp_pg=0 (spawn #327200) 2025-06-27 07:53:20.085674 :AA3C8954:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 07:53:20.085674 :AA3C8955:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=225 time=3986543266 2025-06-27 07:53:20.085675 :AA3C8956:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 07:53:20.085675 :AA3C8957:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 07:53:20.085676 :AA3C8958:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 07:53:20.085676 :AA3C8959:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 07:53:20.085676 :AA3C895A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 07:53:20.086194 :AA3C895B: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-06-27 07:53:20.086235 :AA3C8961: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-06-27 07:53:20.090222 :AA3C897A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-27 07:53:20.090263 :AA3C897B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-27 07:53:20.090516 :AA3C897C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-27 07:53:20.090532 :AA3C897D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-27 07:53:20.090742 :AA3C897E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-27 07:53:20.090756 :AA3C897F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-27 07:53:20.091024 :AA3C8980:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-27 07:53:20.091040 :AA3C8981:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-27 07:53:20.091272 :AA3C8982:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-27 07:53:20.091285 :AA3C8983:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-27 07:53:20.091490 :AA3C8984:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpus:kelt.c:517 2025-06-27 07:53:20.091502 :AA3C8985:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:25:201] atxpop:kelt.c:517 2025-06-27 07:53:20.093884 :AA3C8986:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-27 07:53:28.791465 :AA3C8C35:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-27 07:53:28.780 2025-06-27 07:53:28.791467 :AA3C8C36:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13356 pso_num=25 pso_serial#=47 2025-06-27 07:53:28.791655 :AA3C8C37:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13356 osp_idx=23 osp_ver=170754 osp_pg=0 (spawn #327202) 2025-06-27 07:53:28.791656 :AA3C8C38:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 07:53:28.791656 :AA3C8C39:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=226 time=3986551969 2025-06-27 07:53:28.791657 :AA3C8C3A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 07:53:28.791658 :AA3C8C3B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 07:53:28.791658 :AA3C8C3C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 07:53:28.791658 :AA3C8C3D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 07:53:28.791659 :AA3C8C3E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 07:53:28.792153 :AA3C8C3F: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-06-27 07:53:58.840562 :AA3C95E8:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-27 07:54:28.870792 :AA3C9FC8:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-27 07:54:28.860 2025-06-27 07:54:28.870794 :AA3C9FC9:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15056 pso_num=25 pso_serial#=48 2025-06-27 07:54:28.870921 :AA3C9FCA:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15056 osp_idx=23 osp_ver=170755 osp_pg=0 (spawn #327204) 2025-06-27 07:54:28.870922 :AA3C9FCB:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 07:54:28.870922 :AA3C9FCC:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=227 time=3986612047 2025-06-27 07:54:28.870923 :AA3C9FCD:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 07:54:28.870923 :AA3C9FCE:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 07:54:28.870923 :AA3C9FCF:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 07:54:28.870924 :AA3C9FD0:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 07:54:28.870924 :AA3C9FD1:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 07:54:28.871432 :AA3C9FD2: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-06-27 07:54:53.882174 :AA3CA802: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-06-27 07:55:23.969847 :AA3CB1AC:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-27 07:55:28.925985 :AA3CB37C:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-27 07:55:28.914 2025-06-27 07:55:28.925987 :AA3CB37D:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17880 pso_num=25 pso_serial#=49 2025-06-27 07:55:28.926107 :AA3CB37E:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17880 osp_idx=23 osp_ver=170756 osp_pg=0 (spawn #327206) 2025-06-27 07:55:28.926108 :AA3CB37F:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 07:55:28.926108 :AA3CB380:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=228 time=3986672110 2025-06-27 07:55:28.926109 :AA3CB381:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 07:55:28.926109 :AA3CB382:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 07:55:28.926109 :AA3CB383:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 07:55:28.926110 :AA3CB384:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 07:55:28.926110 :AA3CB385:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 07:55:28.926653 :AA3CB386: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-06-27 07:55:59.025333 :AA3CBD19:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-27 07:56:29.008627 :AA3CC77B:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-27 07:56:28.998 2025-06-27 07:56:29.008629 :AA3CC77C:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14924 pso_num=25 pso_serial#=50 2025-06-27 07:56:29.008780 :AA3CC77D:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14924 osp_idx=23 osp_ver=170757 osp_pg=0 (spawn #327208) 2025-06-27 07:56:29.008781 :AA3CC77E:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 07:56:29.008781 :AA3CC77F:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=229 time=3986732188 2025-06-27 07:56:29.008782 :AA3CC780:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 07:56:29.008782 :AA3CC781:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 07:56:29.008782 :AA3CC782:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 07:56:29.008783 :AA3CC783:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 07:56:29.008783 :AA3CC784:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 07:56:29.009267 :AA3CC785: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-06-27 07:56:59.121959 :AA3CD238:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-27 07:57:29.089727 :AA3CDC11:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-27 07:57:29.079 2025-06-27 07:57:29.089728 :AA3CDC12:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15520 pso_num=25 pso_serial#=51 2025-06-27 07:57:29.089862 :AA3CDC13:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15520 osp_idx=23 osp_ver=170758 osp_pg=0 (spawn #327210) 2025-06-27 07:57:29.089863 :AA3CDC14:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 07:57:29.089863 :AA3CDC15:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=230 time=3986792282 2025-06-27 07:57:29.089864 :AA3CDC16:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 07:57:29.089864 :AA3CDC17:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 07:57:29.089864 :AA3CDC18:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 07:57:29.089865 :AA3CDC19:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 07:57:29.089865 :AA3CDC1A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 07:57:29.090343 :AA3CDC1B: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-06-27 07:57:59.124307 :AA3CE5E5:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-27 07:58:29.178267 :AA3CEF9F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-27 07:58:29.167 2025-06-27 07:58:29.178270 :AA3CEFA0:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15208 pso_num=25 pso_serial#=52 2025-06-27 07:58:29.178387 :AA3CEFA1:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15208 osp_idx=23 osp_ver=170759 osp_pg=0 (spawn #327212) 2025-06-27 07:58:29.178387 :AA3CEFA2:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 07:58:29.178388 :AA3CEFA3:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=231 time=3986852360 2025-06-27 07:58:29.178388 :AA3CEFA4:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 07:58:29.178389 :AA3CEFA5:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 07:58:29.178389 :AA3CEFA6:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 07:58:29.178389 :AA3CEFA7:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 07:58:29.178390 :AA3CEFA8:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 07:58:29.178861 :AA3CEFA9: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-06-27 07:58:59.240188 :AA3CF96B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-27 07:59:29.264495 :AA3D0334:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-27 07:59:29.253 2025-06-27 07:59:29.264497 :AA3D0335:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15932 pso_num=25 pso_serial#=53 2025-06-27 07:59:29.264634 :AA3D0336:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15932 osp_idx=23 osp_ver=170760 osp_pg=0 (spawn #327214) 2025-06-27 07:59:29.264635 :AA3D0337:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 07:59:29.264635 :AA3D0338:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=232 time=3986912454 2025-06-27 07:59:29.264636 :AA3D0339:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 07:59:29.264636 :AA3D033A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 07:59:29.264636 :AA3D033B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 07:59:29.264637 :AA3D033C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 07:59:29.264637 :AA3D033D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 07:59:29.265111 :AA3D033E: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-06-27 07:59:59.347592 :AA3D0D7D:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-27 08:00:22.043824 :AA3D151A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-27 08:00:22.034 2025-06-27 08:00:22.043826 :AA3D151B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=1312 pso_num=25 pso_serial#=54 2025-06-27 08:00:22.043960 :AA3D151C:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=1312 (legacy spawn) 2025-06-27 08:00:22.050378 :AA3D1521: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-06-27 08:00:22.050740 :AA3D1526: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-06-27 08:00:22.051543 :AA3D152B:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-27 08:00:22.262977 :AA3D156A:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-27 08:00:22.253 2025-06-27 08:00:22.262979 :AA3D156B:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17332 pso_num=25 pso_serial#=55 2025-06-27 08:00:22.263113 :AA3D156C:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17332 osp_idx=23 osp_ver=170762 osp_pg=0 (spawn #327216) 2025-06-27 08:00:22.263114 :AA3D156D:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 08:00:22.263114 :AA3D156E:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=233 time=3986965454 2025-06-27 08:00:22.263115 :AA3D156F:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 08:00:22.263115 :AA3D1570:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 08:00:22.263115 :AA3D1571:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 08:00:22.263116 :AA3D1572:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 08:00:22.263116 :AA3D1573:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 08:00:22.263604 :AA3D1574: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-06-27 08:00:22.263648 :AA3D157A: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-06-27 08:00:22.271486 :AA3D1588: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-06-27 08:00:22.272577 :AA3D1590: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-06-27 08:00:22.273201 :AA3D1598: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-06-27 08:00:22.273689 :AA3D15A0: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-06-27 08:00:22.274148 :AA3D15A8: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-06-27 08:00:22.276683 :AA3D15B0: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-06-27 08:00:22.277926 :AA3D15B8: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-06-27 08:00:22.329237 :AA3D15E0: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-06-27 08:00:22.330155 :AA3D15E8: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-06-27 08:00:22.330407 :AA3D15F0: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-06-27 08:00:22.330636 :AA3D15F8: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-06-27 08:00:22.332488 :AA3D1600:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-27 08:00:29.331134 :AA3D1881:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-27 08:00:29.320 2025-06-27 08:00:29.331137 :AA3D1882:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15492 pso_num=25 pso_serial#=56 2025-06-27 08:00:29.331256 :AA3D1883:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15492 osp_idx=23 osp_ver=170763 osp_pg=0 (spawn #327218) 2025-06-27 08:00:29.331257 :AA3D1884:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 08:00:29.331257 :AA3D1885:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=234 time=3986972516 2025-06-27 08:00:29.331257 :AA3D1886:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 08:00:29.331257 :AA3D1887:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 08:00:29.331258 :AA3D1888:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 08:00:29.331258 :AA3D1889:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 08:00:29.331258 :AA3D188A:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 08:00:29.331802 :AA3D188B: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-06-27 08:00:59.418557 :AA3D2340:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-27 08:01:29.436720 :AA3D2D45:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-27 08:01:29.426 2025-06-27 08:01:29.436722 :AA3D2D46:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=15184 pso_num=25 pso_serial#=57 2025-06-27 08:01:29.436855 :AA3D2D47:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=15184 osp_idx=23 osp_ver=170764 osp_pg=0 (spawn #327221) 2025-06-27 08:01:29.436856 :AA3D2D48:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 08:01:29.436856 :AA3D2D49:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=235 time=3987032625 2025-06-27 08:01:29.436857 :AA3D2D4A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 08:01:29.436857 :AA3D2D4B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 08:01:29.436857 :AA3D2D4C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 08:01:29.436858 :AA3D2D4D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 08:01:29.436858 :AA3D2D4E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 08:01:29.437342 :AA3D2D4F: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-06-27 08:01:59.494092 :AA3D3779:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-27 08:02:29.533277 :AA3D4155:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-27 08:02:29.522 2025-06-27 08:02:29.533279 :AA3D4156:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=16560 pso_num=25 pso_serial#=58 2025-06-27 08:02:29.533417 :AA3D4157:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=16560 osp_idx=23 osp_ver=170765 osp_pg=0 (spawn #327223) 2025-06-27 08:02:29.533418 :AA3D4158:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-27 08:02:29.533419 :AA3D4159:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=236 time=3987092719 2025-06-27 08:02:29.533419 :AA3D415A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-27 08:02:29.533419 :AA3D415B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-27 08:02:29.533419 :AA3D415C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-27 08:02:29.533420 :AA3D415D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-27 08:02:29.533420 :AA3D415E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-27 08:02:29.533890 :AA3D415F: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 25 (osid: 16560, J001)