Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250914220804\orcl_j002_19984_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:34796M/63366M, Ph+PgF:39418M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 19984, image: ORACLE.EXE (J002) *** 2025-09-14 22:08:04.612 *** SESSION ID:(225.63289) 2025-09-14 22:08:04.612 *** 2025-09-14 22:08:04.612 Process diagnostic dump for ORACLE.EXE (J002), OS id=19984, pid: 28, proc_ser: 130, sid: 225, sess_ser: 63289 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 19984 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=16 seq_num=17 snap_id=1 wait times: snap=0.490952 sec, exc=0.490952 sec, total=0.490952 sec wait times: max=0.500000 sec, heur=8.519981 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=15 seq_num=16 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.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 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.000012 sec of elapsed time 2: 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.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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.000011 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.500007 sec, exc=0.500007 sec, total=0.500007 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=10 seq_num=11 snap_id=1 wait times: snap=0.499975 sec, exc=0.499975 sec, total=0.499975 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000015 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.510162 sec, exc=0.510162 sec, total=0.510162 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=8 seq_num=9 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.000010 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.510104 sec, exc=0.510104 sec, total=0.510104 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000010 sec of elapsed time Sampled Session History of session 225 serial 63289 --------------------------------------------------- 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 --------------------------------------------------- [9 samples, 22:07:56 - 22:08:04] idle wait at each sample [session created at: 22:07:56] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-14 22:08:04.612 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 19984, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-14 22:03:20.410906 :CD79604A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:03:20.411802 :CD79604C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:03:53.565895 :CD796B08:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:03:53.551 2025-09-14 22:03:53.565897 :CD796B09:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24688 pso_num=28 pso_serial#=106 2025-09-14 22:03:53.566041 :CD796B0A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24688 (legacy spawn) 2025-09-14 22:03:53.572629 :CD796B0B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:03:53.572798 :CD796B10:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:03:53.573588 :CD796B15:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:03:53.590740 :CD796B18:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:03:53.583 2025-09-14 22:03:53.590742 :CD796B19:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25464 pso_num=28 pso_serial#=107 2025-09-14 22:03:53.590882 :CD796B1A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=25464 (legacy spawn) 2025-09-14 22:03:53.597507 :CD796B1B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:03:53.597677 :CD796B24:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:03:53.598521 :CD796B25:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:03:56.690335 :CD796C2D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:03:56.677 2025-09-14 22:03:56.690338 :CD796C2E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23788 pso_num=28 pso_serial#=108 2025-09-14 22:03:56.690518 :CD796C2F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23788 (legacy spawn) 2025-09-14 22:03:56.697106 :CD796C30:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:03:56.697285 :CD796C35:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:03:56.698084 :CD796C3A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:03:56.706928 :CD796C3D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:03:56.692 2025-09-14 22:03:56.706930 :CD796C3E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22152 pso_num=28 pso_serial#=109 2025-09-14 22:03:56.707074 :CD796C3F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22152 (legacy spawn) 2025-09-14 22:03:56.713782 :CD796C40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:03:56.713957 :CD796C48:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:03:56.714783 :CD796C4A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:04:17.049554 :CD7972BE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:04:17.043 2025-09-14 22:04:17.049557 :CD7972BF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23244 pso_num=28 pso_serial#=110 2025-09-14 22:04:17.049689 :CD7972C0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23244 (legacy spawn) 2025-09-14 22:04:17.056342 :CD7972C1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:04:17.056519 :CD7972C8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:04:17.057357 :CD7972CB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:04:21.523798 :CD7973FC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:04:21.511 2025-09-14 22:04:21.523800 :CD7973FD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21000 pso_num=28 pso_serial#=111 2025-09-14 22:04:21.523950 :CD7973FE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21000 (legacy spawn) 2025-09-14 22:04:21.530704 :CD7973FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:04:21.530884 :CD797404:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:04:21.531775 :CD797409:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:04:51.389885 :CD7980EC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:04:51.376 2025-09-14 22:04:51.389887 :CD7980ED:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20568 pso_num=28 pso_serial#=112 2025-09-14 22:04:51.390028 :CD7980EE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20568 (legacy spawn) 2025-09-14 22:04:51.396681 :CD7980EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:04:51.396857 :CD7980F4:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:04:51.397648 :CD7980F9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:04:51.422520 :CD7980FC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:04:51.407 2025-09-14 22:04:51.422522 :CD7980FD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24008 pso_num=28 pso_serial#=113 2025-09-14 22:04:51.422642 :CD7980FE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24008 (legacy spawn) 2025-09-14 22:04:51.429119 :CD7980FF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:04:51.429306 :CD798108:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:04:51.430115 :CD798109:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:04:52.588809 :CD7981F7:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:04:52.579 2025-09-14 22:04:52.588811 :CD7981F8:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23052 pso_num=28 pso_serial#=114 2025-09-14 22:04:52.588952 :CD7981F9:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23052 (legacy spawn) 2025-09-14 22:04:52.595736 :CD7981FA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:04:52.595902 :CD798203:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:04:52.596879 :CD798204:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:04:56.333576 :CD798322:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:04:56.324 2025-09-14 22:04:56.333578 :CD798323:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23336 pso_num=28 pso_serial#=115 2025-09-14 22:04:56.333722 :CD798324:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23336 (legacy spawn) 2025-09-14 22:04:56.340278 :CD798325:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:04:56.340454 :CD79832A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:04:56.341276 :CD79832F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:05:06.089574 :CD7985F1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:05:06.075 2025-09-14 22:05:06.089576 :CD7985F2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23756 pso_num=28 pso_serial#=116 2025-09-14 22:05:06.089718 :CD7985F3:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23756 (legacy spawn) 2025-09-14 22:05:06.096539 :CD79861C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:05:06.096726 :CD798621:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:05:06.097588 :CD798626:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:05:06.106714 :CD798629:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:05:06.106 2025-09-14 22:05:06.106717 :CD79862A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24252 pso_num=28 pso_serial#=117 2025-09-14 22:05:06.106869 :CD79862B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24252 (legacy spawn) 2025-09-14 22:05:06.113752 :CD79862C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:05:06.113928 :CD798635:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:05:06.114828 :CD798636:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:05:09.281605 :CD798760:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:05:09.268 2025-09-14 22:05:09.281606 :CD798761:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23796 pso_num=28 pso_serial#=118 2025-09-14 22:05:09.281747 :CD798762:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23796 (legacy spawn) 2025-09-14 22:05:09.288270 :CD798763:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:05:09.288446 :CD798768:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:05:09.289250 :CD79876D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:05:09.298025 :CD798770:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:05:09.283 2025-09-14 22:05:09.298028 :CD798771:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25052 pso_num=28 pso_serial#=119 2025-09-14 22:05:09.298170 :CD798772:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=25052 (legacy spawn) 2025-09-14 22:05:09.304711 :CD798773:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:05:09.304878 :CD79877A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:05:09.305687 :CD79877D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:05:54.809169 :CD7996E0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:05:54.800 2025-09-14 22:05:54.809171 :CD7996E1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24780 pso_num=28 pso_serial#=120 2025-09-14 22:05:54.809314 :CD7996E2:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24780 (legacy spawn) 2025-09-14 22:05:54.816249 :CD7996E3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:05:54.816446 :CD7996E8:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:05:54.817312 :CD7996ED:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:06:04.444652 :CD799F44:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:06:04.435 2025-09-14 22:06:04.444654 :CD799F45:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22692 pso_num=28 pso_serial#=121 2025-09-14 22:06:04.444807 :CD799F46:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22692 (legacy spawn) 2025-09-14 22:06:04.467823 :CD799F54:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:06:04.468004 :CD799F59:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:06:04.468919 :CD799F5E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:06:18.890814 :CD79A3E3:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:06:18.876 2025-09-14 22:06:18.890816 :CD79A3E4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23096 pso_num=28 pso_serial#=122 2025-09-14 22:06:18.890956 :CD79A3E5:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23096 (legacy spawn) 2025-09-14 22:06:18.909071 :CD79A3E6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:06:18.909237 :CD79A3EB:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:06:18.910072 :CD79A3F0:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:06:18.918979 :CD79A3F3:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:06:18.907 2025-09-14 22:06:18.918981 :CD79A3F4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22584 pso_num=28 pso_serial#=123 2025-09-14 22:06:18.919129 :CD79A3F5:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22584 (legacy spawn) 2025-09-14 22:06:18.925827 :CD79A3F6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:06:18.925994 :CD79A3FF:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:06:18.926789 :CD79A400:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:06:55.393832 :CD79B0BB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:06:55.378 2025-09-14 22:06:55.393834 :CD79B0BC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24648 pso_num=28 pso_serial#=124 2025-09-14 22:06:55.393977 :CD79B0BD:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24648 (legacy spawn) 2025-09-14 22:06:55.400885 :CD79B0BE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:06:55.401066 :CD79B0C3:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:06:55.401937 :CD79B0C8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:06:55.411363 :CD79B0CB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:06:55.409 2025-09-14 22:06:55.411365 :CD79B0CC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=24944 pso_num=28 pso_serial#=125 2025-09-14 22:06:55.411519 :CD79B0CD:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=24944 (legacy spawn) 2025-09-14 22:06:55.418456 :CD79B0CE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:06:55.418638 :CD79B0D7:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:06:55.419531 :CD79B0D8:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:06:58.835571 :CD79B259:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:06:58.821 2025-09-14 22:06:58.835574 :CD79B25A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22456 pso_num=28 pso_serial#=126 2025-09-14 22:06:58.835718 :CD79B25B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22456 (legacy spawn) 2025-09-14 22:06:58.842291 :CD79B25C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:06:58.842468 :CD79B265:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:06:58.843290 :CD79B266:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:06:58.852242 :CD79B269:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:06:58.852 2025-09-14 22:06:58.852244 :CD79B26A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=25420 pso_num=28 pso_serial#=127 2025-09-14 22:06:58.852384 :CD79B26B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=25420 (legacy spawn) 2025-09-14 22:06:58.858947 :CD79B26C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:06:58.859122 :CD79B274:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:06:58.859954 :CD79B276:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:07:12.630868 :CD79B677:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:07:12.622 2025-09-14 22:07:12.630870 :CD79B678:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=23932 pso_num=28 pso_serial#=128 2025-09-14 22:07:12.631009 :CD79B679:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=23932 (legacy spawn) 2025-09-14 22:07:12.657453 :CD79B686:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-14 22:07:12.657620 :CD79B68B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:07:12.658414 :CD79B690:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:07:55.894843 :CD79C515:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:07:55.889 2025-09-14 22:07:55.894845 :CD79C516:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22520 pso_num=28 pso_serial#=129 2025-09-14 22:07:55.894987 :CD79C517:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=22520 osp_idx=26 osp_ver=326322 osp_pg=0 (spawn #592534) 2025-09-14 22:07:55.894988 :CD79C518:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 22:07:55.894988 :CD79C519:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=197 time=2273327205 2025-09-14 22:07:55.894989 :CD79C51A:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 22:07:55.894989 :CD79C51B:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 22:07:55.894989 :CD79C51C:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 22:07:55.894990 :CD79C51D:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 22:07:55.894990 :CD79C51E:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 22:07:55.895510 :CD79C51F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-14 22:07:55.895555 :CD79C525:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] 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 22:07:55.899799 :CD79C53C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-14 22:07:55.899845 :CD79C53D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-14 22:07:55.900106 :CD79C53E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-14 22:07:55.900122 :CD79C53F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-14 22:07:55.900434 :CD79C540:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-14 22:07:55.900448 :CD79C541:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-14 22:07:55.900661 :CD79C542:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-14 22:07:55.900675 :CD79C543:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-14 22:07:55.900893 :CD79C544:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-14 22:07:55.900906 :CD79C545:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-14 22:07:55.901116 :CD79C546:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-09-14 22:07:55.901130 :CD79C547:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-09-14 22:07:55.903769 :CD79C57A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-09-14 22:07:56.097825 :CD79C72C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 09-14 22:07:56.092 2025-09-14 22:07:56.097826 :CD79C72D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19984 pso_num=28 pso_serial#=130 2025-09-14 22:07:56.098036 :CD79C72E:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=19984 osp_idx=26 osp_ver=326323 osp_pg=0 (spawn #592536) 2025-09-14 22:07:56.098037 :CD79C72F:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-14 22:07:56.098037 :CD79C730:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=198 time=2273327408 2025-09-14 22:07:56.098038 :CD79C731:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-09-14 22:07:56.098039 :CD79C732:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-09-14 22:07:56.098039 :CD79C733:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-09-14 22:07:56.098040 :CD79C734:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-14 22:07:56.098041 :CD79C735:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-14 22:07:56.098611 :CD79C73F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] 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 28 (osid: 19984, J002)