Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250803100814\orcl_j002_3088_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:35790M/63366M, Ph+PgF:41438M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 3088, image: ORACLE.EXE (J002) *** 2025-08-03 10:08:14.606 *** SESSION ID:(225.65027) 2025-08-03 10:08:14.606 *** 2025-08-03 10:08:14.606 Process diagnostic dump for ORACLE.EXE (J002), OS id=3088, pid: 28, proc_ser: 68, sid: 225, sess_ser: 65027 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 3088 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.052762 sec, exc=0.052762 sec, total=0.052762 sec wait times: max=0.500000 sec, heur=6.049559 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.000018 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499969 sec, exc=0.499969 sec, total=0.499969 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 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.000016 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.502653 sec, exc=0.502653 sec, total=0.502653 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.500013 sec, exc=0.500013 sec, total=0.500013 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.499974 sec, exc=0.499974 sec, total=0.499974 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.500135 sec, exc=0.500135 sec, total=0.500135 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=5 seq_num=6 snap_id=1 wait times: snap=0.499980 sec, exc=0.499980 sec, total=0.499980 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.500009 sec, exc=0.500009 sec, total=0.500009 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=3 seq_num=4 snap_id=1 wait times: snap=0.499955 sec, exc=0.499955 sec, total=0.499955 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000018 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=2 seq_num=3 snap_id=1 wait times: snap=0.500031 sec, exc=0.500031 sec, total=0.500031 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time Sampled Session History of session 225 serial 65027 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [7 samples, 10:08:08 - 10:08:14] idle wait at each sample [session created at: 10:08:08] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-03 10:08:14.606 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 3088, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-03 10:04:05.305012 :BAB9EC4F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:04:05.322561 :BAB9EC52:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:04:05.314 2025-08-03 10:04:05.322563 :BAB9EC53:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=5028 pso_num=28 pso_serial#=44 2025-08-03 10:04:05.322700 :BAB9EC54:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=5028 (legacy spawn) 2025-08-03 10:04:05.329240 :BAB9EC55: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-03 10:04:05.329268 :BAB9EC58: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-03 10:04:05.329407 :BAB9EC5F: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-03 10:04:05.330232 :BAB9EC6C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:04:08.393953 :BAB9ED6D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:04:08.381 2025-08-03 10:04:08.393956 :BAB9ED6E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=636 pso_num=28 pso_serial#=45 2025-08-03 10:04:08.394099 :BAB9ED6F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=636 (legacy spawn) 2025-08-03 10:04:08.400703 :BAB9ED7C: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-03 10:04:08.400875 :BAB9ED81: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-03 10:04:08.401691 :BAB9ED86:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:04:08.410331 :BAB9ED89:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:04:08.397 2025-08-03 10:04:08.410333 :BAB9ED8A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20060 pso_num=28 pso_serial#=46 2025-08-03 10:04:08.410468 :BAB9ED8B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20060 (legacy spawn) 2025-08-03 10:04:08.416932 :BAB9ED8C: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-03 10:04:08.417092 :BAB9ED95: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-03 10:04:08.417875 :BAB9ED96:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:04:10.042020 :BAB9EDDE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:04:10.032 2025-08-03 10:04:10.042023 :BAB9EDDF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11712 pso_num=28 pso_serial#=47 2025-08-03 10:04:10.042163 :BAB9EDE0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11712 (legacy spawn) 2025-08-03 10:04:10.048690 :BAB9EDE1: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-03 10:04:10.048881 :BAB9EDE6: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-03 10:04:10.049611 :BAB9EDEB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:04:16.831828 :BAB9F00E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:04:16.824 2025-08-03 10:04:16.831830 :BAB9F00F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17920 pso_num=28 pso_serial#=48 2025-08-03 10:04:16.831972 :BAB9F010:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17920 (legacy spawn) 2025-08-03 10:04:16.838476 :BAB9F011: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-03 10:04:16.838645 :BAB9F016: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-03 10:04:16.839435 :BAB9F01B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:05:17.678435 :BABA04AC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:05:17.664 2025-08-03 10:05:17.678437 :BABA04AD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14460 pso_num=28 pso_serial#=49 2025-08-03 10:05:17.678575 :BABA04AE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14460 (legacy spawn) 2025-08-03 10:05:17.685027 :BABA04AF: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-03 10:05:17.685206 :BABA04B4: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-03 10:05:17.686011 :BABA04B9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:05:17.703552 :BABA04BC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:05:17.695 2025-08-03 10:05:17.703554 :BABA04BD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7996 pso_num=28 pso_serial#=50 2025-08-03 10:05:17.703687 :BABA04BE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7996 (legacy spawn) 2025-08-03 10:05:17.710187 :BABA04BF: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-03 10:05:17.710381 :BABA04C8: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-03 10:05:17.711250 :BABA04C9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:05:18.334102 :BABA04DC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:05:18.325 2025-08-03 10:05:18.334104 :BABA04DD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14192 pso_num=28 pso_serial#=51 2025-08-03 10:05:18.334242 :BABA04DE:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14192 (legacy spawn) 2025-08-03 10:05:18.340699 :BABA04DF: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-03 10:05:18.340892 :BABA04F3: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-03 10:05:18.341714 :BABA04F4:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:05:19.021224 :BABA0517:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:05:19.012 2025-08-03 10:05:19.021226 :BABA0518:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8984 pso_num=28 pso_serial#=52 2025-08-03 10:05:19.021366 :BABA0519:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8984 (legacy spawn) 2025-08-03 10:05:19.027847 :BABA051A: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-03 10:05:19.028033 :BABA051F: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-03 10:05:19.028866 :BABA0524:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:05:20.745447 :BABA05E3:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:05:20.731 2025-08-03 10:05:20.745449 :BABA05E4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19760 pso_num=28 pso_serial#=53 2025-08-03 10:05:20.745589 :BABA05E5:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19760 (legacy spawn) 2025-08-03 10:05:20.753083 :BABA05E6: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-03 10:05:20.753289 :BABA05EB: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-03 10:05:20.754332 :BABA05F0:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:05:20.764295 :BABA05F3:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:05:20.762 2025-08-03 10:05:20.764297 :BABA05F4:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19940 pso_num=28 pso_serial#=54 2025-08-03 10:05:20.764438 :BABA05F5:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19940 (legacy spawn) 2025-08-03 10:05:20.771543 :BABA05F6: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-03 10:05:20.771739 :BABA05FF: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-03 10:05:20.772661 :BABA0600:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:05:25.600599 :BABA0768:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:05:25.588 2025-08-03 10:05:25.600601 :BABA0769:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=2648 pso_num=28 pso_serial#=55 2025-08-03 10:05:25.600738 :BABA076A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=2648 (legacy spawn) 2025-08-03 10:05:25.607299 :BABA076B: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-03 10:05:25.607486 :BABA0770: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-03 10:05:25.608302 :BABA0775:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:05:25.616950 :BABA0778:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:05:25.603 2025-08-03 10:05:25.616952 :BABA0779:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9288 pso_num=28 pso_serial#=56 2025-08-03 10:05:25.617081 :BABA077A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9288 (legacy spawn) 2025-08-03 10:05:25.623706 :BABA0787: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-03 10:05:25.623873 :BABA0790: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-03 10:05:25.624692 :BABA0791:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:05:57.189181 :BABA184E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:05:57.176 2025-08-03 10:05:57.189182 :BABA184F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15704 pso_num=28 pso_serial#=57 2025-08-03 10:05:57.189323 :BABA1850:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15704 (legacy spawn) 2025-08-03 10:05:57.195819 :BABA1851: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-03 10:05:57.195996 :BABA1856: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-03 10:05:57.196866 :BABA185B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:05:57.213833 :BABA185E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:05:57.207 2025-08-03 10:05:57.213836 :BABA185F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18796 pso_num=28 pso_serial#=58 2025-08-03 10:05:57.213973 :BABA1860:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18796 (legacy spawn) 2025-08-03 10:05:57.220439 :BABA1861: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-03 10:05:57.220606 :BABA186A: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-03 10:05:57.221416 :BABA186B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:06:21.229018 :BABA20C6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:06:21.221 2025-08-03 10:06:21.229020 :BABA20C7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18500 pso_num=28 pso_serial#=59 2025-08-03 10:06:21.229155 :BABA20C8:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18500 (legacy spawn) 2025-08-03 10:06:21.235647 :BABA20C9: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-03 10:06:21.235819 :BABA20CE: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-03 10:06:21.236626 :BABA20D3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:07:00.440054 :BABA2EC0:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:07:00.430 2025-08-03 10:07:00.440056 :BABA2EC1:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8860 pso_num=28 pso_serial#=60 2025-08-03 10:07:00.440203 :BABA2EC2:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8860 (legacy spawn) 2025-08-03 10:07:00.446728 :BABA2EC3: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-03 10:07:00.446897 :BABA2EC8: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-03 10:07:00.447690 :BABA2ECD:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:07:06.801666 :BABA30D5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:07:06.801 2025-08-03 10:07:06.801668 :BABA30D6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20360 pso_num=28 pso_serial#=61 2025-08-03 10:07:06.801810 :BABA30D7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20360 (legacy spawn) 2025-08-03 10:07:06.808779 :BABA30D8: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-03 10:07:06.808984 :BABA30E1: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-03 10:07:06.809917 :BABA30E2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:07:06.819592 :BABA30E5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:07:06.817 2025-08-03 10:07:06.819594 :BABA30E6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18468 pso_num=28 pso_serial#=62 2025-08-03 10:07:06.819736 :BABA30E7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18468 (legacy spawn) 2025-08-03 10:07:06.826718 :BABA30E8: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-03 10:07:06.826903 :BABA30F1: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-03 10:07:06.827891 :BABA30F2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:07:10.018099 :BABA31E1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:07:10.005 2025-08-03 10:07:10.018101 :BABA31E2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7532 pso_num=28 pso_serial#=63 2025-08-03 10:07:10.018237 :BABA31E3:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7532 (legacy spawn) 2025-08-03 10:07:10.024750 :BABA31E4: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-03 10:07:10.024913 :BABA31ED: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-03 10:07:10.025727 :BABA31EE:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:07:10.043574 :BABA31F1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:07:10.036 2025-08-03 10:07:10.043576 :BABA31F2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10992 pso_num=28 pso_serial#=64 2025-08-03 10:07:10.043707 :BABA31F3:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10992 (legacy spawn) 2025-08-03 10:07:10.050191 :BABA31F4: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-03 10:07:10.050353 :BABA31FC: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-03 10:07:10.051164 :BABA31FD:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:07:23.401345 :BABA363D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:07:23.393 2025-08-03 10:07:23.401347 :BABA363E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18688 pso_num=28 pso_serial#=65 2025-08-03 10:07:23.401487 :BABA363F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18688 (legacy spawn) 2025-08-03 10:07:23.407996 :BABA3640: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-03 10:07:23.408170 :BABA3645: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-03 10:07:23.409028 :BABA364A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:08:04.090940 :BABA43CA:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:08:04.082 2025-08-03 10:08:04.090943 :BABA43CB:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10824 pso_num=28 pso_serial#=66 2025-08-03 10:08:04.091079 :BABA43CC:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10824 (legacy spawn) 2025-08-03 10:08:04.097564 :BABA43CD: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-03 10:08:04.097733 :BABA43D6: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-03 10:08:04.098543 :BABA43E3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:08:08.357370 :BABA454B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:08:08.352 2025-08-03 10:08:08.357372 :BABA454C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20408 pso_num=28 pso_serial#=67 2025-08-03 10:08:08.357509 :BABA454D:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=20408 osp_idx=26 osp_ver=196210 osp_pg=0 (spawn #450842) 2025-08-03 10:08:08.357510 :BABA454E: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-03 10:08:08.357511 :BABA454F:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=207 time=2896357658 2025-08-03 10:08:08.357511 :BABA4550:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 10:08:08.357512 :BABA4551:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 10:08:08.357512 :BABA4552:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 10:08:08.357512 :BABA4553:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 10:08:08.357513 :BABA4554:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 10:08:08.358017 :BABA4555: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-03 10:08:08.358065 :BABA455B: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-03 10:08:08.362193 :BABA456D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-03 10:08:08.362241 :BABA456E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-03 10:08:08.362495 :BABA456F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-03 10:08:08.362510 :BABA4570:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-03 10:08:08.362725 :BABA4571:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-03 10:08:08.362739 :BABA4572:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-03 10:08:08.362951 :BABA4573:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-03 10:08:08.362964 :BABA4574:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-03 10:08:08.363167 :BABA4575:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-03 10:08:08.363180 :BABA4576:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-03 10:08:08.363387 :BABA4577:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-03 10:08:08.363400 :BABA4578:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-03 10:08:08.365936 :BABA45A6:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-03 10:08:08.560695 :BABA49A8:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-03 10:08:08.556 2025-08-03 10:08:08.560696 :BABA49A9:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=3088 pso_num=28 pso_serial#=68 2025-08-03 10:08:08.560831 :BABA49AA:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=3088 osp_idx=26 osp_ver=196211 osp_pg=0 (spawn #450844) 2025-08-03 10:08:08.560832 :BABA49AB: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-03 10:08:08.560833 :BABA49AC:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=208 time=2896357861 2025-08-03 10:08:08.560833 :BABA49AD:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-03 10:08:08.560834 :BABA49AE:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-03 10:08:08.560834 :BABA49AF:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-03 10:08:08.560834 :BABA49B0:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-03 10:08:08.560835 :BABA49B1:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-03 10:08:08.561328 :BABA49BB: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: 3088, J002)