Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250629181045\orcl_w000_17648_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250629181045\orcl_w000_17648_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:36549M/63366M, Ph+PgF:42668M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 25 Windows thread id: 17648, image: ORACLE.EXE (W000) *** 2025-06-29 18:10:45.548 *** SESSION ID:(201.62237) 2025-06-29 18:10:45.548 *** 2025-06-29 18:10:45.548 Process diagnostic dump for ORACLE.EXE (W000), OS id=17648, pid: 25, proc_ser: 135, sid: 201, sess_ser: 62237 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=36 seq_num=37 snap_id=1 wait times: snap=1.317394 sec, exc=1.317394 sec, total=1.317394 sec wait times: max=5.000000 sec, heur=2 min 56 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.000040 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=35 seq_num=36 snap_id=1 wait times: snap=5.000147 sec, exc=5.000147 sec, total=5.000147 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000040 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=34 seq_num=35 snap_id=1 wait times: snap=5.001613 sec, exc=5.001613 sec, total=5.001613 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000031 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=33 seq_num=34 snap_id=1 wait times: snap=5.004645 sec, exc=5.004645 sec, total=5.004645 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=32 seq_num=33 snap_id=1 wait times: snap=5.014724 sec, exc=5.014724 sec, total=5.014724 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=31 seq_num=32 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.000023 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=30 seq_num=31 snap_id=1 wait times: snap=5.008386 sec, exc=5.008386 sec, total=5.008386 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=29 seq_num=30 snap_id=1 wait times: snap=5.000150 sec, exc=5.000150 sec, total=5.000150 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=28 seq_num=29 snap_id=1 wait times: snap=5.006505 sec, exc=5.006505 sec, total=5.006505 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=27 seq_num=28 snap_id=1 wait times: snap=5.004124 sec, exc=5.004124 sec, total=5.004124 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=26 seq_num=27 snap_id=1 wait times: snap=5.004620 sec, exc=5.004620 sec, total=5.004620 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time Sampled Session History of session 201 serial 62237 --------------------------------------------------- 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, 18:08:45 - 18:10:45] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-29 18:10:45.548 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 25 (osid: 17648, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-29 15:47:31.612284 :AB457966:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 15:47:31.612285 :AB457967:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 15:47:31.612285 :AB457968:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 15:47:31.612285 :AB457969:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 15:47:31.612789 :AB45796A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 15:47:31.612839 :AB457971:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 15:57:32.481217 :AB463D02:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 15:57:32.728479 :AB463D11:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 15:57:32.715 2025-06-29 15:57:32.728482 :AB463D12:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=2680 pso_num=25 pso_serial#=122 2025-06-29 15:57:32.728664 :AB463D13:db_trace:kso.c@4070:ksonfy(): [10420:25:0] kso: new process: pid=2680 (legacy spawn) 2025-06-29 15:57:32.735145 :AB463D14:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-06-29 15:57:32.737932 :AB463D1D:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 15:57:32.738858 :AB463D1E:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 15:57:32.751450 :AB463D82:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 15:57:32.746 2025-06-29 15:57:32.751452 :AB463D83:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17940 pso_num=25 pso_serial#=123 2025-06-29 15:57:32.751583 :AB463D84:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17940 osp_idx=23 osp_ver=175930 osp_pg=0 (spawn #335003) 2025-06-29 15:57:32.751584 :AB463D85:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 15:57:32.751584 :AB463D86:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=210 time=4188395938 2025-06-29 15:57:32.751585 :AB463D87:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 15:57:32.751585 :AB463D88:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 15:57:32.751585 :AB463D89:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 15:57:32.751586 :AB463D8A:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 15:57:32.751586 :AB463D8B:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 15:57:32.752096 :AB463D8C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 15:57:32.752153 :AB463D92:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 15:58:17.829844 :AB464BC7:db_trace:ksl2.c@2477:kslwtbctx(): [10005:25:201] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=10 seq_num=11 snap_id=1 2025-06-29 15:58:17.829848 :AB464BC8:db_trace:ksl2.c@2611:kslwtectx(): [10005:25:201] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=10 seq_num=11 snap_id=1 2025-06-29 15:58:17.829849 :AB464BC9:db_trace:ksl2.c@2619:kslwtectx(): [10005:25:201] KSL WAIT END wait times (usecs) - snap=4, exc=4, tot=4 2025-06-29 15:58:17.829858 :AB464BD3:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 16:07:33.616814 :AB470CAF:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 16:07:33.902922 :AB470D00:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 16:07:33.898 2025-06-29 16:07:33.902925 :AB470D01:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=12088 pso_num=25 pso_serial#=124 2025-06-29 16:07:33.903084 :AB470D02:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=12088 osp_idx=23 osp_ver=175931 osp_pg=0 (spawn #335028) 2025-06-29 16:07:33.903084 :AB470D03:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 16:07:33.903085 :AB470D04:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=211 time=4188997094 2025-06-29 16:07:33.903085 :AB470D05:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 16:07:33.903086 :AB470D06:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 16:07:33.903087 :AB470D07:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 16:07:33.903087 :AB470D08:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 16:07:33.903088 :AB470D09:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 16:07:33.903627 :AB470D0A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 16:07:33.903673 :AB470D10:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 16:17:34.771949 :AB47D3D7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 16:17:35.104665 :AB47D43F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 16:17:35.100 2025-06-29 16:17:35.104667 :AB47D440:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17572 pso_num=25 pso_serial#=125 2025-06-29 16:17:35.104802 :AB47D441:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17572 osp_idx=23 osp_ver=175932 osp_pg=0 (spawn #335051) 2025-06-29 16:17:35.104802 :AB47D442:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 16:17:35.104803 :AB47D443:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=212 time=4189598297 2025-06-29 16:17:35.104803 :AB47D444:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 16:17:35.104804 :AB47D445:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 16:17:35.104804 :AB47D446:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 16:17:35.104804 :AB47D447:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 16:17:35.104805 :AB47D448:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 16:17:35.105297 :AB47D449:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 16:17:35.105345 :AB47D451:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 16:27:35.986098 :AB48989A:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 16:27:36.319094 :AB4898F7:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 16:27:36.314 2025-06-29 16:27:36.319096 :AB4898F8:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11976 pso_num=25 pso_serial#=126 2025-06-29 16:27:36.319236 :AB4898F9:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11976 osp_idx=23 osp_ver=175933 osp_pg=0 (spawn #335073) 2025-06-29 16:27:36.319237 :AB4898FA:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 16:27:36.319237 :AB4898FB:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=213 time=4190199516 2025-06-29 16:27:36.319238 :AB4898FC:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 16:27:36.319238 :AB4898FD:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 16:27:36.319239 :AB4898FE:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 16:27:36.319239 :AB4898FF:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 16:27:36.319240 :AB489900:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 16:27:36.319750 :AB489901:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 16:27:36.319794 :AB489908:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 16:30:20.861440 :AB48CE76:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 16:37:37.588316 :AB495F00:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 16:47:38.568046 :AB4A246C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 16:47:38.791543 :AB4A24C9:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 16:47:38.786 2025-06-29 16:47:38.791545 :AB4A24CA:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=18156 pso_num=25 pso_serial#=127 2025-06-29 16:47:38.791681 :AB4A24CB:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=18156 osp_idx=23 osp_ver=175934 osp_pg=0 (spawn #335116) 2025-06-29 16:47:38.791682 :AB4A24CC:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 16:47:38.791682 :AB4A24CD:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=214 time=4191401985 2025-06-29 16:47:38.791682 :AB4A24CE:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 16:47:38.791682 :AB4A24CF:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 16:47:38.791683 :AB4A24D0:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 16:47:38.791683 :AB4A24D1:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 16:47:38.791683 :AB4A24D2:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 16:47:38.792169 :AB4A24D3:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 16:47:38.792214 :AB4A24DB:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 16:57:39.666816 :AB4AE9F1:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 16:57:40.140348 :AB4AEA65:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 16:57:40.135 2025-06-29 16:57:40.140350 :AB4AEA66:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=11568 pso_num=25 pso_serial#=128 2025-06-29 16:57:40.140484 :AB4AEA67:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=11568 osp_idx=23 osp_ver=175935 osp_pg=0 (spawn #335138) 2025-06-29 16:57:40.140485 :AB4AEA68:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 16:57:40.140485 :AB4AEA69:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=215 time=4192003329 2025-06-29 16:57:40.140486 :AB4AEA6A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 16:57:40.140486 :AB4AEA6B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 16:57:40.140486 :AB4AEA6C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 16:57:40.140487 :AB4AEA6D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 16:57:40.140487 :AB4AEA6E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 16:57:40.140968 :AB4AEA6F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 16:57:40.141012 :AB4AEA76:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 17:07:41.045198 :AB4BB73C:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 17:07:41.378060 :AB4BB790:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 17:07:41.373 2025-06-29 17:07:41.378062 :AB4BB791:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14552 pso_num=25 pso_serial#=129 2025-06-29 17:07:41.378197 :AB4BB792:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14552 osp_idx=23 osp_ver=175936 osp_pg=0 (spawn #335164) 2025-06-29 17:07:41.378198 :AB4BB793:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 17:07:41.378198 :AB4BB794:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=216 time=4192604563 2025-06-29 17:07:41.378198 :AB4BB795:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 17:07:41.378199 :AB4BB796:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 17:07:41.378200 :AB4BB797:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 17:07:41.378200 :AB4BB798:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 17:07:41.378201 :AB4BB799:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 17:07:41.378700 :AB4BB79A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 17:07:41.378746 :AB4BB7A2:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 17:17:42.348717 :AB4C7F66:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 17:17:42.619023 :AB4C7FCD:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 17:17:42.614 2025-06-29 17:17:42.619025 :AB4C7FCE:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=13400 pso_num=25 pso_serial#=130 2025-06-29 17:17:42.619163 :AB4C7FCF:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=13400 osp_idx=23 osp_ver=175937 osp_pg=0 (spawn #335187) 2025-06-29 17:17:42.619164 :AB4C7FD0:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 17:17:42.619164 :AB4C7FD1:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=217 time=4193205813 2025-06-29 17:17:42.619165 :AB4C7FD2:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 17:17:42.619165 :AB4C7FD3:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 17:17:42.619165 :AB4C7FD4:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 17:17:42.619166 :AB4C7FD5:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 17:17:42.619166 :AB4C7FD6:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 17:17:42.619663 :AB4C7FD7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 17:17:42.619714 :AB4C7FDE:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 17:27:43.492337 :AB4D43B2:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 17:27:43.856520 :AB4D4424:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 17:27:43.851 2025-06-29 17:27:43.856522 :AB4D4425:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=14232 pso_num=25 pso_serial#=131 2025-06-29 17:27:43.856658 :AB4D4426:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=14232 osp_idx=23 osp_ver=175938 osp_pg=0 (spawn #335209) 2025-06-29 17:27:43.856659 :AB4D4427:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 17:27:43.856659 :AB4D4428:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=218 time=4193807047 2025-06-29 17:27:43.856659 :AB4D4429:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 17:27:43.856660 :AB4D442A:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 17:27:43.856660 :AB4D442B:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 17:27:43.856661 :AB4D442C:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 17:27:43.856661 :AB4D442D:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 17:27:43.857158 :AB4D442E:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 17:27:43.857202 :AB4D4436:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 17:37:44.693833 :AB4E0909:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 17:37:45.057812 :AB4E0977:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 17:37:45.053 2025-06-29 17:37:45.057814 :AB4E0978:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=7880 pso_num=25 pso_serial#=132 2025-06-29 17:37:45.057951 :AB4E0979:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=7880 osp_idx=23 osp_ver=175939 osp_pg=0 (spawn #335230) 2025-06-29 17:37:45.057952 :AB4E097A:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 17:37:45.057952 :AB4E097B:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=219 time=4194408250 2025-06-29 17:37:45.057953 :AB4E097C:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 17:37:45.057953 :AB4E097D:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 17:37:45.057954 :AB4E097E:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 17:37:45.057954 :AB4E097F:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 17:37:45.057954 :AB4E0980:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 17:37:45.058448 :AB4E0981:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 17:37:45.058494 :AB4E0988:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 17:47:45.921690 :AB4ECD41:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 17:47:46.379619 :AB4ECDE4:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 17:47:46.375 2025-06-29 17:47:46.379621 :AB4ECDE5:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=10400 pso_num=25 pso_serial#=133 2025-06-29 17:47:46.379762 :AB4ECDE6:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=10400 osp_idx=23 osp_ver=175940 osp_pg=0 (spawn #335253) 2025-06-29 17:47:46.379763 :AB4ECDE7:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 17:47:46.379763 :AB4ECDE8:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=220 time=4195009563 2025-06-29 17:47:46.379763 :AB4ECDE9:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 17:47:46.379764 :AB4ECDEA:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 17:47:46.379764 :AB4ECDEB:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 17:47:46.379765 :AB4ECDEC:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 17:47:46.379765 :AB4ECDED:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 17:47:46.380258 :AB4ECDEE:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 17:47:46.380305 :AB4ECDF6:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 17:57:47.212071 :AB4F92CD:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 17:57:47.670178 :AB4F938F:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 17:57:47.664 2025-06-29 17:57:47.670180 :AB4F9390:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=9536 pso_num=25 pso_serial#=134 2025-06-29 17:57:47.670346 :AB4F9391:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=9536 osp_idx=23 osp_ver=175941 osp_pg=0 (spawn #335275) 2025-06-29 17:57:47.670347 :AB4F9392:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 17:57:47.670347 :AB4F9393:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=221 time=4195610860 2025-06-29 17:57:47.670347 :AB4F9394:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 17:57:47.670347 :AB4F9395:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 17:57:47.670348 :AB4F9396:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 17:57:47.670348 :AB4F9397:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 17:57:47.670349 :AB4F9398:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 17:57:47.670961 :AB4F9399:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 17:57:47.671024 :AB4F939F:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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-06-29 18:07:48.530342 :AB5062F7:db_trace:kst.c@698:kstpsodel(): [10280:25:0] kst: process state object about to be deleted 2025-06-29 18:07:49.011275 :AB506395:db_trace:kst.c@673:kstipg(): [10280:25:0] kst: process state object created on 06-29 18:07:49.006 2025-06-29 18:07:49.011278 :AB506396:db_trace:kst.c@679:kstipg(): [10280:25:0] kst: process info: ospid=17648 pso_num=25 pso_serial#=135 2025-06-29 18:07:49.011416 :AB506397:db_trace:kso.c@4093:ksonfy(): [10420:25:0] kso: new process: pid=17648 osp_idx=23 osp_ver=175942 osp_pg=0 (spawn #335301) 2025-06-29 18:07:49.011417 :AB506398:db_trace:kso.c@4099:ksonfy(): [10420:25:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 18:07:49.011417 :AB506399:db_trace:kso.c@4103:ksonfy(): [10420:25:0] kso: new process: pso_index=25 reservation=222 time=4196212204 2025-06-29 18:07:49.011418 :AB50639A:db_trace:kso.c@4109:ksonfy(): [10420:25:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 18:07:49.011418 :AB50639B:db_trace:kso.c@4111:ksonfy(): [10420:25:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 18:07:49.011419 :AB50639C:db_trace:kso.c@4113:ksonfy(): [10420:25:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 18:07:49.011419 :AB50639D:db_trace:kso.c@4115:ksonfy(): [10420:25:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 18:07:49.011420 :AB50639E:db_trace:kso.c@4117:ksonfy(): [10420:25:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 18:07:49.011890 :AB50639F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:25:201] KSL POST SENT postee=23 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-06-29 18:07:49.011942 :AB5063A9:db_trace:ksl2.c@12699:ksliwat(): [10005:25:201] 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 25 (osid: 17648, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de