Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250607220608\orcl_j002_15848_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:36799M/63366M, Ph+PgF:43405M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 15848, image: ORACLE.EXE (J002) *** 2025-06-07 22:06:08.220 *** SESSION ID:(225.45019) 2025-06-07 22:06:08.220 *** 2025-06-07 22:06:08.220 Process diagnostic dump for ORACLE.EXE (J002), OS id=15848, pid: 28, proc_ser: 9, sid: 225, sess_ser: 45019 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 15848 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.412267 sec, exc=0.412267 sec, total=0.412267 sec wait times: max=0.500000 sec, heur=6.436446 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.000014 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.500008 sec, exc=0.500008 sec, total=0.500008 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.510092 sec, exc=0.510092 sec, total=0.510092 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 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.500001 sec, exc=0.500001 sec, total=0.500001 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.499999 sec, exc=0.499999 sec, total=0.499999 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 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.499995 sec, exc=0.499995 sec, total=0.499995 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.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.000011 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.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.000009 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.510102 sec, exc=0.510102 sec, total=0.510102 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=3 seq_num=4 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 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.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.000009 sec of elapsed time Sampled Session History of session 225 serial 45019 --------------------------------------------------- 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:01 - 22:06:08] idle wait at each sample [session created at: 22:06:01] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-07 22:06:08.220 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 15848, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-07 22:01:51.515002 :A1AF31B1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:01:51.506 2025-06-07 22:01:51.515004 :A1AF31B2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13524 pso_num=28 pso_serial#=240 2025-06-07 22:01:51.515138 :A1AF31B3:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13524 (legacy spawn) 2025-06-07 22:01:51.521600 :A1AF31B4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:01:51.521767 :A1AF31B9:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:01:51.522694 :A1AF31BE:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:02:45.304361 :A1AF4348:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:02:45.304 2025-06-07 22:02:45.304364 :A1AF4349:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=1112 pso_num=28 pso_serial#=241 2025-06-07 22:02:45.304538 :A1AF434A:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=1112 (legacy spawn) 2025-06-07 22:02:45.310998 :A1AF434B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:02:45.311031 :A1AF434E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:02:45.311181 :A1AF4355:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:02:45.311983 :A1AF4362:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:02:53.705141 :A1AF45AF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:02:53.697 2025-06-07 22:02:53.705143 :A1AF45B0:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13984 pso_num=28 pso_serial#=242 2025-06-07 22:02:53.705293 :A1AF45B1:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13984 (legacy spawn) 2025-06-07 22:02:53.712194 :A1AF45B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:02:53.712379 :A1AF45B7:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:02:53.713259 :A1AF45BC:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:02:57.885603 :A1AF4707:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:02:57.885 2025-06-07 22:02:57.885604 :A1AF4708:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15480 pso_num=28 pso_serial#=243 2025-06-07 22:02:57.885778 :A1AF470E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15480 (legacy spawn) 2025-06-07 22:02:57.892289 :A1AF470F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:02:57.892467 :A1AF4714:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:02:57.893268 :A1AF4719:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:02:57.902093 :A1AF471C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:02:57.901 2025-06-07 22:02:57.902095 :A1AF471D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15408 pso_num=28 pso_serial#=244 2025-06-07 22:02:57.902230 :A1AF471E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15408 (legacy spawn) 2025-06-07 22:02:57.908592 :A1AF471F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:02:57.908766 :A1AF4727:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:02:57.909577 :A1AF4729:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:03:01.041887 :A1AF4847:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:03:01.028 2025-06-07 22:03:01.041889 :A1AF4848:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15704 pso_num=28 pso_serial#=245 2025-06-07 22:03:01.042028 :A1AF4849:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15704 (legacy spawn) 2025-06-07 22:03:01.048511 :A1AF484A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:03:01.048689 :A1AF484F:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:03:01.049480 :A1AF4854:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:03:01.067853 :A1AF4857:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:03:01.059 2025-06-07 22:03:01.067855 :A1AF4858:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13176 pso_num=28 pso_serial#=246 2025-06-07 22:03:01.067997 :A1AF4859:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13176 (legacy spawn) 2025-06-07 22:03:01.074463 :A1AF485A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:03:01.074624 :A1AF4863:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:03:01.075476 :A1AF4864:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:03:53.606969 :A1AF5915:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:03:53.593 2025-06-07 22:03:53.606970 :A1AF5916:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15128 pso_num=28 pso_serial#=247 2025-06-07 22:03:53.607109 :A1AF5917:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15128 (legacy spawn) 2025-06-07 22:03:53.613546 :A1AF5918:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:03:53.613577 :A1AF591B:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:03:53.613726 :A1AF5922:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:03:53.614510 :A1AF592F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:03:56.079551 :A1AF5A03:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:03:56.071 2025-06-07 22:03:56.079553 :A1AF5A04:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10020 pso_num=28 pso_serial#=248 2025-06-07 22:03:56.079745 :A1AF5A05:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10020 (legacy spawn) 2025-06-07 22:03:56.086215 :A1AF5A06:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:03:56.086399 :A1AF5A0B:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:03:56.087247 :A1AF5A1C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:03:59.361490 :A1AF5B09:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:03:59.353 2025-06-07 22:03:59.361492 :A1AF5B0A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15452 pso_num=28 pso_serial#=249 2025-06-07 22:03:59.361632 :A1AF5B0B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15452 (legacy spawn) 2025-06-07 22:03:59.368044 :A1AF5B0C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:03:59.368218 :A1AF5B15:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:03:59.369129 :A1AF5B16:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:04:27.164598 :A1AF642A:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:04:27.156 2025-06-07 22:04:27.164600 :A1AF642B:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14596 pso_num=28 pso_serial#=250 2025-06-07 22:04:27.164741 :A1AF642C:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14596 (legacy spawn) 2025-06-07 22:04:27.171220 :A1AF642D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:04:27.171390 :A1AF6432:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:04:27.172292 :A1AF6443:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:04:27.681027 :A1AF646C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:04:27.671 2025-06-07 22:04:27.681028 :A1AF646D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15896 pso_num=28 pso_serial#=251 2025-06-07 22:04:27.681161 :A1AF646E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15896 (legacy spawn) 2025-06-07 22:04:27.687608 :A1AF646F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:04:27.687774 :A1AF6477:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:04:27.688590 :A1AF6479:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:04:46.844409 :A1AF6B62:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:04:46.841 2025-06-07 22:04:46.844412 :A1AF6B63:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15348 pso_num=28 pso_serial#=252 2025-06-07 22:04:46.844558 :A1AF6B64:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15348 (legacy spawn) 2025-06-07 22:04:46.851295 :A1AF6B65:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:04:46.851482 :A1AF6B6A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:04:46.852356 :A1AF6B6F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:04:46.861416 :A1AF6B7D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:04:46.857 2025-06-07 22:04:46.861418 :A1AF6B7E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15936 pso_num=28 pso_serial#=253 2025-06-07 22:04:46.861555 :A1AF6B7F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15936 (legacy spawn) 2025-06-07 22:04:46.868211 :A1AF6B80:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:04:46.868391 :A1AF6B89:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:04:46.869264 :A1AF6B8A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:04:50.035885 :A1AF6C6E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:04:50.023 2025-06-07 22:04:50.035887 :A1AF6C6F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=12464 pso_num=28 pso_serial#=254 2025-06-07 22:04:50.036027 :A1AF6C70:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=12464 (legacy spawn) 2025-06-07 22:04:50.042649 :A1AF6C71:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:04:50.042822 :A1AF6C76:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:04:50.043621 :A1AF6C7B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:04:50.052270 :A1AF6C7E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:04:50.039 2025-06-07 22:04:50.052272 :A1AF6C7F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15676 pso_num=28 pso_serial#=255 2025-06-07 22:04:50.052412 :A1AF6C80:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15676 (legacy spawn) 2025-06-07 22:04:50.074129 :A1AF6C81:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:04:50.074292 :A1AF6C8A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:04:50.075070 :A1AF6C8B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:04:58.259135 :A1AF6F04:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:04:58.259 2025-06-07 22:04:58.259137 :A1AF6F05:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14600 pso_num=28 pso_serial#=1 2025-06-07 22:04:58.259284 :A1AF6F06:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14600 (legacy spawn) 2025-06-07 22:04:58.265746 :A1AF6F07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:04:58.265915 :A1AF6F0D:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:04:58.266765 :A1AF6F11:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:05:01.712029 :A1AF7045:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:05:01.703 2025-06-07 22:05:01.712031 :A1AF7046:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=7792 pso_num=28 pso_serial#=2 2025-06-07 22:05:01.712166 :A1AF7047:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=7792 (legacy spawn) 2025-06-07 22:05:01.718614 :A1AF7048:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:05:01.718796 :A1AF7050:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:05:01.719616 :A1AF7052:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:05:05.751508 :A1AF76AD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:05:05.738 2025-06-07 22:05:05.751510 :A1AF76AE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13452 pso_num=28 pso_serial#=3 2025-06-07 22:05:05.751651 :A1AF76AF:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=13452 (legacy spawn) 2025-06-07 22:05:05.758037 :A1AF76B0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:05:05.758206 :A1AF76B9:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:05:05.758985 :A1AF76BA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:05:05.767711 :A1AF76BD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:05:05.754 2025-06-07 22:05:05.767712 :A1AF76BE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=14824 pso_num=28 pso_serial#=4 2025-06-07 22:05:05.767850 :A1AF76BF:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=14824 (legacy spawn) 2025-06-07 22:05:05.774234 :A1AF76C0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:05:05.774401 :A1AF76C9:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:05:05.775213 :A1AF76CA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:05:59.745699 :A1AF896E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:05:59.742 2025-06-07 22:05:59.745701 :A1AF896F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16160 pso_num=28 pso_serial#=5 2025-06-07 22:05:59.745839 :A1AF8970:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16160 (legacy spawn) 2025-06-07 22:05:59.752302 :A1AF8971:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:05:59.752478 :A1AF897A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:05:59.753265 :A1AF897B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:05:59.762366 :A1AF897E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:05:59.757 2025-06-07 22:05:59.762368 :A1AF897F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=4800 pso_num=28 pso_serial#=6 2025-06-07 22:05:59.762527 :A1AF8980:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=4800 (legacy spawn) 2025-06-07 22:05:59.769134 :A1AF8981:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:05:59.769303 :A1AF8989:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:05:59.770109 :A1AF898B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:06:00.452513 :A1AF89B6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:06:00.445 2025-06-07 22:06:00.452515 :A1AF89B7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16084 pso_num=28 pso_serial#=7 2025-06-07 22:06:00.452654 :A1AF89B8:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16084 (legacy spawn) 2025-06-07 22:06:00.459081 :A1AF89B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-07 22:06:00.459257 :A1AF89BE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-06-07 22:06:00.460045 :A1AF89C3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:06:01.585548 :A1AF8A6E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:06:01.580 2025-06-07 22:06:01.585550 :A1AF8A6F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16292 pso_num=28 pso_serial#=8 2025-06-07 22:06:01.585694 :A1AF8A70:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=16292 osp_idx=26 osp_ver=186206 osp_pg=0 (spawn #262486) 2025-06-07 22:06:01.585695 :A1AF8A71:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 22:06:01.585695 :A1AF8A72:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=254 time=2309720235 2025-06-07 22:06:01.585695 :A1AF8A73:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 22:06:01.585696 :A1AF8A74:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 22:06:01.585696 :A1AF8A75:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 22:06:01.585697 :A1AF8A76:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 22:06:01.585697 :A1AF8A77:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 22:06:01.586198 :A1AF8A78:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-07 22:06:01.586244 :A1AF8A7E:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-07 22:06:01.590270 :A1AF8A95:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-07 22:06:01.590315 :A1AF8A96:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-07 22:06:01.590588 :A1AF8A97:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-07 22:06:01.590606 :A1AF8A98:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-07 22:06:01.590831 :A1AF8A99:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-07 22:06:01.590845 :A1AF8A9A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-07 22:06:01.591052 :A1AF8A9B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-07 22:06:01.591065 :A1AF8A9C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-07 22:06:01.591273 :A1AF8A9D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-07 22:06:01.591286 :A1AF8A9E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-07 22:06:01.591490 :A1AF8A9F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-06-07 22:06:01.591503 :A1AF8AA0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-06-07 22:06:01.593908 :A1AF8AD3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-06-07 22:06:01.788557 :A1AF8EF2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 06-07 22:06:01.783 2025-06-07 22:06:01.788559 :A1AF8EF3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15848 pso_num=28 pso_serial#=9 2025-06-07 22:06:01.788686 :A1AF8EF4:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=15848 osp_idx=26 osp_ver=186207 osp_pg=0 (spawn #262488) 2025-06-07 22:06:01.788687 :A1AF8EF5:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-07 22:06:01.788687 :A1AF8EF6:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=255 time=2309720438 2025-06-07 22:06:01.788688 :A1AF8EF7:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-06-07 22:06:01.788688 :A1AF8EF8:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-06-07 22:06:01.788688 :A1AF8EF9:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-06-07 22:06:01.788689 :A1AF8EFA:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-07 22:06:01.788689 :A1AF8EFB:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-07 22:06:01.789277 :A1AF8F05: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: 15848, J002)