Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250716081523\orcl_m002_12656_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:36299M/63366M, Ph+PgF:42259M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 27 Windows thread id: 12656, image: ORACLE.EXE (M002) *** 2025-07-16 08:15:24.102 *** SESSION ID:(219.5455) 2025-07-16 08:15:24.102 *** 2025-07-16 08:15:24.102 Process diagnostic dump for ORACLE.EXE (M002), OS id=12656, pid: 27, proc_ser: 193, sid: 219, sess_ser: 5455 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=985 seq_num=986 snap_id=1 wait times: snap=0.000506 sec, exc=0.000506 sec, total=0.000506 sec wait times: max=infinite, heur=0.032082 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000006 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=984 seq_num=985 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=983 seq_num=984 snap_id=1 wait times: snap=0.000049 sec, exc=0.000049 sec, total=0.000049 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 2: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=982 seq_num=983 snap_id=1 wait times: snap=0.000046 sec, exc=0.000046 sec, total=0.000046 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000005 sec of elapsed time 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=981 seq_num=982 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=980 seq_num=981 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 5: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=979 seq_num=980 snap_id=1 wait times: snap=0.000042 sec, exc=0.000042 sec, total=0.000042 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=978 seq_num=979 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=977 seq_num=978 snap_id=1 wait times: snap=0.000043 sec, exc=0.000043 sec, total=0.000043 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 8: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=976 seq_num=977 snap_id=1 wait times: snap=0.000044 sec, exc=0.000044 sec, total=0.000044 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time 9: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=975 seq_num=976 snap_id=1 wait times: snap=0.000051 sec, exc=0.000051 sec, total=0.000051 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000006 sec of elapsed time Sampled Session History of session 219 serial 5455 --------------------------------------------------- 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 --------------------------------------------------- [1 sample, 08:15:23] not in wait at each sample [1 sample, 08:15:22] idle wait at each sample [session created at: 08:15:23] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-16 08:15:24.102 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 27 (osid: 12656, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-16 01:59:50.771050 :B2908577:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12636 pso_num=27 pso_serial#=183 2025-07-16 01:59:50.771191 :B2908578:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=12636 (legacy spawn) 2025-07-16 01:59:50.777679 :B2908579:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 01:59:50.777863 :B290857E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-16 01:59:50.778730 :B2908583:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-16 01:59:53.900279 :B2908672:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-16 01:59:53.886 2025-07-16 01:59:53.900281 :B2908673:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=700 pso_num=27 pso_serial#=184 2025-07-16 01:59:53.900417 :B2908674:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=700 (legacy spawn) 2025-07-16 01:59:53.906954 :B2908675:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 01:59:53.907127 :B290867A:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-16 01:59:53.907934 :B290867F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-16 01:59:53.916595 :B2908682:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-16 01:59:53.902 2025-07-16 01:59:53.916597 :B2908683:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=8372 pso_num=27 pso_serial#=185 2025-07-16 01:59:53.916730 :B2908684:db_trace:kso.c@4070:ksonfy(): [10420:27:0] kso: new process: pid=8372 (legacy spawn) 2025-07-16 01:59:53.923343 :B2908685:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 01:59:53.923511 :B290868E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-16 01:59:53.924477 :B290868F:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-16 02:00:00.020978 :B29088A1:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-16 02:00:00.011 2025-07-16 02:00:00.020980 :B29088A2:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11700 pso_num=27 pso_serial#=186 2025-07-16 02:00:00.021117 :B29088A3:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=11700 osp_idx=25 osp_ver=166454 osp_pg=0 (spawn #389726) 2025-07-16 02:00:00.021118 :B29088A4:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 02:00:00.021119 :B29088A5:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=195 time=1311975908 2025-07-16 02:00:00.021119 :B29088A6:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 02:00:00.021119 :B29088A7:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 02:00:00.021120 :B29088A8:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 02:00:00.021120 :B29088A9:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 02:00:00.021121 :B29088AA:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 02:00:00.021631 :B29088AB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-16 02:00:00.021678 :B29088B2:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-16 02:00:00.021754 :B29088C6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-16 02:00:00.025583 :B29088D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:00.077116 :B29088E0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:00.081748 :B29088E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:00.083127 :B29088F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:00.083568 :B29088F8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:00.083587 :B29088FB:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-16 02:00:00.083627 :B2908906:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-16 02:00:00.084254 :B2908911:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:00.084266 :B2908914:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-16 02:00:00.084290 :B2908920:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-16 02:00:00.085104 :B290892A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:00.085117 :B290892D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='ksr2.h LINE:603 ID:ksrpublish' id1=0 id2=0 name= type=0 2025-07-16 02:00:00.085142 :B2908938:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksr2.h LINE:607 ID:ksrmdone' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-16 02:00:00.085188 :B2908944:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=14 loc='ksl2.h LINE:2165 ID:kslpsr' id1=134 id2=0 name=EV type=0 fac#=0 facpost=1 2025-07-16 02:00:00.086378 :B2908945:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-16 02:00:03.134217 :B2908AEC:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-16 02:00:03.125 2025-07-16 02:00:03.134220 :B2908AED:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=15428 pso_num=27 pso_serial#=187 2025-07-16 02:00:03.134354 :B2908AEE:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=15428 osp_idx=25 osp_ver=166455 osp_pg=0 (spawn #389728) 2025-07-16 02:00:03.134355 :B2908AEF:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 02:00:03.134355 :B2908AF0:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=196 time=1311979017 2025-07-16 02:00:03.134356 :B2908AF1:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 02:00:03.134356 :B2908AF2:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 02:00:03.134356 :B2908AF3:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 02:00:03.134357 :B2908AF4:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 02:00:03.134357 :B2908AF5:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 02:00:03.134861 :B2908AF6:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-16 02:00:03.134906 :B2908AFC:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-16 02:00:03.142141 :B2908B0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:03.143198 :B2908B12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:03.143773 :B2908B1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:03.144225 :B2908B22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:03.144655 :B2908B2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:03.147240 :B2908B32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:03.148361 :B2908B3A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:03.198306 :B2908B42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:03.199273 :B2908B4A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:03.199518 :B2908B52:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:03.199749 :B2908B5A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 02:00:03.201479 :B2908B62:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-16 03:00:10.844510 :B29539E4:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-16 03:00:10.838 2025-07-16 03:00:10.844512 :B29539E5:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17676 pso_num=27 pso_serial#=188 2025-07-16 03:00:10.844645 :B29539E6:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17676 osp_idx=25 osp_ver=166456 osp_pg=0 (spawn #389867) 2025-07-16 03:00:10.844646 :B29539E7:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 03:00:10.844646 :B29539E8:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=197 time=1315586720 2025-07-16 03:00:10.844646 :B29539E9:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 03:00:10.844647 :B29539EA:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 03:00:10.844647 :B29539EB:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 03:00:10.844647 :B29539EC:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 03:00:10.844648 :B29539ED:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 03:00:10.845131 :B29539EE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-16 03:00:10.845178 :B29539F4:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-16 03:00:10.852958 :B2953A02:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 03:00:10.854057 :B2953A0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 03:00:10.854684 :B2953A12:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 03:00:10.855139 :B2953A1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 03:00:10.855576 :B2953A22:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 03:00:10.858034 :B2953A2A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 03:00:10.859187 :B2953A32:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 03:00:10.908432 :B2953A3A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 03:00:10.909322 :B2953A42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 03:00:10.909565 :B2953A4A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 03:00:10.909779 :B2953A52:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 03:00:10.911621 :B2953A5A:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-16 04:00:18.802164 :B299E5FE:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-16 04:00:18.801 2025-07-16 04:00:18.802166 :B299E5FF:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=11784 pso_num=27 pso_serial#=189 2025-07-16 04:00:18.802304 :B299E600:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=11784 osp_idx=25 osp_ver=166457 osp_pg=0 (spawn #390005) 2025-07-16 04:00:18.802305 :B299E601:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 04:00:18.802305 :B299E602:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=198 time=1319194689 2025-07-16 04:00:18.802305 :B299E603:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 04:00:18.802306 :B299E604:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 04:00:18.802306 :B299E605:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 04:00:18.802307 :B299E606:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 04:00:18.802307 :B299E607:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 04:00:18.802795 :B299E608:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-16 04:00:18.802838 :B299E60E:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-16 04:00:18.810003 :B299E61C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 04:00:18.811064 :B299E624:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 04:00:18.811611 :B299E62C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 04:00:18.812114 :B299E634:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 04:00:18.812581 :B299E63C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 04:00:18.815037 :B299E644:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 04:00:18.816090 :B299E64C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 04:00:18.865401 :B299E654:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 04:00:18.866289 :B299E65C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 04:00:18.866536 :B299E664:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 04:00:18.866749 :B299E66C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 04:00:18.868542 :B299E674:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-16 05:00:27.136739 :B29E900C:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-16 05:00:27.121 2025-07-16 05:00:27.136741 :B29E900D:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=16748 pso_num=27 pso_serial#=190 2025-07-16 05:00:27.136874 :B29E900E:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=16748 osp_idx=25 osp_ver=166458 osp_pg=0 (spawn #390144) 2025-07-16 05:00:27.136875 :B29E900F:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 05:00:27.136875 :B29E9010:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=199 time=1322803017 2025-07-16 05:00:27.136876 :B29E9011:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 05:00:27.136876 :B29E9012:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 05:00:27.136877 :B29E9013:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 05:00:27.136877 :B29E9014:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 05:00:27.136877 :B29E9015:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 05:00:27.137422 :B29E9016:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-16 05:00:27.137463 :B29E901C:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-16 05:00:27.145191 :B29E902A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 05:00:27.146305 :B29E9032:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 05:00:27.146891 :B29E903A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 05:00:27.147371 :B29E9042:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 05:00:27.147802 :B29E904A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 05:00:27.150344 :B29E9052:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 05:00:27.151417 :B29E905A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 05:00:27.200977 :B29E9062:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 05:00:27.201848 :B29E906A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 05:00:27.202083 :B29E9072:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 05:00:27.202307 :B29E907A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 05:00:27.204243 :B29E9082:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-16 06:00:34.985714 :B2A33984:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-16 06:00:34.972 2025-07-16 06:00:34.985716 :B2A33985:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=17148 pso_num=27 pso_serial#=191 2025-07-16 06:00:34.985854 :B2A33986:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=17148 osp_idx=25 osp_ver=166459 osp_pg=0 (spawn #390282) 2025-07-16 06:00:34.985855 :B2A33987:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 06:00:34.985855 :B2A33988:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=200 time=1326410876 2025-07-16 06:00:34.985856 :B2A33989:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 06:00:34.985856 :B2A3398A:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 06:00:34.985857 :B2A3398B:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 06:00:34.985857 :B2A3398C:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 06:00:34.985857 :B2A3398D:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 06:00:34.986365 :B2A3398E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-16 06:00:34.986411 :B2A33994:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-16 06:00:34.993562 :B2A339A2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 06:00:34.994627 :B2A339AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 06:00:34.995184 :B2A339B2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 06:00:34.995655 :B2A339BA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 06:00:34.996097 :B2A339C2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 06:00:34.998573 :B2A339CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 06:00:34.999650 :B2A339D2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 06:00:35.048901 :B2A339DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 06:00:35.049789 :B2A339E2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 06:00:35.050104 :B2A339EA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 06:00:35.050336 :B2A339F2:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-07-16 06:00:35.052161 :B2A339FA:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-16 08:15:21.763008 :B2ADB56B:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-16 08:15:21.748 2025-07-16 08:15:21.763010 :B2ADB56C:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12112 pso_num=27 pso_serial#=192 2025-07-16 08:15:21.763172 :B2ADB56D:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=12112 osp_idx=25 osp_ver=166460 osp_pg=0 (spawn #390597) 2025-07-16 08:15:21.763173 :B2ADB56E:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 08:15:21.763173 :B2ADB56F:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=201 time=1334497642 2025-07-16 08:15:21.763174 :B2ADB570:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 08:15:21.763174 :B2ADB571:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 08:15:21.763175 :B2ADB572:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 08:15:21.763175 :B2ADB573:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 08:15:21.763175 :B2ADB574:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 08:15:21.764017 :B2ADB575:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-16 08:15:21.764063 :B2ADB57B:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-07-16 08:15:21.886160 :B2ADB5AD:db_trace:kst.c@698:kstpsodel(): [10280:27:0] kst: process state object about to be deleted 2025-07-16 08:15:23.989130 :B2ADB673:db_trace:kst.c@673:kstipg(): [10280:27:0] kst: process state object created on 07-16 08:15:23.977 2025-07-16 08:15:23.989132 :B2ADB674:db_trace:kst.c@679:kstipg(): [10280:27:0] kst: process info: ospid=12656 pso_num=27 pso_serial#=193 2025-07-16 08:15:23.989275 :B2ADB675:db_trace:kso.c@4093:ksonfy(): [10420:27:0] kso: new process: pid=12656 osp_idx=25 osp_ver=166461 osp_pg=0 (spawn #390599) 2025-07-16 08:15:23.989276 :B2ADB676:db_trace:kso.c@4099:ksonfy(): [10420:27:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-16 08:15:23.989276 :B2ADB677:db_trace:kso.c@4103:ksonfy(): [10420:27:0] kso: new process: pso_index=27 reservation=202 time=1334499876 2025-07-16 08:15:23.989276 :B2ADB678:db_trace:kso.c@4109:ksonfy(): [10420:27:0] kso: spawn diagnostics: queue time=0 secs 2025-07-16 08:15:23.989277 :B2ADB679:db_trace:kso.c@4111:ksonfy(): [10420:27:0] kso: spawn diagnostics: fork time=0 secs 2025-07-16 08:15:23.989277 :B2ADB67A:db_trace:kso.c@4113:ksonfy(): [10420:27:0] kso: spawn diagnostics: exec time=0 secs 2025-07-16 08:15:23.989278 :B2ADB67B:db_trace:kso.c@4115:ksonfy(): [10420:27:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-16 08:15:23.989278 :B2ADB67C:db_trace:kso.c@4117:ksonfy(): [10420:27:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-16 08:15:23.989782 :B2ADB67D:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:27:219] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-16 08:15:23.989823 :B2ADB683:db_trace:ksl2.c@12699:ksliwat(): [10005:27:219] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 27 (osid: 12656, M002)