Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250823220208\orcl_j002_4148_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:35286M/63366M, Ph+PgF:40447M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 28 Windows thread id: 4148, image: ORACLE.EXE (J002) *** 2025-08-23 22:02:08.829 *** SESSION ID:(225.54427) 2025-08-23 22:02:08.829 *** 2025-08-23 22:02:08.829 Process diagnostic dump for ORACLE.EXE (J002), OS id=4148, pid: 28, proc_ser: 69, sid: 225, sess_ser: 54427 ------------------------------------------------------------------------------- current sql: client details: O/S info: user: SYSTEM, term: WIN-3PIMCL4OH3L, ospid: 4148 machine: WIN-3PIMCL4OH3L program: ORACLE.EXE (J002) Current Wait Stack: 0: waiting for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=15 seq_num=16 snap_id=1 wait times: snap=0.395777 sec, exc=0.395777 sec, total=0.395777 sec wait times: max=0.500000 sec, heur=7.924845 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.000009 sec since current wait 0: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=14 seq_num=15 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 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=13 seq_num=14 snap_id=1 wait times: snap=0.500000 sec, exc=0.500000 sec, total=0.500000 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 2: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=12 seq_num=13 snap_id=1 wait times: snap=0.504927 sec, exc=0.504927 sec, total=0.504927 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000012 sec of elapsed time 3: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=11 seq_num=12 snap_id=1 wait times: snap=0.500009 sec, exc=0.500009 sec, total=0.500009 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000008 sec of elapsed time 4: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=10 seq_num=11 snap_id=1 wait times: snap=0.510097 sec, exc=0.510097 sec, total=0.510097 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000011 sec of elapsed time 5: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=9 seq_num=10 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.000008 sec of elapsed time 6: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=8 seq_num=9 snap_id=1 wait times: snap=0.510086 sec, exc=0.510086 sec, total=0.510086 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time 7: 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.000008 sec of elapsed time 8: 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.000009 sec of elapsed time 9: waited for 'jobq slave wait' =0x0, =0x0, =0x0 wait_id=5 seq_num=6 snap_id=1 wait times: snap=0.499991 sec, exc=0.499991 sec, total=0.499991 sec wait times: max=0.500000 sec wait counts: calls=1 os=1 occurred after 0.000013 sec of elapsed time Sampled Session History of session 225 serial 54427 --------------------------------------------------- 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 --------------------------------------------------- [8 samples, 22:02:00 - 22:02:08] idle wait at each sample [session created at: 22:02:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-23 22:02:08.829 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 28 (osid: 4148, J002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-23 21:59:17.583596 :C3BDE007:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 21:59:44.807393 :C3BDE8ED:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 21:59:44.799 2025-08-23 21:59:44.807395 :C3BDE8EE:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19660 pso_num=28 pso_serial#=49 2025-08-23 21:59:44.807538 :C3BDE8EF:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19660 (legacy spawn) 2025-08-23 21:59:44.814052 :C3BDE8F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 21:59:44.814231 :C3BDE8F5:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 21:59:44.815083 :C3BDE8FA:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 21:59:44.958008 :C3BDE909:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 21:59:44.950 2025-08-23 21:59:44.958010 :C3BDE90A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=6128 pso_num=28 pso_serial#=50 2025-08-23 21:59:44.958146 :C3BDE90B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=6128 (legacy spawn) 2025-08-23 21:59:44.964676 :C3BDE90C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 21:59:44.964861 :C3BDE915:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 21:59:44.965704 :C3BDE916:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 21:59:51.622199 :C3BDEC04:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 21:59:51.608 2025-08-23 21:59:51.622202 :C3BDEC05:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21084 pso_num=28 pso_serial#=51 2025-08-23 21:59:51.622349 :C3BDEC06:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21084 (legacy spawn) 2025-08-23 21:59:51.628963 :C3BDEC07:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 21:59:51.629131 :C3BDEC10:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 21:59:51.629945 :C3BDEC11:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:00:00.422296 :C3BDEEAC:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:00:00.417 2025-08-23 22:00:00.422297 :C3BDEEAD:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=13880 pso_num=28 pso_serial#=52 2025-08-23 22:00:00.422437 :C3BDEEAE:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=13880 osp_idx=26 osp_ver=256375 osp_pg=0 (spawn #519155) 2025-08-23 22:00:00.422438 :C3BDEEAF:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 22:00:00.422438 :C3BDEEB0:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=9 time=372102424 2025-08-23 22:00:00.422439 :C3BDEEB1:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 22:00:00.422439 :C3BDEEB2:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 22:00:00.422440 :C3BDEEB3:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 22:00:00.422440 :C3BDEEB4:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 22:00:00.422440 :C3BDEEB5:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 22:00:00.422966 :C3BDEEB6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 22:00:00.423010 :C3BDEEBC:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-23 22:00:00.427369 :C3BDEECF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:00.453278 :C3BDEED7:db_trace:ksb.c@6610:ksbcic_int(): [10254:28:225] KSBCIC: (USER) starting cic on opcode KSBCIRCO [1] with args (1 4286478258 8489040) 2025-08-23 22:00:00.453286 :C3BDEED8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=15 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:00.453342 :C3BDEEE0:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=15 loc='ksa2.h LINE:286 ID:ksasnr' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-23 22:00:00.453388 :C3BDEEF0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:00.468738 :C3BDEEF8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:00.500828 :C3BDEF28:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:00.504588 :C3BDEF30:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:00.508276 :C3BDEF38:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:00.512616 :C3BDEF40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:00.513764 :C3BDEF48:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:00.571909 :C3BDEF50:db_trace:ktu.c@4871:ktuaex1r(): [10442:28:225] Extension usn 7, ext 1, nex 3 2025-08-23 22:00:00.571918 :C3BDEF51:db_trace:ktusm.c@2117:ktusmasp(): [10445:28:225] des1:tsn:1 rdba:0x0081866b where: kdiwh22: kdifind 2025-08-23 22:00:01.734488 :C3BDEF84:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:01.781128 :C3BDEF8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:01.781460 :C3BDEF94:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:01.781752 :C3BDEF9C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:01.781768 :C3BDEF9F: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-08-23 22:00:01.781830 :C3BDEFAB:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:00:01.784975 :C3BDEFB9:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:00:05.805614 :C3BDF179:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:00:05.795 2025-08-23 22:00:05.805616 :C3BDF17A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=10320 pso_num=28 pso_serial#=53 2025-08-23 22:00:05.805760 :C3BDF17B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=10320 (legacy spawn) 2025-08-23 22:00:05.812300 :C3BDF17C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:05.812481 :C3BDF181:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:00:05.813274 :C3BDF186:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:00:05.822039 :C3BDF189:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:00:05.810 2025-08-23 22:00:05.822041 :C3BDF18A:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21868 pso_num=28 pso_serial#=54 2025-08-23 22:00:05.822181 :C3BDF18B:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21868 (legacy spawn) 2025-08-23 22:00:05.828786 :C3BDF18C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:05.828949 :C3BDF195:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:00:05.829757 :C3BDF196:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:00:08.876347 :C3BDF282:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:00:08.863 2025-08-23 22:00:08.876349 :C3BDF283:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=15292 pso_num=28 pso_serial#=55 2025-08-23 22:00:08.876501 :C3BDF284:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=15292 (legacy spawn) 2025-08-23 22:00:08.883937 :C3BDF291:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:08.884135 :C3BDF296:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:00:08.885040 :C3BDF29B:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:00:08.894323 :C3BDF29E:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:00:08.894 2025-08-23 22:00:08.894325 :C3BDF29F:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21740 pso_num=28 pso_serial#=56 2025-08-23 22:00:08.894479 :C3BDF2A0:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21740 (legacy spawn) 2025-08-23 22:00:08.901404 :C3BDF2A1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:08.901585 :C3BDF2A9:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:00:08.902536 :C3BDF2AB:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:00:16.036605 :C3BDF51B:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:00:16.028 2025-08-23 22:00:16.036607 :C3BDF51C:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20164 pso_num=28 pso_serial#=57 2025-08-23 22:00:16.036783 :C3BDF51D:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20164 (legacy spawn) 2025-08-23 22:00:16.063952 :C3BDF51E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:16.064124 :C3BDF527:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:00:16.064943 :C3BDF528:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:00:25.652754 :C3BDF81F:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:00:25.644 2025-08-23 22:00:25.652757 :C3BDF820:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=16984 pso_num=28 pso_serial#=58 2025-08-23 22:00:25.652904 :C3BDF821:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=16984 (legacy spawn) 2025-08-23 22:00:25.659425 :C3BDF822:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:25.659588 :C3BDF828:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:00:25.660372 :C3BDF82C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:00:45.250219 :C3BDFF17:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:00:45.236 2025-08-23 22:00:45.250220 :C3BDFF18:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=20640 pso_num=28 pso_serial#=59 2025-08-23 22:00:45.250357 :C3BDFF19:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=20640 (legacy spawn) 2025-08-23 22:00:45.256892 :C3BDFF1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:45.257070 :C3BDFF1F:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:00:45.257898 :C3BDFF24:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:00:45.266612 :C3BDFF27:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:00:45.252 2025-08-23 22:00:45.266614 :C3BDFF28:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21836 pso_num=28 pso_serial#=60 2025-08-23 22:00:45.266747 :C3BDFF29:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21836 (legacy spawn) 2025-08-23 22:00:45.273282 :C3BDFF2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:45.273444 :C3BDFF33:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:00:45.274231 :C3BDFF34:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:00:47.151404 :C3BE0002:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:00:47.143 2025-08-23 22:00:47.151406 :C3BE0003:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21524 pso_num=28 pso_serial#=61 2025-08-23 22:00:47.151541 :C3BE0004:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21524 (legacy spawn) 2025-08-23 22:00:47.158532 :C3BE0005:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:47.158713 :C3BE000A:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:00:47.159615 :C3BE000F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:00:59.702097 :C3BE03B6:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:00:59.692 2025-08-23 22:00:59.702100 :C3BE03B7:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22096 pso_num=28 pso_serial#=62 2025-08-23 22:00:59.702247 :C3BE03B8:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=22096 (legacy spawn) 2025-08-23 22:00:59.708773 :C3BE03B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:00:59.708954 :C3BE03BE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:00:59.709770 :C3BE03C3:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:01:49.346772 :C3BE1412:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:01:49.333 2025-08-23 22:01:49.346774 :C3BE1413:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21280 pso_num=28 pso_serial#=63 2025-08-23 22:01:49.346935 :C3BE1414:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21280 (legacy spawn) 2025-08-23 22:01:49.353410 :C3BE1415:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:01:49.353589 :C3BE141C:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:01:49.354398 :C3BE141F:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:01:54.772716 :C3BE1595:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:01:54.758 2025-08-23 22:01:54.772719 :C3BE1596:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=21800 pso_num=28 pso_serial#=64 2025-08-23 22:01:54.772853 :C3BE1597:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=21800 (legacy spawn) 2025-08-23 22:01:54.779352 :C3BE1598:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:01:54.779531 :C3BE15A1:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:01:54.780335 :C3BE15A2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:01:54.789060 :C3BE15A5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:01:54.774 2025-08-23 22:01:54.789062 :C3BE15A6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=17716 pso_num=28 pso_serial#=65 2025-08-23 22:01:54.789197 :C3BE15A7:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=17716 (legacy spawn) 2025-08-23 22:01:54.795603 :C3BE15A8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:01:54.795772 :C3BE15B1:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:01:54.796556 :C3BE15B2:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:01:57.983985 :C3BE16C2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:01:57.977 2025-08-23 22:01:57.983987 :C3BE16C3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=19936 pso_num=28 pso_serial#=66 2025-08-23 22:01:57.984125 :C3BE16C4:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=19936 (legacy spawn) 2025-08-23 22:01:57.990591 :C3BE16C5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:01:57.990768 :C3BE16CE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:01:57.991567 :C3BE16CF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:01:58.000379 :C3BE16D2:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:01:57.992 2025-08-23 22:01:58.000380 :C3BE16D3:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=18824 pso_num=28 pso_serial#=67 2025-08-23 22:01:58.000523 :C3BE16D4:db_trace:kso.c@4070:ksonfy(): [10420:28:0] kso: new process: pid=18824 (legacy spawn) 2025-08-23 22:01:58.007017 :C3BE16D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-23 22:01:58.007183 :C3BE16DE:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-08-23 22:01:58.008016 :C3BE16DF:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:02:00.705752 :C3BE17B5:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:02:00.700 2025-08-23 22:02:00.705754 :C3BE17B6:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=22248 pso_num=28 pso_serial#=68 2025-08-23 22:02:00.705891 :C3BE17B7:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=22248 osp_idx=26 osp_ver=256391 osp_pg=0 (spawn #519161) 2025-08-23 22:02:00.705892 :C3BE17B8:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 22:02:00.705893 :C3BE17B9:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=10 time=372222721 2025-08-23 22:02:00.705893 :C3BE17BA:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 22:02:00.705893 :C3BE17BB:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 22:02:00.705894 :C3BE17BC:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 22:02:00.705894 :C3BE17BD:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 22:02:00.705894 :C3BE17BE:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 22:02:00.706404 :C3BE17BF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:28:225] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-23 22:02:00.706449 :C3BE17C5:db_trace:ksl2.c@12699:ksliwat(): [10005:28:225] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-08-23 22:02:00.710579 :C3BE17DC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-23 22:02:00.710626 :C3BE17DD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-23 22:02:00.710894 :C3BE17DE:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-23 22:02:00.710912 :C3BE17DF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-23 22:02:00.711136 :C3BE17E0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-23 22:02:00.711149 :C3BE17E1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-23 22:02:00.711354 :C3BE17E2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-23 22:02:00.711367 :C3BE17E3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-23 22:02:00.711575 :C3BE17E4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-23 22:02:00.711588 :C3BE17E5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-23 22:02:00.711795 :C3BE17E6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpus:kelt.c:517 2025-08-23 22:02:00.711808 :C3BE17E7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:28:225] atxpop:kelt.c:517 2025-08-23 22:02:00.714279 :C3BE180C:db_trace:kst.c@698:kstpsodel(): [10280:28:0] kst: process state object about to be deleted 2025-08-23 22:02:00.908655 :C3BE1C70:db_trace:kst.c@673:kstipg(): [10280:28:0] kst: process state object created on 08-23 22:02:00.903 2025-08-23 22:02:00.908657 :C3BE1C71:db_trace:kst.c@679:kstipg(): [10280:28:0] kst: process info: ospid=4148 pso_num=28 pso_serial#=69 2025-08-23 22:02:00.908795 :C3BE1C72:db_trace:kso.c@4093:ksonfy(): [10420:28:0] kso: new process: pid=4148 osp_idx=26 osp_ver=256392 osp_pg=0 (spawn #519164) 2025-08-23 22:02:00.908796 :C3BE1C73:db_trace:kso.c@4099:ksonfy(): [10420:28:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-23 22:02:00.908796 :C3BE1C74:db_trace:kso.c@4103:ksonfy(): [10420:28:0] kso: new process: pso_index=28 reservation=11 time=372222924 2025-08-23 22:02:00.908797 :C3BE1C75:db_trace:kso.c@4109:ksonfy(): [10420:28:0] kso: spawn diagnostics: queue time=0 secs 2025-08-23 22:02:00.908797 :C3BE1C76:db_trace:kso.c@4111:ksonfy(): [10420:28:0] kso: spawn diagnostics: fork time=0 secs 2025-08-23 22:02:00.908798 :C3BE1C77:db_trace:kso.c@4113:ksonfy(): [10420:28:0] kso: spawn diagnostics: exec time=0 secs 2025-08-23 22:02:00.908799 :C3BE1C78:db_trace:kso.c@4115:ksonfy(): [10420:28:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-23 22:02:00.908799 :C3BE1C79:db_trace:kso.c@4117:ksonfy(): [10420:28:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-23 22:02:00.909313 :C3BE1C83: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: 4148, J002)