Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250504180206\orcl_j002_8212_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:37574M/63366M, Ph+PgF:45103M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 8212, image: ORACLE.EXE (J002) *** 2025-05-04 18:02:06.115 *** SESSION ID:(225.42111) 2025-05-04 18:02:06.115 *** 2025-05-04 18:02:06.115 Process diagnostic dump for ORACLE.EXE (J002), OS id=8212, pid: 28, proc_ser: 47, sid: 225, sess_ser: 42111 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 8212 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.034304 sec, exc=0.034304 sec, total=0.034304 sec wait times: max=0.500000 sec, heur=4.037664 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.000013 sec since current wait 0: 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.000010 sec of elapsed time 1: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=6 seq_num=7 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 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=5 seq_num=6 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.000014 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=4 seq_num=5 snap_id=1 wait times: snap=0.499997 sec, exc=0.499997 sec, total=0.499997 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000009 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=3 seq_num=4 snap_id=1 wait times: snap=0.504597 sec, exc=0.504597 sec, total=0.504597 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=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.000012 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=1 seq_num=2 snap_id=1 wait times: snap=0.499998 sec, exc=0.499998 sec, total=0.499998 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 7: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=0.498702 sec, exc=0.498702 sec, total=0.498702 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000000 sec of elapsed time Sampled Session History of session 225 serial 42111 --------------------------------------------------- The sampled session history is constructed by sampling the target session every 1 second. The sampling process captures at each sample if the session is in a non-idle wait, an idle wait, or not in a wait. If the session is in a non-idle wait then one interval is shown for all the samples the session was in the same non-idle wait. If the session is in an idle wait or not in a wait for consecutive samples then one interval is shown for all the consecutive samples. Though we display these consecutive samples in a single interval the session may NOT be continuously idle or not in a wait (the sampling process does not know). The history is displayed in reverse chronological order. sample interval: 1 sec, max history 120 sec --------------------------------------------------- [5 samples, 18:02:02 - 18:02:06] idle wait at each sample [session created at: 18:02:02] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-04 18:02:06.115 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 8212, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-04 17:57:14.040061 :92AB5C33:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 17:57:14.470584 :92AB5C69:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 17:57:14.462 2025-05-04 17:57:14.470586 :92AB5C6A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8780 pso_num=28 pso_serial#=25 2025-05-04 17:57:14.470724 :92AB5C6B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8780 (legacy spawn) 2025-05-04 17:57:14.477206 :92AB5C6C: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-05-04 17:57:14.477390 :92AB5C75: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-05-04 17:57:14.478179 :92AB5C76:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 17:58:16.471180 :92AB708D:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 17:58:16.458 2025-05-04 17:58:16.471182 :92AB708E:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8768 pso_num=28 pso_serial#=26 2025-05-04 17:58:16.471315 :92AB708F:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8768 (legacy spawn) 2025-05-04 17:58:16.477691 :92AB7090: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-05-04 17:58:16.477877 :92AB7095: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-05-04 17:58:16.478691 :92AB709A:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 17:58:20.608045 :92AB7201:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 17:58:20.593 2025-05-04 17:58:20.608048 :92AB7202:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8016 pso_num=28 pso_serial#=27 2025-05-04 17:58:20.608182 :92AB7203:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8016 (legacy spawn) 2025-05-04 17:58:20.626237 :92AB7204: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-05-04 17:58:20.626417 :92AB7209: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-05-04 17:58:20.627286 :92AB720E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 17:58:20.636138 :92AB7211:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 17:58:20.625 2025-05-04 17:58:20.636140 :92AB7212:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=2936 pso_num=28 pso_serial#=28 2025-05-04 17:58:20.636276 :92AB7213:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=2936 (legacy spawn) 2025-05-04 17:58:20.642779 :92AB7214: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-05-04 17:58:20.642939 :92AB721D: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-05-04 17:58:20.643765 :92AB721E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 17:58:22.654133 :92AB72B1:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 17:58:22.645 2025-05-04 17:58:22.654135 :92AB72B2:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10584 pso_num=28 pso_serial#=29 2025-05-04 17:58:22.654272 :92AB72B3:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10584 (legacy spawn) 2025-05-04 17:58:22.660741 :92AB72B4: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-05-04 17:58:22.660922 :92AB72BC: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-05-04 17:58:22.661731 :92AB72BD:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 17:58:23.674134 :92AB7321:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 17:58:23.661 2025-05-04 17:58:23.674136 :92AB7322:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9368 pso_num=28 pso_serial#=30 2025-05-04 17:58:23.674270 :92AB7323:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9368 (legacy spawn) 2025-05-04 17:58:23.680645 :92AB7324: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-05-04 17:58:23.680827 :92AB732D: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-05-04 17:58:23.681611 :92AB732E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 17:58:23.690142 :92AB7331:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 17:58:23.676 2025-05-04 17:58:23.690143 :92AB7332:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9660 pso_num=28 pso_serial#=31 2025-05-04 17:58:23.690282 :92AB7333:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9660 (legacy spawn) 2025-05-04 17:58:23.696792 :92AB7334: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-05-04 17:58:23.696963 :92AB733D: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-05-04 17:58:23.697772 :92AB733E:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 17:59:18.658239 :92AB84F5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 17:59:18.650 2025-05-04 17:59:18.658242 :92AB84F6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8444 pso_num=28 pso_serial#=32 2025-05-04 17:59:18.658378 :92AB84F7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=8444 (legacy spawn) 2025-05-04 17:59:18.664794 :92AB84F8: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-05-04 17:59:18.664830 :92AB84FB: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-05-04 17:59:18.664997 :92AB84FE: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-05-04 17:59:18.665803 :92AB850F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 17:59:30.872035 :92AB88BD:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 17:59:30.863 2025-05-04 17:59:30.872037 :92AB88BE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11844 pso_num=28 pso_serial#=33 2025-05-04 17:59:30.872216 :92AB88BF:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11844 (legacy spawn) 2025-05-04 17:59:30.878601 :92AB88C0: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-05-04 17:59:30.878770 :92AB88C5: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-05-04 17:59:30.879611 :92AB88CA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 18:00:01.601348 :92AB93BE:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 18:00:01.596 2025-05-04 18:00:01.601350 :92AB93BF:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11624 pso_num=28 pso_serial#=34 2025-05-04 18:00:01.601483 :92AB93C0:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=11624 osp_idx=26 osp_ver=86478 osp_pg=0 (spawn #148172) 2025-05-04 18:00:01.601483 :92AB93C1:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 18:00:01.601484 :92AB93C2:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=231 time=3652378765 2025-05-04 18:00:01.601484 :92AB93C3:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 18:00:01.601485 :92AB93C4:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 18:00:01.601485 :92AB93C5:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 18:00:01.601486 :92AB93C6:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 18:00:01.601486 :92AB93C7:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 18:00:01.602104 :92AB93D2: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-05-04 18:00:01.602146 :92AB93D8: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-05-04 18:00:01.606474 :92AB93FB: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-05-04 18:00:01.639193 :92AB9467:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 1, ext 0, nex 2 2025-05-04 18:00:01.639200 :92AB9468:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x00812d1f where: kdiwh22: kdifind 2025-05-04 18:00:01.641389 :92AB9469: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-05-04 18:00:01.641399 :92AB946A:db_trace:ktur.c@3079:ktuabt(): [10444:28:225] ABORT TRANSACTION - xid: 0x0001.00e.0000bf10 2025-05-04 18:00:01.641649 :92AB9472:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 1, ext 1, nex 3 2025-05-04 18:00:01.641655 :92AB9473:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x00812d2b where: kdiwh133: kdisdelete 2025-05-04 18:00:01.644033 :92AB947C:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 1, ext 2, nex 4 2025-05-04 18:00:01.644039 :92AB947D:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x00812d2f where: kdiwh22: kdifind 2025-05-04 18:00:01.647365 :92AB9486: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-05-04 18:00:01.671809 :92AB94CE: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-05-04 18:00:01.675522 :92AB951C: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-05-04 18:00:01.679056 :92AB9536: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-05-04 18:00:01.683851 :92AB9548: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-05-04 18:00:01.685418 :92AB9550: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-05-04 18:00:02.939206 :92AB95CC: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-05-04 18:00:02.964292 :92AB95D4: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-05-04 18:00:02.964600 :92AB95DC: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-05-04 18:00:02.964874 :92AB95E4: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-05-04 18:00:02.964889 :92AB95E7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-05-04 18:00:02.964951 :92AB95F4: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-05-04 18:00:02.967338 :92AB9601:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 18:00:04.945908 :92AB96FF:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 18:00:04.932 2025-05-04 18:00:04.945910 :92AB9700:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10120 pso_num=28 pso_serial#=35 2025-05-04 18:00:04.946044 :92AB9701:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10120 (legacy spawn) 2025-05-04 18:00:04.952463 :92AB9702: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-05-04 18:00:05.037210 :92AB970B: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-05-04 18:00:05.038157 :92AB970C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 18:00:09.221837 :92AB9875:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 18:00:09.208 2025-05-04 18:00:09.221839 :92AB9876:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11948 pso_num=28 pso_serial#=36 2025-05-04 18:00:09.221983 :92AB9877:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11948 (legacy spawn) 2025-05-04 18:00:09.228447 :92AB9878: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-05-04 18:00:09.228626 :92AB9881: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-05-04 18:00:09.229413 :92AB9882:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 18:00:09.246921 :92AB9885:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 18:00:09.239 2025-05-04 18:00:09.246922 :92AB9886:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11740 pso_num=28 pso_serial#=37 2025-05-04 18:00:09.247064 :92AB9887:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11740 (legacy spawn) 2025-05-04 18:00:09.253505 :92AB9888: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-05-04 18:00:09.253665 :92AB9891: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-05-04 18:00:09.254580 :92AB9892:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 18:00:12.408361 :92AB9983:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 18:00:12.395 2025-05-04 18:00:12.408363 :92AB9984:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10972 pso_num=28 pso_serial#=38 2025-05-04 18:00:12.408499 :92AB9985:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10972 (legacy spawn) 2025-05-04 18:00:12.415046 :92AB9986: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-05-04 18:00:12.415215 :92AB998B: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-05-04 18:00:12.416032 :92AB9990:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 18:00:12.424593 :92AB9993:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 18:00:12.411 2025-05-04 18:00:12.424596 :92AB9994:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11100 pso_num=28 pso_serial#=39 2025-05-04 18:00:12.424728 :92AB9995:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11100 (legacy spawn) 2025-05-04 18:00:12.431284 :92AB9996: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-05-04 18:00:12.431445 :92AB999F: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-05-04 18:00:12.432258 :92AB99A0:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 18:01:13.264982 :92ABAD42:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 18:01:13.252 2025-05-04 18:01:13.264984 :92ABAD43:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=9112 pso_num=28 pso_serial#=40 2025-05-04 18:01:13.265116 :92ABAD44:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=9112 (legacy spawn) 2025-05-04 18:01:13.271530 :92ABAD45: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-05-04 18:01:13.271702 :92ABAD4A: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-05-04 18:01:13.272512 :92ABAD4F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 18:01:22.034873 :92ABB01C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 18:01:22.029 2025-05-04 18:01:22.034875 :92ABB01D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=3780 pso_num=28 pso_serial#=41 2025-05-04 18:01:22.035017 :92ABB01E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=3780 (legacy spawn) 2025-05-04 18:01:22.041570 :92ABB01F: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-05-04 18:01:22.041738 :92ABB024: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-05-04 18:01:22.042583 :92ABB029:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 18:01:22.051457 :92ABB02C:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 18:01:22.044 2025-05-04 18:01:22.051459 :92ABB02D:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11580 pso_num=28 pso_serial#=42 2025-05-04 18:01:22.051614 :92ABB02E:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11580 (legacy spawn) 2025-05-04 18:01:22.058171 :92ABB02F: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-05-04 18:01:22.058343 :92ABB038: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-05-04 18:01:22.059213 :92ABB039:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 18:01:23.070676 :92ABB0B6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 18:01:23.062 2025-05-04 18:01:23.070677 :92ABB0B7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=6768 pso_num=28 pso_serial#=43 2025-05-04 18:01:23.070814 :92ABB0B8:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=6768 (legacy spawn) 2025-05-04 18:01:23.077229 :92ABB0B9: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-05-04 18:01:23.077407 :92ABB0C2: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-05-04 18:01:23.078231 :92ABB0C3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 18:01:25.216066 :92ABB136:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 18:01:25.202 2025-05-04 18:01:25.216068 :92ABB137:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=5220 pso_num=28 pso_serial#=44 2025-05-04 18:01:25.216207 :92ABB138:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=5220 (legacy spawn) 2025-05-04 18:01:25.222660 :92ABB139: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-05-04 18:01:25.222830 :92ABB13E: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-05-04 18:01:25.223661 :92ABB143:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 18:01:25.232261 :92ABB146:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 18:01:25.218 2025-05-04 18:01:25.232262 :92ABB147:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=11156 pso_num=28 pso_serial#=45 2025-05-04 18:01:25.232395 :92ABB148:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=11156 (legacy spawn) 2025-05-04 18:01:25.238874 :92ABB149: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-05-04 18:01:25.239033 :92ABB152: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-05-04 18:01:25.239827 :92ABB153:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 18:02:01.876066 :92ABC076:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 18:02:01.871 2025-05-04 18:02:01.876068 :92ABC077:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8072 pso_num=28 pso_serial#=46 2025-05-04 18:02:01.876201 :92ABC078:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=8072 osp_idx=26 osp_ver=86490 osp_pg=0 (spawn #148178) 2025-05-04 18:02:01.876202 :92ABC079:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 18:02:01.876203 :92ABC07A:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=232 time=3652499046 2025-05-04 18:02:01.876203 :92ABC07B:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 18:02:01.876203 :92ABC07C:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 18:02:01.876204 :92ABC07D:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 18:02:01.876204 :92ABC07E:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 18:02:01.876204 :92ABC07F:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 18:02:01.876705 :92ABC080: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-05-04 18:02:01.876750 :92ABC086: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-05-04 18:02:01.897423 :92ABC15C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-05-04 18:02:02.079821 :92ABC2CB:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 05-04 18:02:02.074 2025-05-04 18:02:02.079823 :92ABC2CC:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=8212 pso_num=28 pso_serial#=47 2025-05-04 18:02:02.079950 :92ABC2CD:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=8212 osp_idx=26 osp_ver=86491 osp_pg=0 (spawn #148182) 2025-05-04 18:02:02.079951 :92ABC2CE:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-04 18:02:02.079951 :92ABC2CF:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=233 time=3652499250 2025-05-04 18:02:02.079951 :92ABC2D0:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 18:02:02.079952 :92ABC2D1:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 18:02:02.079952 :92ABC2D2:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 18:02:02.079952 :92ABC2D3:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 18:02:02.079953 :92ABC2D4:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 18:02:02.080441 :92ABC2DE: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: 8212, J002)