Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250607140511\orcl_j002_15108_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:36832M/63366M, Ph+PgF:43499M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 15108, image: ORACLE.EXE (J002) *** 2025-06-07 14:05:12.055 *** SESSION ID:(225.39195) 2025-06-07 14:05:12.055 *** 2025-06-07 14:05:12.055 Process diagnostic dump for ORACLE.EXE (J002), OS id=15108, pid: 28, proc_ser: 157, sid: 225, sess_ser: 39195 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15108 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.035643 sec, exc=0.035643 sec, total=0.035643 sec wait times: max=0.500000 sec, heur=7.549523 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.000007 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 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=13 seq_num=14 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.000009 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 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 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.510103 sec, exc=0.510103 sec, total=0.510103 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.499987 sec, exc=0.499987 sec, total=0.499987 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499992 sec, exc=0.499992 sec, total=0.499992 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500003 sec, exc=0.500003 sec, total=0.500003 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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.000013 sec of elapsed time Sampled Session History of session 225 serial 39195 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 14:05:04 - 14:05:12] idle wait at each sample [session created at: 14:05:04] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-07 14:05:12.055 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 15108, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-07 14:01:17.057593 :A189D861:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:01:17.055 2025-06-07 14:01:17.057595 :A189D862:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15832 pso_num=28 pso_serial#=133 2025-06-07 14:01:17.057732 :A189D863:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15832 (legacy spawn) 2025-06-07 14:01:17.064232 :A189D864: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-06-07 14:01:17.064394 :A189D86D: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-06-07 14:01:17.065191 :A189D86E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:01:17.579782 :A189D871:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:01:17.571 2025-06-07 14:01:17.579784 :A189D872:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12880 pso_num=28 pso_serial#=134 2025-06-07 14:01:17.579927 :A189D873:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12880 (legacy spawn) 2025-06-07 14:01:17.939040 :A189D880: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-06-07 14:01:17.939224 :A189D885: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-06-07 14:01:17.940044 :A189D88A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:01:28.941182 :A189DC43:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:01:28.933 2025-06-07 14:01:28.941184 :A189DC44:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13024 pso_num=28 pso_serial#=135 2025-06-07 14:01:28.941331 :A189DC45:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13024 (legacy spawn) 2025-06-07 14:01:28.947896 :A189DC46: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-06-07 14:01:28.947927 :A189DC49: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-06-07 14:01:28.948068 :A189DC50: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-06-07 14:01:28.948887 :A189DC5D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:02:00.025238 :A189E679:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:02:00.017 2025-06-07 14:02:00.025240 :A189E67A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15904 pso_num=28 pso_serial#=136 2025-06-07 14:02:00.025373 :A189E67B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15904 (legacy spawn) 2025-06-07 14:02:00.031815 :A189E67C: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-06-07 14:02:00.031986 :A189E682: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-06-07 14:02:00.032787 :A189E686:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:02:22.042939 :A189ED98:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:02:22.043 2025-06-07 14:02:22.042942 :A189ED99:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14064 pso_num=28 pso_serial#=137 2025-06-07 14:02:22.043076 :A189ED9A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14064 (legacy spawn) 2025-06-07 14:02:22.049464 :A189ED9B: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-06-07 14:02:22.049635 :A189EDA0: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-06-07 14:02:22.050463 :A189EDA5:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:02:22.059134 :A189EDA8:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:02:22.058 2025-06-07 14:02:22.059136 :A189EDA9:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14600 pso_num=28 pso_serial#=138 2025-06-07 14:02:22.059278 :A189EDAA:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14600 (legacy spawn) 2025-06-07 14:02:22.065698 :A189EDAB: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-06-07 14:02:22.065859 :A189EDB4: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-06-07 14:02:22.066652 :A189EDB5:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:02:25.157632 :A189EED4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:02:25.144 2025-06-07 14:02:25.157634 :A189EED5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14436 pso_num=28 pso_serial#=139 2025-06-07 14:02:25.157766 :A189EED6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14436 (legacy spawn) 2025-06-07 14:02:25.164227 :A189EED7: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-06-07 14:02:25.164394 :A189EEDC: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-06-07 14:02:25.165267 :A189EEE1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:02:25.183521 :A189EEE4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:02:25.176 2025-06-07 14:02:25.183523 :A189EEE5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16380 pso_num=28 pso_serial#=140 2025-06-07 14:02:25.183659 :A189EEE6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16380 (legacy spawn) 2025-06-07 14:02:25.190108 :A189EEE7: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-06-07 14:02:25.190270 :A189EEF0: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-06-07 14:02:25.191121 :A189EEF1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:02:25.481918 :A189EEF4:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:02:25.473 2025-06-07 14:02:25.481920 :A189EEF5:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15152 pso_num=28 pso_serial#=141 2025-06-07 14:02:25.482057 :A189EEF6:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15152 (legacy spawn) 2025-06-07 14:02:25.488533 :A189EF0B: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-06-07 14:02:25.488646 :A189EF28: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-06-07 14:02:25.489441 :A189EF29:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:02:59.880406 :A189FB6D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:02:59.871 2025-06-07 14:02:59.880407 :A189FB6E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16164 pso_num=28 pso_serial#=142 2025-06-07 14:02:59.880546 :A189FB6F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16164 (legacy spawn) 2025-06-07 14:02:59.887028 :A189FB70: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-06-07 14:02:59.887279 :A189FB75: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-06-07 14:02:59.888380 :A189FB7A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:03:01.637820 :A189FC42:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:03:01.625 2025-06-07 14:03:01.637822 :A189FC43:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15192 pso_num=28 pso_serial#=143 2025-06-07 14:03:01.637959 :A189FC44:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15192 (legacy spawn) 2025-06-07 14:03:01.644388 :A189FC45: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-06-07 14:03:01.644563 :A189FC4A: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-06-07 14:03:01.645366 :A189FC4F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:03:01.654038 :A189FC52:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:03:01.640 2025-06-07 14:03:01.654041 :A189FC53:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14908 pso_num=28 pso_serial#=144 2025-06-07 14:03:01.654173 :A189FC54:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14908 (legacy spawn) 2025-06-07 14:03:01.660604 :A189FC55: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-06-07 14:03:01.660762 :A189FC5E: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-06-07 14:03:01.661556 :A189FC5F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:03:02.195416 :A189FC80:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:03:02.187 2025-06-07 14:03:02.195419 :A189FC81:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13400 pso_num=28 pso_serial#=145 2025-06-07 14:03:02.195553 :A189FC82:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13400 (legacy spawn) 2025-06-07 14:03:02.202023 :A189FC83: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-06-07 14:03:02.202196 :A189FC8C: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-06-07 14:03:02.203079 :A189FC8D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:03:34.956898 :A18A0743:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:03:34.942 2025-06-07 14:03:34.956901 :A18A0744:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11816 pso_num=28 pso_serial#=146 2025-06-07 14:03:34.957045 :A18A0745:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11816 (legacy spawn) 2025-06-07 14:03:34.963476 :A18A0746: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-06-07 14:03:34.963645 :A18A074F: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-06-07 14:03:34.964437 :A18A0750:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:03:34.973039 :A18A0753:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:03:34.958 2025-06-07 14:03:34.973041 :A18A0754:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13748 pso_num=28 pso_serial#=147 2025-06-07 14:03:34.973173 :A18A0755:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13748 (legacy spawn) 2025-06-07 14:03:34.979744 :A18A0756: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-06-07 14:03:34.979917 :A18A075E: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-06-07 14:03:34.980783 :A18A0760:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:03:38.035246 :A18A0847:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:03:38.022 2025-06-07 14:03:38.035248 :A18A0848:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16296 pso_num=28 pso_serial#=148 2025-06-07 14:03:38.035382 :A18A0849:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16296 (legacy spawn) 2025-06-07 14:03:38.041863 :A18A084A: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-06-07 14:03:38.042034 :A18A084F: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-06-07 14:03:38.042811 :A18A0854:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:03:38.051407 :A18A0857:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:03:38.038 2025-06-07 14:03:38.051409 :A18A0858:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11704 pso_num=28 pso_serial#=149 2025-06-07 14:03:38.051543 :A18A0859:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11704 (legacy spawn) 2025-06-07 14:03:38.058013 :A18A085A: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-06-07 14:03:38.058188 :A18A0863: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-06-07 14:03:38.058981 :A18A0864:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:04:04.370663 :A18A10DA:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:04:04.363 2025-06-07 14:04:04.370665 :A18A10DB:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14144 pso_num=28 pso_serial#=150 2025-06-07 14:04:04.370803 :A18A10DC:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14144 (legacy spawn) 2025-06-07 14:04:04.377277 :A18A10DD: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-06-07 14:04:04.377449 :A18A10E6: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-06-07 14:04:04.378286 :A18A10E7:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:04:07.795541 :A18A11CE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:04:07.786 2025-06-07 14:04:07.795543 :A18A11CF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9112 pso_num=28 pso_serial#=151 2025-06-07 14:04:07.795680 :A18A11D0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9112 (legacy spawn) 2025-06-07 14:04:07.802105 :A18A11E5: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-06-07 14:04:07.802235 :A18A11EA: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-06-07 14:04:07.803047 :A18A1204:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:04:11.349507 :A18A1319:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:04:11.335 2025-06-07 14:04:11.349509 :A18A131A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16172 pso_num=28 pso_serial#=152 2025-06-07 14:04:11.349644 :A18A131B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16172 (legacy spawn) 2025-06-07 14:04:11.356085 :A18A131C: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-06-07 14:04:11.356258 :A18A1323: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-06-07 14:04:11.357053 :A18A1326:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:04:11.365704 :A18A1329:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:04:11.351 2025-06-07 14:04:11.365706 :A18A132A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15104 pso_num=28 pso_serial#=153 2025-06-07 14:04:11.365836 :A18A132B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15104 (legacy spawn) 2025-06-07 14:04:11.372295 :A18A132C: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-06-07 14:04:11.372459 :A18A1335: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-06-07 14:04:11.373304 :A18A1336:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:04:14.648611 :A18A1428:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:04:14.640 2025-06-07 14:04:14.648613 :A18A1429:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13356 pso_num=28 pso_serial#=154 2025-06-07 14:04:14.648757 :A18A142A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13356 (legacy spawn) 2025-06-07 14:04:14.655295 :A18A142B: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-06-07 14:04:14.655476 :A18A1434: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-06-07 14:04:14.656278 :A18A1435:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:04:14.665111 :A18A1438:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:04:14.656 2025-06-07 14:04:14.665113 :A18A1439:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15860 pso_num=28 pso_serial#=155 2025-06-07 14:04:14.665299 :A18A143A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15860 (legacy spawn) 2025-06-07 14:04:14.671804 :A18A143B: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-06-07 14:04:14.671972 :A18A1443: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-06-07 14:04:14.672829 :A18A1445:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:05:04.305621 :A18A245A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:05:04.300 2025-06-07 14:05:04.305623 :A18A245B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14460 pso_num=28 pso_serial#=156 2025-06-07 14:05:04.305760 :A18A245C:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=14460 osp_idx=26 osp_ver=183294 osp_pg=0 (spawn #261372) 2025-06-07 14:05:04.305760 :A18A245D:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 14:05:04.305761 :A18A245E:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=178 time=2280862954 2025-06-07 14:05:04.305761 :A18A245F:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 14:05:04.305762 :A18A2460:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 14:05:04.305762 :A18A2461:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 14:05:04.305762 :A18A2462:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 14:05:04.305763 :A18A2463:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 14:05:04.306273 :A18A2464: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-06-07 14:05:04.306318 :A18A246A: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-06-07 14:05:04.310472 :A18A2481:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-07 14:05:04.310514 :A18A2482:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-07 14:05:04.310784 :A18A2483:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-07 14:05:04.310802 :A18A2484:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-07 14:05:04.311028 :A18A2485:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-07 14:05:04.311041 :A18A2486:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-07 14:05:04.311250 :A18A2487:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-07 14:05:04.311263 :A18A2488:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-07 14:05:04.311470 :A18A2489:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-07 14:05:04.311482 :A18A248A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-07 14:05:04.311690 :A18A248B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-07 14:05:04.311703 :A18A248C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-07 14:05:04.313937 :A18A24BF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 14:05:04.508830 :A18A2917:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 14:05:04.503 2025-06-07 14:05:04.508832 :A18A2918:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15108 pso_num=28 pso_serial#=157 2025-06-07 14:05:04.508966 :A18A2919:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=15108 osp_idx=26 osp_ver=183295 osp_pg=0 (spawn #261374) 2025-06-07 14:05:04.508967 :A18A291A:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 14:05:04.508968 :A18A291B:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=179 time=2280863157 2025-06-07 14:05:04.508968 :A18A291C:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 14:05:04.508969 :A18A291D:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 14:05:04.508969 :A18A291E:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 14:05:04.508970 :A18A291F:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 14:05:04.508970 :A18A2920:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 14:05:04.509468 :A18A2921: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: 15108, J002)