Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250817140052\orcl_j002_14012_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:35425M/63366M, Ph+PgF:40730M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 14012, image: ORACLE.EXE (J002) *** 2025-08-17 14:00:52.642 *** SESSION ID:(225.20599) 2025-08-17 14:00:52.642 *** 2025-08-17 14:00:52.642 Process diagnostic dump for ORACLE.EXE (J002), OS id=14012, pid: 28, proc_ser: 5, sid: 225, sess_ser: 20599 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 14012 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.065135 sec, exc=0.065135 sec, total=0.065135 sec wait times: max=0.500000 sec, heur=4.604105 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.000012 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.515011 sec, exc=0.515011 sec, total=0.515011 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000014 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 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.000017 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000021 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.510126 sec, exc=0.510126 sec, total=0.510126 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=4 seq_num=5 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.000007 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 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.000008 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 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=1 seq_num=2 snap_id=1 wait times: snap=0.499993 sec, exc=0.499993 sec, total=0.499993 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 8: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.513768 sec, exc=0.513768 sec, total=0.513768 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 225 serial 20599 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 14:00:48 - 14:00:52] idle wait at each sample [session created at: 14:00:48] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-17 14:00:52.642 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 14012, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-17 13:55:47.913174 :C0F5F691:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:55:48.039047 :C0F5F694:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:55:48.030 2025-08-17 13:55:48.039049 :C0F5F695:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21608 pso_num=28 pso_serial#=236 2025-08-17 13:55:48.039189 :C0F5F696:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21608 (legacy spawn) 2025-08-17 13:55:48.045640 :C0F5F697: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-08-17 13:55:48.045805 :C0F5F6A0: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-08-17 13:55:48.046623 :C0F5F6A1:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:55:50.993016 :C0F5F78E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:55:50.984 2025-08-17 13:55:50.993018 :C0F5F78F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21300 pso_num=28 pso_serial#=237 2025-08-17 13:55:50.993162 :C0F5F790:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21300 (legacy spawn) 2025-08-17 13:55:50.999783 :C0F5F791: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-08-17 13:55:50.999960 :C0F5F796: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-08-17 13:55:51.000790 :C0F5F79B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:55:51.009480 :C0F5F79E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:55:50.999 2025-08-17 13:55:51.009482 :C0F5F79F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22184 pso_num=28 pso_serial#=238 2025-08-17 13:55:51.009615 :C0F5F7A0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22184 (legacy spawn) 2025-08-17 13:55:51.016154 :C0F5F7A1: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-08-17 13:55:51.016333 :C0F5F7A9: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-08-17 13:55:51.017127 :C0F5F7AB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:56:50.217278 :C0F60B7F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:56:50.208 2025-08-17 13:56:50.217280 :C0F60B80:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=6744 pso_num=28 pso_serial#=239 2025-08-17 13:56:50.217413 :C0F60B81:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=6744 (legacy spawn) 2025-08-17 13:56:50.223841 :C0F60B82: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-08-17 13:56:50.224038 :C0F60B87: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-08-17 13:56:50.224816 :C0F60B8C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:56:55.336218 :C0F60D52:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:56:55.328 2025-08-17 13:56:55.336220 :C0F60D53:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21128 pso_num=28 pso_serial#=240 2025-08-17 13:56:55.336367 :C0F60D54:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21128 (legacy spawn) 2025-08-17 13:56:55.342908 :C0F60D55: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-08-17 13:56:55.343079 :C0F60D5E: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-08-17 13:56:55.343870 :C0F60D5F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:57:00.497082 :C0F60EE2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:57:00.482 2025-08-17 13:57:00.497084 :C0F60EE3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21840 pso_num=28 pso_serial#=241 2025-08-17 13:57:00.497232 :C0F60EE4:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21840 (legacy spawn) 2025-08-17 13:57:00.503987 :C0F60EE5: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-08-17 13:57:00.504176 :C0F60EEA: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-08-17 13:57:00.505031 :C0F60EEF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:57:00.514185 :C0F60EFD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:57:00.513 2025-08-17 13:57:00.514187 :C0F60EFE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7616 pso_num=28 pso_serial#=242 2025-08-17 13:57:00.514338 :C0F60EFF:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7616 (legacy spawn) 2025-08-17 13:57:00.521037 :C0F60F00: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-08-17 13:57:00.521212 :C0F60F09: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-08-17 13:57:00.522050 :C0F60F0A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:57:03.591231 :C0F60FF6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:57:03.578 2025-08-17 13:57:03.591233 :C0F60FF7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22444 pso_num=28 pso_serial#=243 2025-08-17 13:57:03.591384 :C0F60FF8:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22444 (legacy spawn) 2025-08-17 13:57:03.597925 :C0F60FF9: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-08-17 13:57:03.598106 :C0F60FFE: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-08-17 13:57:03.598896 :C0F61003:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:57:03.607596 :C0F61006:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:57:03.593 2025-08-17 13:57:03.607598 :C0F61007:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15292 pso_num=28 pso_serial#=244 2025-08-17 13:57:03.607732 :C0F61008:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15292 (legacy spawn) 2025-08-17 13:57:03.614218 :C0F61009: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-08-17 13:57:03.614384 :C0F61012: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-08-17 13:57:03.615203 :C0F61013:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:57:52.396771 :C0F61FC8:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:57:52.388 2025-08-17 13:57:52.396773 :C0F61FC9:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16692 pso_num=28 pso_serial#=245 2025-08-17 13:57:52.396912 :C0F61FCA:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16692 (legacy spawn) 2025-08-17 13:57:52.403368 :C0F61FCB: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-08-17 13:57:52.403404 :C0F61FCE: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-08-17 13:57:52.403560 :C0F61FD1: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-08-17 13:57:52.404383 :C0F61FE2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:58:03.634617 :C0F6234D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:58:03.625 2025-08-17 13:58:03.634619 :C0F6234E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=6512 pso_num=28 pso_serial#=246 2025-08-17 13:58:03.634759 :C0F6234F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=6512 (legacy spawn) 2025-08-17 13:58:03.641259 :C0F62350: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-08-17 13:58:03.641439 :C0F62355: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-08-17 13:58:03.642244 :C0F6235A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:58:49.398502 :C0F63275:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:58:49.384 2025-08-17 13:58:49.398504 :C0F63276:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21512 pso_num=28 pso_serial#=247 2025-08-17 13:58:49.398643 :C0F63277:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21512 (legacy spawn) 2025-08-17 13:58:49.405145 :C0F63278: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-08-17 13:58:49.405307 :C0F63280: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-08-17 13:58:49.406111 :C0F63282:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:58:49.414745 :C0F63285:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:58:49.399 2025-08-17 13:58:49.414747 :C0F63286:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21748 pso_num=28 pso_serial#=248 2025-08-17 13:58:49.414886 :C0F63287:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21748 (legacy spawn) 2025-08-17 13:58:49.421276 :C0F63288: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-08-17 13:58:49.421443 :C0F63291: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-08-17 13:58:49.422239 :C0F63292:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:58:52.604985 :C0F63377:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:58:52.592 2025-08-17 13:58:52.604987 :C0F63378:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22384 pso_num=28 pso_serial#=249 2025-08-17 13:58:52.605123 :C0F63379:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22384 (legacy spawn) 2025-08-17 13:58:52.611741 :C0F6337A: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-08-17 13:58:52.611913 :C0F6337F: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-08-17 13:58:52.612761 :C0F63384:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:58:52.621566 :C0F63387:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:58:52.607 2025-08-17 13:58:52.621568 :C0F63388:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21596 pso_num=28 pso_serial#=250 2025-08-17 13:58:52.621700 :C0F63389:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21596 (legacy spawn) 2025-08-17 13:58:52.628173 :C0F6338A: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-08-17 13:58:52.628352 :C0F63393: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-08-17 13:58:52.629191 :C0F63394:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:58:54.595028 :C0F63430:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:58:54.586 2025-08-17 13:58:54.595031 :C0F63431:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21800 pso_num=28 pso_serial#=251 2025-08-17 13:58:54.595277 :C0F63432:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21800 (legacy spawn) 2025-08-17 13:58:54.602352 :C0F63433: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-08-17 13:58:54.602536 :C0F63438: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-08-17 13:58:54.603477 :C0F6343D:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:59:11.736507 :C0F6395A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:59:11.728 2025-08-17 13:59:11.736509 :C0F6395B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20052 pso_num=28 pso_serial#=252 2025-08-17 13:59:11.736690 :C0F6395C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20052 (legacy spawn) 2025-08-17 13:59:11.743220 :C0F6395D: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-08-17 13:59:11.743396 :C0F63961: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-08-17 13:59:11.744224 :C0F63967:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:59:45.921029 :C0F644DC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:59:45.911 2025-08-17 13:59:45.921031 :C0F644DD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20984 pso_num=28 pso_serial#=253 2025-08-17 13:59:45.921167 :C0F644DE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20984 (legacy spawn) 2025-08-17 13:59:45.927611 :C0F644DF: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-08-17 13:59:45.927784 :C0F644E4: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-08-17 13:59:45.928589 :C0F644E9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 13:59:56.807229 :C0F648A3:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 13:59:56.799 2025-08-17 13:59:56.807231 :C0F648A4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20028 pso_num=28 pso_serial#=254 2025-08-17 13:59:56.807370 :C0F648A5:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20028 (legacy spawn) 2025-08-17 13:59:56.814101 :C0F648A6: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-08-17 13:59:56.814297 :C0F648AB: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-08-17 13:59:56.815181 :C0F648B0:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 14:00:02.497330 :C0F64A67:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 14:00:02.482 2025-08-17 14:00:02.497332 :C0F64A68:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20204 pso_num=28 pso_serial#=255 2025-08-17 14:00:02.497470 :C0F64A69:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20204 (legacy spawn) 2025-08-17 14:00:02.503862 :C0F64A6A: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-08-17 14:00:02.504037 :C0F64A6F: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-08-17 14:00:02.504841 :C0F64A74:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 14:00:02.513528 :C0F64A77:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 14:00:02.498 2025-08-17 14:00:02.513530 :C0F64A78:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21064 pso_num=28 pso_serial#=1 2025-08-17 14:00:02.513664 :C0F64A79:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21064 (legacy spawn) 2025-08-17 14:00:02.531407 :C0F64A7A: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-08-17 14:00:02.531571 :C0F64A83: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-08-17 14:00:02.532457 :C0F64A84:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 14:00:05.677992 :C0F64B89:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 14:00:05.664 2025-08-17 14:00:05.677993 :C0F64B8A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16800 pso_num=28 pso_serial#=2 2025-08-17 14:00:05.678131 :C0F64B8B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16800 (legacy spawn) 2025-08-17 14:00:05.684544 :C0F64BAF: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-08-17 14:00:05.684727 :C0F64BB4: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-08-17 14:00:05.685507 :C0F64BB9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 14:00:05.694311 :C0F64BBC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 14:00:05.680 2025-08-17 14:00:05.694313 :C0F64BBD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20264 pso_num=28 pso_serial#=3 2025-08-17 14:00:05.694463 :C0F64BBE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20264 (legacy spawn) 2025-08-17 14:00:05.700979 :C0F64BBF: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-08-17 14:00:05.701144 :C0F64BC8: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-08-17 14:00:05.701967 :C0F64BC9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 14:00:47.774370 :C0F65B77:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 14:00:47.769 2025-08-17 14:00:47.774372 :C0F65B78:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21724 pso_num=28 pso_serial#=4 2025-08-17 14:00:47.774510 :C0F65B79:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=21724 osp_idx=26 osp_ver=239497 osp_pg=0 (spawn #498047) 2025-08-17 14:00:47.774510 :C0F65B7A:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 14:00:47.774511 :C0F65B7B:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=248 time=4119917079 2025-08-17 14:00:47.774511 :C0F65B7C:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 14:00:47.774511 :C0F65B7D:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 14:00:47.774512 :C0F65B7E:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 14:00:47.774512 :C0F65B7F:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 14:00:47.774512 :C0F65B80:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 14:00:47.775015 :C0F65B81: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-08-17 14:00:47.775059 :C0F65B87: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-08-17 14:00:47.779201 :C0F65B99:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-17 14:00:47.779246 :C0F65B9A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-17 14:00:47.779499 :C0F65B9B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-17 14:00:47.779515 :C0F65B9C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-17 14:00:47.779725 :C0F65B9D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-17 14:00:47.779738 :C0F65B9E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-17 14:00:47.779949 :C0F65B9F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-17 14:00:47.779961 :C0F65BA0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-17 14:00:47.780174 :C0F65BA1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-17 14:00:47.780187 :C0F65BA2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-17 14:00:47.780390 :C0F65BA3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-17 14:00:47.780404 :C0F65BA4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-17 14:00:47.783033 :C0F65BD2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-17 14:00:48.040252 :C0F65FDC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-17 14:00:48.039 2025-08-17 14:00:48.040253 :C0F65FDD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14012 pso_num=28 pso_serial#=5 2025-08-17 14:00:48.040386 :C0F65FDE:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=14012 osp_idx=26 osp_ver=239498 osp_pg=0 (spawn #498049) 2025-08-17 14:00:48.040388 :C0F65FDF:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-17 14:00:48.040388 :C0F65FE0:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=249 time=4119917345 2025-08-17 14:00:48.040389 :C0F65FE1:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-17 14:00:48.040389 :C0F65FE2:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-17 14:00:48.040389 :C0F65FE3:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-17 14:00:48.040390 :C0F65FE4:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-17 14:00:48.040390 :C0F65FE5:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-17 14:00:48.040887 :C0F65FEF: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: 14012, J002)