Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250905220004\orcl_w000_19336_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250905220004\orcl_w000_19336_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:35014M/63366M, Ph+PgF:39856M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 19336, image: ORACLE.EXE (W000) *** 2025-09-05 22:00:05.076 *** SESSION ID:(177.65183) 2025-09-05 22:00:05.076 *** 2025-09-05 22:00:05.076 Process diagnostic dump for ORACLE.EXE (W000), OS id=19336, pid: 22, proc_ser: 4, sid: 177, sess_ser: 65183 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=69 seq_num=70 snap_id=1 wait times: snap=3.179350 sec, exc=3.179350 sec, total=3.179350 sec wait times: max=5.000000 sec, heur=5 min 43 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.000034 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=68 seq_num=69 snap_id=1 wait times: snap=5.000305 sec, exc=5.000305 sec, total=5.000305 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000031 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=67 seq_num=68 snap_id=1 wait times: snap=5.000299 sec, exc=5.000299 sec, total=5.000299 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000036 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=66 seq_num=67 snap_id=1 wait times: snap=5.000236 sec, exc=5.000236 sec, total=5.000236 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000035 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=65 seq_num=66 snap_id=1 wait times: snap=5.012510 sec, exc=5.012510 sec, total=5.012510 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000085 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=64 seq_num=65 snap_id=1 wait times: snap=5.014725 sec, exc=5.014725 sec, total=5.014725 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000032 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=63 seq_num=64 snap_id=1 wait times: snap=5.004624 sec, exc=5.004624 sec, total=5.004624 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000037 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=62 seq_num=63 snap_id=1 wait times: snap=5.009717 sec, exc=5.009717 sec, total=5.009717 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=61 seq_num=62 snap_id=1 wait times: snap=5.003186 sec, exc=5.003186 sec, total=5.003186 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000039 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=60 seq_num=61 snap_id=1 wait times: snap=5.000784 sec, exc=5.000784 sec, total=5.000784 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=59 seq_num=60 snap_id=1 wait times: snap=5.002270 sec, exc=5.002270 sec, total=5.002270 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000036 sec of elapsed time Sampled Session History of session 177 serial 65183 --------------------------------------------------- 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 --------------------------------------------------- [121 samples, 21:58:05 - 22:00:05] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-05 22:00:05.076 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 19336, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-05 19:34:04.705945 :C9728FB5:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 19:34:04.705946 :C9728FB6:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 19:34:04.706441 :C9728FB7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-05 19:34:04.706488 :C9728FBE:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 19:44:05.506547 :C97357C5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-05 19:44:05.808291 :C9735802:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-05 19:44:05.803 2025-09-05 19:44:05.808293 :C9735803:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22840 pso_num=22 pso_serial#=245 2025-09-05 19:44:05.808486 :C9735804:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22840 osp_idx=0 osp_ver=579932 osp_pg=0 (spawn #562166) 2025-09-05 19:44:05.808487 :C9735805:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-05 19:44:05.808488 :C9735806:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=160 time=1487097112 2025-09-05 19:44:05.808488 :C9735807:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 19:44:05.808489 :C9735808:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 19:44:05.808489 :C9735809:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 19:44:05.808489 :C973580A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 19:44:05.808490 :C973580B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 19:44:05.808999 :C973580C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-05 19:44:05.809051 :C9735812:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 19:54:06.681702 :C9742086:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-05 19:54:06.983551 :C97420BF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-05 19:54:06.978 2025-09-05 19:54:06.983554 :C97420C0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24184 pso_num=22 pso_serial#=246 2025-09-05 19:54:06.983698 :C97420C1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24184 osp_idx=0 osp_ver=579933 osp_pg=0 (spawn #562189) 2025-09-05 19:54:06.983698 :C97420C2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-05 19:54:06.983699 :C97420C3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=161 time=1487698283 2025-09-05 19:54:06.983699 :C97420C4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 19:54:06.983700 :C97420C5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 19:54:06.983700 :C97420C6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 19:54:06.983700 :C97420C7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 19:54:06.983701 :C97420C8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 19:54:06.984231 :C97420C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-05 19:54:06.984282 :C97420D2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 20:04:07.840082 :C974EB79:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-05 20:04:08.188680 :C974EBBF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-05 20:04:08.183 2025-09-05 20:04:08.188682 :C974EBC0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21560 pso_num=22 pso_serial#=247 2025-09-05 20:04:08.188819 :C974EBC1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21560 osp_idx=0 osp_ver=579934 osp_pg=0 (spawn #562215) 2025-09-05 20:04:08.188819 :C974EBC2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-05 20:04:08.188820 :C974EBC3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=162 time=1488299487 2025-09-05 20:04:08.188820 :C974EBC4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 20:04:08.188821 :C974EBC5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 20:04:08.188821 :C974EBC6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 20:04:08.188821 :C974EBC7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 20:04:08.188821 :C974EBC8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 20:04:08.189345 :C974EBC9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-05 20:04:08.189398 :C974EBD3:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 20:14:09.089095 :C975BEA1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-05 20:14:09.406309 :C975BED6:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-05 20:14:09.401 2025-09-05 20:14:09.406311 :C975BED7:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22264 pso_num=22 pso_serial#=248 2025-09-05 20:14:09.406448 :C975BED8:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22264 osp_idx=0 osp_ver=579935 osp_pg=0 (spawn #562237) 2025-09-05 20:14:09.406449 :C975BED9:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-05 20:14:09.406449 :C975BEDA:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=163 time=1488900705 2025-09-05 20:14:09.406450 :C975BEDB:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 20:14:09.406450 :C975BEDC:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 20:14:09.406450 :C975BEDD:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 20:14:09.406451 :C975BEDE:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 20:14:09.406451 :C975BEDF:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 20:14:09.406941 :C975BEE0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-05 20:14:09.406988 :C975BEE9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 20:24:10.192844 :C976868D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-05 20:24:10.525753 :C97686D0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-05 20:24:10.521 2025-09-05 20:24:10.525755 :C97686D1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7572 pso_num=22 pso_serial#=249 2025-09-05 20:24:10.525889 :C97686D2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7572 osp_idx=0 osp_ver=579936 osp_pg=0 (spawn #562260) 2025-09-05 20:24:10.525889 :C97686D3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-05 20:24:10.525890 :C97686D4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=164 time=1489501830 2025-09-05 20:24:10.525890 :C97686D5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 20:24:10.525890 :C97686D6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 20:24:10.525891 :C97686D7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 20:24:10.525891 :C97686D8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 20:24:10.525892 :C97686D9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 20:24:10.526364 :C97686DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-05 20:24:10.526407 :C97686E2:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 20:34:11.248491 :C9774FC6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-05 20:34:11.706297 :C9774FF8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-05 20:34:11.701 2025-09-05 20:34:11.706299 :C9774FF9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23432 pso_num=22 pso_serial#=250 2025-09-05 20:34:11.706433 :C9774FFA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23432 osp_idx=0 osp_ver=579937 osp_pg=0 (spawn #562283) 2025-09-05 20:34:11.706434 :C9774FFB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-05 20:34:11.706435 :C9774FFC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=165 time=1490103018 2025-09-05 20:34:11.706436 :C9774FFD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 20:34:11.706436 :C9774FFE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 20:34:11.706436 :C9774FFF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 20:34:11.706437 :C9775000:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 20:34:11.706437 :C9775001:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 20:34:11.706974 :C9775002:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-05 20:34:11.707019 :C9775009:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 20:44:12.505861 :C9781900:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-05 20:44:12.948460 :C9781947:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-05 20:44:12.943 2025-09-05 20:44:12.948462 :C9781948:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23280 pso_num=22 pso_serial#=251 2025-09-05 20:44:12.948607 :C9781949:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23280 osp_idx=0 osp_ver=579938 osp_pg=0 (spawn #562305) 2025-09-05 20:44:12.948608 :C978194A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-05 20:44:12.948609 :C978194B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=166 time=1490704252 2025-09-05 20:44:12.948609 :C978194C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 20:44:12.948610 :C978194D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 20:44:12.948610 :C978194E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 20:44:12.948611 :C978194F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 20:44:12.948611 :C9781950:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 20:44:12.949151 :C9781951:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-05 20:44:12.949203 :C978195B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 20:54:13.777857 :C978E20C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-05 20:54:14.188834 :C978E248:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-05 20:54:14.183 2025-09-05 20:54:14.188838 :C978E249:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22768 pso_num=22 pso_serial#=252 2025-09-05 20:54:14.188972 :C978E24A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22768 osp_idx=0 osp_ver=579939 osp_pg=0 (spawn #562329) 2025-09-05 20:54:14.188972 :C978E24B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-05 20:54:14.188972 :C978E24C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=167 time=1491305487 2025-09-05 20:54:14.188973 :C978E24D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 20:54:14.188973 :C978E24E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 20:54:14.188973 :C978E24F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 20:54:14.188974 :C978E250:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 20:54:14.188974 :C978E251:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 20:54:14.189458 :C978E252:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-05 20:54:14.189506 :C978E259:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 21:04:15.050683 :C979AD9E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-05 21:04:15.189420 :C979ADA1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-05 21:04:15.175 2025-09-05 21:04:15.189423 :C979ADA2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=6020 pso_num=22 pso_serial#=253 2025-09-05 21:04:15.189570 :C979ADA3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=6020 (legacy spawn) 2025-09-05 21:04:15.196337 :C979ADA4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-05 21:04:15.196505 :C979ADA9:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-05 21:04:15.197358 :C979ADAE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-05 21:04:15.206686 :C979ADB1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-05 21:04:15.207 2025-09-05 21:04:15.206688 :C979ADB2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18748 pso_num=22 pso_serial#=254 2025-09-05 21:04:15.206838 :C979ADB3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18748 (legacy spawn) 2025-09-05 21:04:15.213781 :C979ADB4:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-09-05 21:04:15.213954 :C979ADBC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] 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-09-05 21:04:15.214872 :C979ADBE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-05 21:04:15.477247 :C979ADF9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-05 21:04:15.472 2025-09-05 21:04:15.477249 :C979ADFA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21936 pso_num=22 pso_serial#=255 2025-09-05 21:04:15.477388 :C979ADFB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=21936 osp_idx=0 osp_ver=579942 osp_pg=0 (spawn #562355) 2025-09-05 21:04:15.477388 :C979ADFC:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-05 21:04:15.477389 :C979ADFD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=168 time=1491906783 2025-09-05 21:04:15.477389 :C979ADFE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:04:15.477390 :C979ADFF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:04:15.477390 :C979AE00:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:04:15.477390 :C979AE01:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:04:15.477391 :C979AE02:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:04:15.477883 :C979AE03:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-05 21:04:15.477929 :C979AE0B:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 21:14:16.283095 :C97A7BB8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-05 21:14:16.756588 :C97A7C0C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-05 21:14:16.752 2025-09-05 21:14:16.756589 :C97A7C0D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23428 pso_num=22 pso_serial#=1 2025-09-05 21:14:16.756721 :C97A7C0E:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23428 osp_idx=0 osp_ver=579943 osp_pg=0 (spawn #562377) 2025-09-05 21:14:16.756721 :C97A7C0F:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-05 21:14:16.756722 :C97A7C10:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=169 time=1492508065 2025-09-05 21:14:16.756722 :C97A7C11:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:14:16.756723 :C97A7C12:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:14:16.756723 :C97A7C13:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:14:16.756723 :C97A7C14:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:14:16.756724 :C97A7C15:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:14:16.757208 :C97A7C16:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-05 21:14:16.757261 :C97A7C20:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 21:20:33.307714 :C97AF9FC:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=16 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-05 21:24:17.970365 :C97B4729:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksl2.h LINE:2165 ID:kslpsr' id1=255 id2=0 name=EV type=0 fac#=0 facpost=1 2025-09-05 21:34:18.684588 :C97C0FCA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-05 21:34:19.158124 :C97C1026:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-05 21:34:19.153 2025-09-05 21:34:19.158126 :C97C1027:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22064 pso_num=22 pso_serial#=2 2025-09-05 21:34:19.158326 :C97C1028:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22064 osp_idx=0 osp_ver=579944 osp_pg=0 (spawn #562422) 2025-09-05 21:34:19.158327 :C97C1029:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-05 21:34:19.158327 :C97C102A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=170 time=1493710455 2025-09-05 21:34:19.158328 :C97C102B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:34:19.158328 :C97C102C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:34:19.158328 :C97C102D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:34:19.158329 :C97C102E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:34:19.158329 :C97C102F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:34:19.158838 :C97C1030:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-05 21:34:19.158889 :C97C103A:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 21:44:20.048125 :C97CD8A8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-05 21:44:20.318511 :C97CD8E5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-05 21:44:20.313 2025-09-05 21:44:20.318514 :C97CD8E6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22672 pso_num=22 pso_serial#=3 2025-09-05 21:44:20.318653 :C97CD8E7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=22672 osp_idx=0 osp_ver=579945 osp_pg=0 (spawn #562444) 2025-09-05 21:44:20.318653 :C97CD8E8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-05 21:44:20.318654 :C97CD8E9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=171 time=1494311627 2025-09-05 21:44:20.318654 :C97CD8EA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:44:20.318655 :C97CD8EB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:44:20.318655 :C97CD8EC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:44:20.318655 :C97CD8ED:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:44:20.318656 :C97CD8EE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:44:20.319147 :C97CD8EF:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-05 21:44:20.319194 :C97CD8F8:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 loc='ksv2.h LINE:1639 ID:ksvpst: run' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-09-05 21:54:21.085459 :C97DA0EC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-05 21:54:21.559400 :C97DA152:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-05 21:54:21.554 2025-09-05 21:54:21.559401 :C97DA153:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19336 pso_num=22 pso_serial#=4 2025-09-05 21:54:21.559543 :C97DA154:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19336 osp_idx=0 osp_ver=579946 osp_pg=0 (spawn #562467) 2025-09-05 21:54:21.559544 :C97DA155:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-05 21:54:21.559544 :C97DA156:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=172 time=1494912862 2025-09-05 21:54:21.559545 :C97DA157:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-05 21:54:21.559545 :C97DA158:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-05 21:54:21.559545 :C97DA159:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-05 21:54:21.559546 :C97DA15A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-05 21:54:21.559546 :C97DA15B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-05 21:54:21.560139 :C97DA15C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-05 21:54:21.560193 :C97DA166:db_trace:ksl2.c@12699:ksliwat(): [10005:22:177] KSL POST RCVD poster=23 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 22 (osid: 19336, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de