Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250614220630\orcl_j002_13620_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:36654M/63366M, Ph+PgF:43101M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 13620, image: ORACLE.EXE (J002) *** 2025-06-14 22:06:30.906 *** SESSION ID:(219.6167) 2025-06-14 22:06:30.906 *** 2025-06-14 22:06:30.906 Process diagnostic dump for ORACLE.EXE (J002), OS id=13620, pid: 27, proc_ser: 231, sid: 219, sess_ser: 6167 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 13620 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=13 seq_num=14 snap_id=1 wait times: snap=0.294520 sec, exc=0.294520 sec, total=0.294520 sec wait times: max=0.500000 sec, heur=6.794296 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=12 seq_num=13 snap_id=1 wait times: snap=0.500160 sec, exc=0.500160 sec, total=0.500160 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.499996 sec, exc=0.499996 sec, total=0.499996 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000010 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 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 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 snap_id=1 wait times: snap=0.504593 sec, exc=0.504593 sec, total=0.504593 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=8 seq_num=9 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.000009 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=7 seq_num=8 snap_id=1 wait times: snap=0.500002 sec, exc=0.500002 sec, total=0.500002 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 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.000009 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 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=4 seq_num=5 snap_id=1 wait times: snap=0.499988 sec, exc=0.499988 sec, total=0.499988 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.501098 sec, exc=0.501098 sec, total=0.501098 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000006 sec of elapsed time Sampled Session History of session 219 serial 6167 --------------------------------------------------- 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, 22:06:24 - 22:06:30] idle wait at each sample [session created at: 22:06:24] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-14 22:06:30.906 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 13620, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-14 22:01:23.329331 :A4BE1F76:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 22:01:23.329331 :A4BE1F77:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 22:01:23.329332 :A4BE1F78:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 22:01:23.329332 :A4BE1F79:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 22:01:23.329816 :A4BE1F7A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 22:01:23.329862 :A4BE1F80:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:01:23.350200 :A4BE1FCD:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:02:19.502506 :A4BE31EF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:02:19.494 2025-06-14 22:02:19.502508 :A4BE31F0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17244 pso_num=27 pso_serial#=208 2025-06-14 22:02:19.502652 :A4BE31F1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17244 (legacy spawn) 2025-06-14 22:02:19.509533 :A4BE31F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:02:19.509575 :A4BE31F5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:02:19.509752 :A4BE31F8:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:02:19.510758 :A4BE3209:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:02:19.519971 :A4BE320C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:02:19.510 2025-06-14 22:02:19.519974 :A4BE320D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17088 pso_num=27 pso_serial#=209 2025-06-14 22:02:19.520121 :A4BE320E:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17088 (legacy spawn) 2025-06-14 22:02:19.526930 :A4BE320F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:02:19.527117 :A4BE3218:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:02:19.528001 :A4BE3219:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:02:21.997284 :A4BE32B8:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:02:21.989 2025-06-14 22:02:21.997286 :A4BE32B9:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11704 pso_num=27 pso_serial#=210 2025-06-14 22:02:21.997432 :A4BE32BA:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11704 (legacy spawn) 2025-06-14 22:02:22.003961 :A4BE32BB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:02:22.004135 :A4BE32C0:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:02:22.005119 :A4BE32C5:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:02:22.621919 :A4BE3330:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:02:22.614 2025-06-14 22:02:22.621922 :A4BE3331:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16800 pso_num=27 pso_serial#=211 2025-06-14 22:02:22.622115 :A4BE3332:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16800 (legacy spawn) 2025-06-14 22:02:22.628600 :A4BE3333:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:02:22.628779 :A4BE333C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:02:22.629607 :A4BE333D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:02:22.638185 :A4BE3340:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:02:22.629 2025-06-14 22:02:22.638187 :A4BE3341:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15120 pso_num=27 pso_serial#=212 2025-06-14 22:02:22.638326 :A4BE3342:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15120 (legacy spawn) 2025-06-14 22:02:22.644796 :A4BE3343:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:02:22.644966 :A4BE334C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:02:22.645840 :A4BE334D:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:02:25.633291 :A4BE3447:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:02:25.624 2025-06-14 22:02:25.633293 :A4BE3448:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13532 pso_num=27 pso_serial#=213 2025-06-14 22:02:25.633428 :A4BE3449:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13532 (legacy spawn) 2025-06-14 22:02:25.639911 :A4BE344A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:02:25.640074 :A4BE344F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:02:25.640879 :A4BE3454:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:03:17.066551 :A4BE4542:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:03:17.058 2025-06-14 22:03:17.066553 :A4BE4543:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17180 pso_num=27 pso_serial#=214 2025-06-14 22:03:17.066695 :A4BE4544:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17180 (legacy spawn) 2025-06-14 22:03:17.073080 :A4BE4545:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:03:17.073253 :A4BE454A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:03:17.074115 :A4BE454F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:03:24.165317 :A4BE4782:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:03:24.157 2025-06-14 22:03:24.165320 :A4BE4783:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14564 pso_num=27 pso_serial#=215 2025-06-14 22:03:24.165456 :A4BE4784:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14564 (legacy spawn) 2025-06-14 22:03:24.172113 :A4BE4785:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:03:24.172321 :A4BE478D:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:03:24.173170 :A4BE478E:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:03:32.122307 :A4BE4A09:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:03:32.108 2025-06-14 22:03:32.122309 :A4BE4A0A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16008 pso_num=27 pso_serial#=216 2025-06-14 22:03:32.122451 :A4BE4A0B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16008 (legacy spawn) 2025-06-14 22:03:32.128861 :A4BE4A0C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:03:32.129039 :A4BE4A11:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:03:32.129811 :A4BE4A16:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:03:32.147981 :A4BE4A19:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:03:32.139 2025-06-14 22:03:32.147983 :A4BE4A1A:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14860 pso_num=27 pso_serial#=217 2025-06-14 22:03:32.148173 :A4BE4A1B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14860 (legacy spawn) 2025-06-14 22:03:32.154550 :A4BE4A1C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:03:32.154735 :A4BE4A25:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:03:32.155568 :A4BE4A26:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:03:33.538570 :A4BE4A76:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:03:33.530 2025-06-14 22:03:33.538572 :A4BE4A77:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16764 pso_num=27 pso_serial#=218 2025-06-14 22:03:33.538716 :A4BE4A78:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16764 (legacy spawn) 2025-06-14 22:03:33.545188 :A4BE4A79:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:03:33.545349 :A4BE4A7E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:03:33.546147 :A4BE4A83:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:03:35.200055 :A4BE4B1D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:03:35.187 2025-06-14 22:03:35.200057 :A4BE4B1E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16104 pso_num=27 pso_serial#=219 2025-06-14 22:03:35.200192 :A4BE4B1F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16104 (legacy spawn) 2025-06-14 22:03:35.206638 :A4BE4B20:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:03:35.206813 :A4BE4B28:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:03:35.207608 :A4BE4B2A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:03:35.216143 :A4BE4B2D:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:03:35.203 2025-06-14 22:03:35.216145 :A4BE4B2E:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15192 pso_num=27 pso_serial#=220 2025-06-14 22:03:35.216279 :A4BE4B2F:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15192 (legacy spawn) 2025-06-14 22:03:35.222824 :A4BE4B30:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:03:35.223009 :A4BE4B39:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:03:35.223881 :A4BE4B3A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:04:11.396796 :A4BE5768:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:04:11.383 2025-06-14 22:04:11.396797 :A4BE5769:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13244 pso_num=27 pso_serial#=221 2025-06-14 22:04:11.396941 :A4BE576B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=13244 (legacy spawn) 2025-06-14 22:04:11.403713 :A4BE577B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:04:11.403889 :A4BE5783:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:04:11.404672 :A4BE5784:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:04:11.413578 :A4BE57AB:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:04:11.398 2025-06-14 22:04:11.413580 :A4BE57AC:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17264 pso_num=27 pso_serial#=222 2025-06-14 22:04:11.413715 :A4BE57AD:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17264 (legacy spawn) 2025-06-14 22:04:11.420281 :A4BE57BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:04:11.420428 :A4BE57C3:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:04:11.421219 :A4BE57C4:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:05:15.949249 :A4BE6EB6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:05:15.940 2025-06-14 22:05:15.949251 :A4BE6EB7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12532 pso_num=27 pso_serial#=223 2025-06-14 22:05:15.949387 :A4BE6EB8:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12532 (legacy spawn) 2025-06-14 22:05:15.955880 :A4BE6EB9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:05:15.956058 :A4BE6EBE:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:05:15.956858 :A4BE6EC3:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:05:20.900238 :A4BE7095:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:05:20.885 2025-06-14 22:05:20.900240 :A4BE7096:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=14340 pso_num=27 pso_serial#=224 2025-06-14 22:05:20.900378 :A4BE7097:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=14340 (legacy spawn) 2025-06-14 22:05:20.906813 :A4BE7098:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:05:20.906988 :A4BE70A1:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:05:20.907800 :A4BE70A2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:05:20.916472 :A4BE70A5:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:05:20.901 2025-06-14 22:05:20.916475 :A4BE70A6:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15416 pso_num=27 pso_serial#=225 2025-06-14 22:05:20.916607 :A4BE70A7:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=15416 (legacy spawn) 2025-06-14 22:05:20.923110 :A4BE70A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:05:20.923273 :A4BE70B1:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:05:20.924078 :A4BE70B2:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:05:24.008446 :A4BE71BF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:05:23.995 2025-06-14 22:05:24.008448 :A4BE71C0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17124 pso_num=27 pso_serial#=226 2025-06-14 22:05:24.008591 :A4BE71C1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=17124 (legacy spawn) 2025-06-14 22:05:24.015039 :A4BE71C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:05:24.015204 :A4BE71C7:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:05:24.015994 :A4BE71CC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:05:24.024706 :A4BE71CF:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:05:24.010 2025-06-14 22:05:24.024708 :A4BE71D0:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16496 pso_num=27 pso_serial#=227 2025-06-14 22:05:24.024853 :A4BE71D1:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=16496 (legacy spawn) 2025-06-14 22:05:24.031430 :A4BE71D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:05:24.031606 :A4BE71DB:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:05:24.032415 :A4BE71DC:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:05:28.473376 :A4BE7813:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:05:28.465 2025-06-14 22:05:28.473378 :A4BE7814:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=9804 pso_num=27 pso_serial#=228 2025-06-14 22:05:28.473521 :A4BE7815:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=9804 (legacy spawn) 2025-06-14 22:05:28.480046 :A4BE7816:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:05:28.574238 :A4BE7845:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:05:28.615977 :A4BE7848:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:05:28.616982 :A4BE7859:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:06:23.920618 :A4BE8AA6:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:06:23.915 2025-06-14 22:06:23.920620 :A4BE8AA7:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17156 pso_num=27 pso_serial#=229 2025-06-14 22:06:23.920757 :A4BE8AA8:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17156 osp_idx=25 osp_ver=68568 osp_pg=0 (spawn #285837) 2025-06-14 22:06:23.920758 :A4BE8AA9:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 22:06:23.920758 :A4BE8AAA:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=128 time=2914527110 2025-06-14 22:06:23.920758 :A4BE8AAB:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 22:06:23.920759 :A4BE8AAC:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 22:06:23.920759 :A4BE8AAD:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 22:06:23.920759 :A4BE8AAE:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 22:06:23.920760 :A4BE8AAF:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 22:06:23.921253 :A4BE8AB0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-14 22:06:23.921305 :A4BE8AB7:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:06:23.925447 :A4BE8ACD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-14 22:06:23.925494 :A4BE8ACE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-14 22:06:23.925761 :A4BE8ACF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-14 22:06:23.925780 :A4BE8AD0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-14 22:06:23.926009 :A4BE8AD1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-14 22:06:23.926022 :A4BE8AD2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-14 22:06:23.926234 :A4BE8AD3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-14 22:06:23.926248 :A4BE8AD4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-14 22:06:23.926460 :A4BE8AD5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-14 22:06:23.926473 :A4BE8AD6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-14 22:06:23.926677 :A4BE8AD7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpus:kelt.c:517 2025-06-14 22:06:23.926689 :A4BE8AD8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:27:219] atxpop:kelt.c:517 2025-06-14 22:06:23.928906 :A4BE8B0A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:06:24.033833 :A4BE8C61:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:06:24.025 2025-06-14 22:06:24.033835 :A4BE8C62:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11720 pso_num=27 pso_serial#=230 2025-06-14 22:06:24.033974 :A4BE8C6B:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=11720 (legacy spawn) 2025-06-14 22:06:24.040694 :A4BE8C94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-14 22:06:24.040909 :A4BE8C9F:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] 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-14 22:06:24.041709 :A4BE8CA0:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-06-14 22:06:24.123662 :A4BE8CE2:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 06-14 22:06:24.118 2025-06-14 22:06:24.123664 :A4BE8CE3:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=13620 pso_num=27 pso_serial#=231 2025-06-14 22:06:24.123805 :A4BE8CE4:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=13620 osp_idx=25 osp_ver=68570 osp_pg=0 (spawn #285839) 2025-06-14 22:06:24.123805 :A4BE8CE5:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-14 22:06:24.123806 :A4BE8CE6:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=129 time=2914527313 2025-06-14 22:06:24.123806 :A4BE8CE7:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-06-14 22:06:24.123807 :A4BE8CE8:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-06-14 22:06:24.123807 :A4BE8CE9:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-06-14 22:06:24.123807 :A4BE8CEA:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-14 22:06:24.123808 :A4BE8CEB:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-14 22:06:24.124349 :A4BE8CF5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] 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 27 (osid: 13620, J002)