Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250613222503\orcl_m002_14440_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:36720M/63366M, Ph+PgF:43226M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 26 Windows thread id: 14440, image: ORACLE.EXE (M002) *** 2025-06-13 22:25:03.489 *** SESSION ID:(209.39723) 2025-06-13 22:25:03.489 *** 2025-06-13 22:25:03.489 Process diagnostic dump for ORACLE.EXE (M002), OS id=14440, pid: 26, proc_ser: 192, sid: 209, sess_ser: 39723 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=690 seq_num=691 snap_id=1 wait times: snap=0.000040 sec, exc=0.000040 sec, total=0.000040 sec wait times: max=infinite, heur=0.019990 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x21 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000007 sec since current wait 0: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=690 seq_num=691 snap_id=1 wait times: snap=0.000090 sec, exc=0.000090 sec, total=0.000090 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 1: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=689 seq_num=690 snap_id=1 wait times: snap=0.000090 sec, exc=0.000090 sec, total=0.000090 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=688 seq_num=689 snap_id=1 wait times: snap=0.000092 sec, exc=0.000092 sec, total=0.000092 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 3: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=687 seq_num=688 snap_id=1 wait times: snap=0.000094 sec, exc=0.000094 sec, total=0.000094 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time 4: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=686 seq_num=687 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.000006 sec of elapsed time 5: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=685 seq_num=686 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.000005 sec of elapsed time 6: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=684 seq_num=685 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.000007 sec of elapsed time 7: waited for 'ADR block file read' =0x0, =0x0, =0x0 wait_id=693 seq_num=694 snap_id=1 wait times: snap=0.000089 sec, exc=0.000089 sec, total=0.000089 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=692 seq_num=693 snap_id=1 wait times: snap=0.000090 sec, exc=0.000090 sec, total=0.000090 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=691 seq_num=692 snap_id=1 wait times: snap=0.000088 sec, exc=0.000088 sec, total=0.000088 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000007 sec of elapsed time Sampled Session History of session 209 serial 39723 --------------------------------------------------- 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, 22:25:03] idle wait at each sample [session created at: 22:25:03] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-13 22:25:03.489 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 26 (osid: 14440, M002) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-13 22:11:06.455665 :A44CD088:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16544 osp_idx=24 osp_ver=444387 osp_pg=0 (spawn #282504) 2025-06-13 22:11:06.455666 :A44CD089:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:11:06.455667 :A44CD08A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=75 time=2828409641 2025-06-13 22:11:06.455667 :A44CD08B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:11:06.455668 :A44CD08C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:11:06.455669 :A44CD08D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:11:06.455669 :A44CD08E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:11:06.455669 :A44CD08F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:11:06.456209 :A44CD090:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:11:36.578676 :A44CDA3C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-13 22:12:06.537408 :A44CE3DB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-13 22:12:06.525 2025-06-13 22:12:06.537411 :A44CE3DC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=17352 pso_num=26 pso_serial#=178 2025-06-13 22:12:06.537525 :A44CE3DD:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=17352 osp_idx=24 osp_ver=444388 osp_pg=0 (spawn #282506) 2025-06-13 22:12:06.537526 :A44CE3DE:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:12:06.537527 :A44CE3DF:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=76 time=2828469719 2025-06-13 22:12:06.537527 :A44CE3E0:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:12:06.537528 :A44CE3E1:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:12:06.537528 :A44CE3E2:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:12:06.537529 :A44CE3E3:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:12:06.537529 :A44CE3E4:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:12:06.538038 :A44CE3E5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:12:36.631934 :A44CED91:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-13 22:13:06.607361 :A44CF71A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-13 22:13:06.596 2025-06-13 22:13:06.607363 :A44CF71B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14604 pso_num=26 pso_serial#=179 2025-06-13 22:13:06.607503 :A44CF71C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14604 osp_idx=24 osp_ver=444389 osp_pg=0 (spawn #282508) 2025-06-13 22:13:06.607503 :A44CF71D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:13:06.607504 :A44CF71E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=77 time=2828529797 2025-06-13 22:13:06.607504 :A44CF71F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:13:06.607505 :A44CF720:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:13:06.607505 :A44CF721:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:13:06.607505 :A44CF722:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:13:06.607506 :A44CF723:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:13:06.608005 :A44CF724:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:13:36.658042 :A44D018A:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-13 22:14:06.686371 :A44D0B38:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-13 22:14:06.675 2025-06-13 22:14:06.686373 :A44D0B39:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16088 pso_num=26 pso_serial#=180 2025-06-13 22:14:06.686510 :A44D0B3A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16088 osp_idx=24 osp_ver=444390 osp_pg=0 (spawn #282512) 2025-06-13 22:14:06.686511 :A44D0B3B:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:14:06.686511 :A44D0B3C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=78 time=2828589875 2025-06-13 22:14:06.686512 :A44D0B3D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:14:06.686512 :A44D0B3E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:14:06.686513 :A44D0B3F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:14:06.686513 :A44D0B40:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:14:06.686513 :A44D0B41:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:14:06.686998 :A44D0B42:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:14:36.773375 :A44D14F2:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-13 22:15:06.765410 :A44D1E76:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-13 22:15:06.754 2025-06-13 22:15:06.765412 :A44D1E77:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14440 pso_num=26 pso_serial#=181 2025-06-13 22:15:06.765541 :A44D1E78:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14440 osp_idx=24 osp_ver=444391 osp_pg=0 (spawn #282514) 2025-06-13 22:15:06.765542 :A44D1E79:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:15:06.765542 :A44D1E7A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=79 time=2828649954 2025-06-13 22:15:06.765543 :A44D1E7B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:15:06.765543 :A44D1E7C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:15:06.765543 :A44D1E7D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:15:06.765544 :A44D1E7E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:15:06.765544 :A44D1E7F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:15:06.766044 :A44D1E80:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:15:36.842471 :A44D28A7:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-13 22:16:06.857656 :A44D324A:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-13 22:16:06.847 2025-06-13 22:16:06.857658 :A44D324B:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16048 pso_num=26 pso_serial#=182 2025-06-13 22:16:06.857806 :A44D324C:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16048 osp_idx=24 osp_ver=444392 osp_pg=0 (spawn #282516) 2025-06-13 22:16:06.857807 :A44D324D:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:16:06.857807 :A44D324E:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=80 time=2828710047 2025-06-13 22:16:06.857807 :A44D324F:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:16:06.857808 :A44D3250:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:16:06.857809 :A44D3251:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:16:06.857809 :A44D3252:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:16:06.857809 :A44D3253:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:16:06.858288 :A44D3254:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:16:31.876305 :A44D3A37:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-13 22:17:01.949865 :A44D43AC:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-13 22:17:06.934662 :A44D458E:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-13 22:17:06.923 2025-06-13 22:17:06.934664 :A44D458F:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14524 pso_num=26 pso_serial#=183 2025-06-13 22:17:06.934792 :A44D4590:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14524 osp_idx=24 osp_ver=444393 osp_pg=0 (spawn #282518) 2025-06-13 22:17:06.934793 :A44D4591:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:17:06.934794 :A44D4592:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=81 time=2828770125 2025-06-13 22:17:06.934795 :A44D4593:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:17:06.934795 :A44D4594:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:17:06.934796 :A44D4595:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:17:06.934796 :A44D4596:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:17:06.934796 :A44D4597:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:17:06.935310 :A44D4598:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:17:36.964397 :A44D4F57:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-13 22:18:07.008033 :A44D593D:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-13 22:18:06.997 2025-06-13 22:18:07.008035 :A44D593E:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=8352 pso_num=26 pso_serial#=184 2025-06-13 22:18:07.008171 :A44D593F:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=8352 osp_idx=24 osp_ver=444394 osp_pg=0 (spawn #282520) 2025-06-13 22:18:07.008171 :A44D5940:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:18:07.008172 :A44D5941:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=82 time=2828830188 2025-06-13 22:18:07.008172 :A44D5942:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:18:07.008173 :A44D5943:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:18:07.008173 :A44D5944:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:18:07.008173 :A44D5945:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:18:07.008173 :A44D5946:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:18:07.008710 :A44D5947:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:18:37.101024 :A44D62DE:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-13 22:19:07.104082 :A44D6C36:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-13 22:19:07.093 2025-06-13 22:19:07.104085 :A44D6C37:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=13176 pso_num=26 pso_serial#=185 2025-06-13 22:19:07.104216 :A44D6C38:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=13176 osp_idx=24 osp_ver=444395 osp_pg=0 (spawn #282522) 2025-06-13 22:19:07.104216 :A44D6C39:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:19:07.104217 :A44D6C3A:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=83 time=2828890282 2025-06-13 22:19:07.104217 :A44D6C3B:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:19:07.104218 :A44D6C3C:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:19:07.104218 :A44D6C3D:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:19:07.104218 :A44D6C3E:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:19:07.104219 :A44D6C3F:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:19:07.104690 :A44D6C40:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:19:37.213862 :A44D75DF:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-13 22:20:07.202432 :A44D7F57:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-13 22:20:07.191 2025-06-13 22:20:07.202434 :A44D7F58:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12296 pso_num=26 pso_serial#=186 2025-06-13 22:20:07.202558 :A44D7F59:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12296 osp_idx=24 osp_ver=444396 osp_pg=0 (spawn #282524) 2025-06-13 22:20:07.202559 :A44D7F5A:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:20:07.202559 :A44D7F5B:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=84 time=2828950391 2025-06-13 22:20:07.202560 :A44D7F5C:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:20:07.202560 :A44D7F5D:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:20:07.202560 :A44D7F5E:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:20:07.202561 :A44D7F5F:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:20:07.202561 :A44D7F60:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:20:07.203057 :A44D7F61:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:20:37.267542 :A44D89BB:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-13 22:21:07.266547 :A44D93C2:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-13 22:21:07.255 2025-06-13 22:21:07.266549 :A44D93C3:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12276 pso_num=26 pso_serial#=187 2025-06-13 22:21:07.266713 :A44D93C4:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12276 osp_idx=24 osp_ver=444397 osp_pg=0 (spawn #282527) 2025-06-13 22:21:07.266713 :A44D93C5:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:21:07.266713 :A44D93C6:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=85 time=2829010454 2025-06-13 22:21:07.266714 :A44D93C7:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:21:07.266714 :A44D93C8:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:21:07.266714 :A44D93C9:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:21:07.266715 :A44D93CA:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:21:07.266715 :A44D93CB:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:21:07.267203 :A44D93CC:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:21:37.357568 :A44D9D5E:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-13 22:22:07.375246 :A44DA6E6:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-13 22:22:07.364 2025-06-13 22:22:07.375249 :A44DA6E7:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16420 pso_num=26 pso_serial#=188 2025-06-13 22:22:07.375390 :A44DA6E8:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16420 osp_idx=24 osp_ver=444398 osp_pg=0 (spawn #282529) 2025-06-13 22:22:07.375391 :A44DA6E9:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:22:07.375391 :A44DA6EA:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=86 time=2829070563 2025-06-13 22:22:07.375391 :A44DA6EB:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:22:07.375392 :A44DA6EC:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:22:07.375392 :A44DA6ED:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:22:07.375392 :A44DA6EE:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:22:07.375393 :A44DA6EF:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:22:07.375881 :A44DA6F0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:22:37.428181 :A44DB0A6:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-13 22:23:07.458093 :A44DBA54:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-13 22:23:07.446 2025-06-13 22:23:07.458095 :A44DBA55:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=15680 pso_num=26 pso_serial#=189 2025-06-13 22:23:07.458229 :A44DBA56:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=15680 osp_idx=24 osp_ver=444399 osp_pg=0 (spawn #282531) 2025-06-13 22:23:07.458230 :A44DBA57:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:23:07.458230 :A44DBA58:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=87 time=2829130641 2025-06-13 22:23:07.458231 :A44DBA59:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:23:07.458231 :A44DBA5A:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:23:07.458232 :A44DBA5B:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:23:07.458232 :A44DBA5C:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:23:07.458232 :A44DBA5D:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:23:07.458711 :A44DBA5E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:23:37.524641 :A44DC46C:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-13 22:24:07.529176 :A44DCDF8:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-13 22:24:07.518 2025-06-13 22:24:07.529178 :A44DCDF9:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=12524 pso_num=26 pso_serial#=190 2025-06-13 22:24:07.529312 :A44DCDFA:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=12524 osp_idx=24 osp_ver=444400 osp_pg=0 (spawn #282534) 2025-06-13 22:24:07.529313 :A44DCDFB:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:24:07.529313 :A44DCDFC:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=88 time=2829190719 2025-06-13 22:24:07.529314 :A44DCDFD:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:24:07.529315 :A44DCDFE:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:24:07.529315 :A44DCDFF:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:24:07.529315 :A44DCE00:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:24:07.529316 :A44DCE01:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:24:07.529815 :A44DCE02:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:24:37.582269 :A44DD760:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-13 22:25:02.096080 :A44E18CB:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-13 22:25:02.083 2025-06-13 22:25:02.096082 :A44E18CC:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=16276 pso_num=26 pso_serial#=191 2025-06-13 22:25:02.096238 :A44E18CD:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=16276 osp_idx=24 osp_ver=444401 osp_pg=0 (spawn #282536) 2025-06-13 22:25:02.096239 :A44E18CE:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:25:02.096239 :A44E18CF:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=89 time=2829245282 2025-06-13 22:25:02.096240 :A44E18D0:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:25:02.096240 :A44E18D1:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:25:02.096240 :A44E18D2:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:25:02.096241 :A44E18D3:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:25:02.096241 :A44E18D4:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:25:02.097481 :A44E18D5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:25:02.097524 :A44E18DB:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] KSL POST RCVD poster=18 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-06-13 22:25:02.199813 :A44E1919:db_trace:kst.c@698:kstpsodel(): [10280:26:0] kst: process state object about to be deleted 2025-06-13 22:25:03.395768 :A44E1948:db_trace:kst.c@673:kstipg(): [10280:26:0] kst: process state object created on 06-13 22:25:03.395 2025-06-13 22:25:03.395771 :A44E1949:db_trace:kst.c@679:kstipg(): [10280:26:0] kst: process info: ospid=14440 pso_num=26 pso_serial#=192 2025-06-13 22:25:03.395948 :A44E194A:db_trace:kso.c@4093:ksonfy(): [10420:26:0] kso: new process: pid=14440 osp_idx=24 osp_ver=444402 osp_pg=0 (spawn #282538) 2025-06-13 22:25:03.395948 :A44E194B:db_trace:kso.c@4099:ksonfy(): [10420:26:0] kso: new process: first_req_pid=3704 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-13 22:25:03.395948 :A44E194C:db_trace:kso.c@4103:ksonfy(): [10420:26:0] kso: new process: pso_index=26 reservation=90 time=2829246579 2025-06-13 22:25:03.395949 :A44E194D:db_trace:kso.c@4109:ksonfy(): [10420:26:0] kso: spawn diagnostics: queue time=0 secs 2025-06-13 22:25:03.395949 :A44E194E:db_trace:kso.c@4111:ksonfy(): [10420:26:0] kso: spawn diagnostics: fork time=0 secs 2025-06-13 22:25:03.395949 :A44E194F:db_trace:kso.c@4113:ksonfy(): [10420:26:0] kso: spawn diagnostics: exec time=0 secs 2025-06-13 22:25:03.395950 :A44E1950:db_trace:kso.c@4115:ksonfy(): [10420:26:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-13 22:25:03.395950 :A44E1951:db_trace:kso.c@4117:ksonfy(): [10420:26:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-13 22:25:03.396474 :A44E1952:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:26:209] KSL POST SENT postee=18 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-13 22:25:03.396517 :A44E1958:db_trace:ksl2.c@12699:ksliwat(): [10005:26:209] 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 26 (osid: 14440, M002)