Edit D:\app\Administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250511060006\orcl_w000_11128_bucket.trc
Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250511060006\orcl_w000_11128_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:37494M/63366M, Ph+PgF:44863M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 11128, image: ORACLE.EXE (W000) *** 2025-05-11 06:00:06.726 *** SESSION ID:(177.21603) 2025-05-11 06:00:06.726 *** 2025-05-11 06:00:06.726 Process diagnostic dump for ORACLE.EXE (W000), OS id=11128, pid: 22, proc_ser: 122, sid: 177, sess_ser: 21603 ------------------------------------------------------------------------------- current sql: <none> Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=46 seq_num=47 snap_id=1 wait times: snap=3.679825 sec, exc=3.679825 sec, total=3.679825 sec wait times: max=5.000000 sec, heur=3 min 49 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.000021 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=45 seq_num=46 snap_id=1 wait times: snap=5.011719 sec, exc=5.011719 sec, total=5.011719 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=44 seq_num=45 snap_id=1 wait times: snap=5.007199 sec, exc=5.007199 sec, total=5.007199 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=43 seq_num=44 snap_id=1 wait times: snap=5.014727 sec, exc=5.014727 sec, total=5.014727 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=42 seq_num=43 snap_id=1 wait times: snap=5.014715 sec, exc=5.014715 sec, total=5.014715 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=41 seq_num=42 snap_id=1 wait times: snap=5.015222 sec, exc=5.015222 sec, total=5.015222 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000031 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=40 seq_num=41 snap_id=1 wait times: snap=5.003233 sec, exc=5.003233 sec, total=5.003233 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=39 seq_num=40 snap_id=1 wait times: snap=5.011346 sec, exc=5.011346 sec, total=5.011346 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=38 seq_num=39 snap_id=1 wait times: snap=5.006450 sec, exc=5.006450 sec, total=5.006450 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=37 seq_num=38 snap_id=1 wait times: snap=5.014704 sec, exc=5.014704 sec, total=5.014704 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=36 seq_num=37 snap_id=1 wait times: snap=5.004639 sec, exc=5.004639 sec, total=5.004639 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time Sampled Session History of session 177 serial 21603 --------------------------------------------------- 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, 05:58:06 - 06:00:06] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-11 06:00:06.726 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 11128, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-11 03:46:00.785149 :957E05A2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 03:46:00.785626 :957E05A3: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-05-11 03:46:00.785671 :957E05AB: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-05-11 03:56:01.672025 :957EC66A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 03:56:02.176113 :957EC72D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 03:56:02.171 2025-05-11 03:56:02.176115 :957EC72E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11428 pso_num=22 pso_serial#=109 2025-05-11 03:56:02.176253 :957EC72F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11428 osp_idx=0 osp_ver=178354 osp_pg=0 (spawn #169904) 2025-05-11 03:56:02.176254 :957EC730:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 03:56:02.176254 :957EC731:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=41 time=4206539343 2025-05-11 03:56:02.176255 :957EC732:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 03:56:02.176255 :957EC733:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 03:56:02.176256 :957EC734:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 03:56:02.176256 :957EC735:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 03:56:02.176256 :957EC736:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 03:56:02.176744 :957EC737: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-05-11 03:56:02.176794 :957EC73E: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-05-11 04:06:03.027249 :957F90AE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 04:06:03.563133 :957F912D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 04:06:03.558 2025-05-11 04:06:03.563135 :957F912E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12580 pso_num=22 pso_serial#=110 2025-05-11 04:06:03.563304 :957F912F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12580 osp_idx=0 osp_ver=178355 osp_pg=0 (spawn #169931) 2025-05-11 04:06:03.563304 :957F9130:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 04:06:03.563305 :957F9131:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=42 time=4207140734 2025-05-11 04:06:03.563305 :957F9132:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 04:06:03.563306 :957F9133:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 04:06:03.563306 :957F9134:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 04:06:03.563306 :957F9135:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 04:06:03.563307 :957F9136:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 04:06:03.563822 :957F9137: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-05-11 04:06:03.563868 :957F913E: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-05-11 04:16:04.573598 :9580541C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 04:16:04.907108 :95805495:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 04:16:04.902 2025-05-11 04:16:04.907110 :95805496:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9120 pso_num=22 pso_serial#=111 2025-05-11 04:16:04.907244 :95805497:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9120 osp_idx=0 osp_ver=178356 osp_pg=0 (spawn #169955) 2025-05-11 04:16:04.907244 :95805498:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 04:16:04.907245 :95805499:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=43 time=4207742078 2025-05-11 04:16:04.907245 :9580549A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 04:16:04.907246 :9580549B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 04:16:04.907246 :9580549C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 04:16:04.907247 :9580549D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 04:16:04.907248 :9580549E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 04:16:04.907729 :9580549F: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-05-11 04:16:04.907776 :958054A5: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-05-11 04:16:19.935085 :958059C7:db_trace:ksl2.c@2477:kslwtbctx(): [10005:22:177] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-05-11 04:16:19.935090 :958059CA:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:177] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=4 seq_num=5 snap_id=1 2025-05-11 04:16:19.935091 :958059CD:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=4, exc=4, tot=4 2025-05-11 04:16:19.935098 :958059CF: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-05-11 04:17:20.029988 :95806C50:db_trace:ksl2.c@2477:kslwtbctx(): [10005:22:177] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=18 seq_num=19 snap_id=1 2025-05-11 04:17:20.030012 :95806C55:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:177] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=18 seq_num=19 snap_id=1 2025-05-11 04:17:20.030013 :95806C5C:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=23, exc=23, tot=23 2025-05-11 04:18:12.924122 :95807CEC: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-05-11 04:26:06.141372 :95811544: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-05-11 04:36:07.066396 :9581D5B6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 04:36:07.461655 :9581D61D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 04:36:07.456 2025-05-11 04:36:07.461657 :9581D61E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13180 pso_num=22 pso_serial#=112 2025-05-11 04:36:07.461799 :9581D61F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13180 osp_idx=0 osp_ver=178357 osp_pg=0 (spawn #170001) 2025-05-11 04:36:07.461800 :9581D620:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 04:36:07.461800 :9581D621:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=44 time=4208944625 2025-05-11 04:36:07.461800 :9581D622:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 04:36:07.461801 :9581D623:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 04:36:07.461801 :9581D624:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 04:36:07.461802 :9581D625:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 04:36:07.461802 :9581D626:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 04:36:07.462309 :9581D627: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-05-11 04:36:07.462361 :9581D62E: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-05-11 04:46:08.350923 :958297B6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 04:46:08.808791 :95829825:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 04:46:08.804 2025-05-11 04:46:08.808793 :95829826:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13212 pso_num=22 pso_serial#=113 2025-05-11 04:46:08.808958 :95829827:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13212 osp_idx=0 osp_ver=178358 osp_pg=0 (spawn #170024) 2025-05-11 04:46:08.808959 :95829828:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 04:46:08.808959 :95829829:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=45 time=4209545968 2025-05-11 04:46:08.808960 :9582982A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 04:46:08.808960 :9582982B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 04:46:08.808961 :9582982C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 04:46:08.808961 :9582982D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 04:46:08.808961 :9582982E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 04:46:08.809469 :9582982F: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-05-11 04:46:08.809522 :95829836: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-05-11 04:56:09.718858 :958358B4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 04:56:09.988974 :95835920:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 04:56:09.984 2025-05-11 04:56:09.988976 :95835921:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11596 pso_num=22 pso_serial#=114 2025-05-11 04:56:09.989106 :95835922:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11596 osp_idx=0 osp_ver=178359 osp_pg=0 (spawn #170047) 2025-05-11 04:56:09.989107 :95835923:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 04:56:09.989107 :95835924:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=46 time=4210147156 2025-05-11 04:56:09.989108 :95835925:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 04:56:09.989108 :95835926:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 04:56:09.989108 :95835927:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 04:56:09.989109 :95835928:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 04:56:09.989109 :95835929:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 04:56:09.989587 :9583592A: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-05-11 04:56:09.989631 :95835934: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-05-11 04:56:40.040488 :958362FF:db_trace:ksl2.c@2477:kslwtbctx(): [10005:22:177] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=7 seq_num=8 snap_id=1 2025-05-11 04:56:40.040493 :95836308:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:177] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=7 seq_num=8 snap_id=1 2025-05-11 04:56:40.040493 :95836309:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=6, exc=6, tot=6 2025-05-11 04:56:40.040502 :9583630E: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-05-11 05:06:10.929907 :9584271C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 05:06:11.247050 :95842752:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 05:06:11.242 2025-05-11 05:06:11.247052 :95842753:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=3224 pso_num=22 pso_serial#=115 2025-05-11 05:06:11.247190 :95842754:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=3224 osp_idx=0 osp_ver=178360 osp_pg=0 (spawn #170074) 2025-05-11 05:06:11.247191 :95842755:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 05:06:11.247192 :95842756:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=47 time=4210748406 2025-05-11 05:06:11.247192 :95842757:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 05:06:11.247192 :95842758:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 05:06:11.247192 :95842759:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 05:06:11.247193 :9584275A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 05:06:11.247193 :9584275B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 05:06:11.247704 :9584275C: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-05-11 05:06:11.247759 :95842766: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-05-11 05:16:12.148627 :9584EB2A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 05:16:12.512701 :9584EB8A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 05:16:12.508 2025-05-11 05:16:12.512704 :9584EB8B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13084 pso_num=22 pso_serial#=116 2025-05-11 05:16:12.512839 :9584EB8C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13084 osp_idx=0 osp_ver=178361 osp_pg=0 (spawn #170097) 2025-05-11 05:16:12.512840 :9584EB8D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 05:16:12.512841 :9584EB8E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=48 time=4211349671 2025-05-11 05:16:12.512841 :9584EB8F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 05:16:12.512841 :9584EB90:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 05:16:12.512842 :9584EB91:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 05:16:12.512842 :9584EB92:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 05:16:12.512842 :9584EB93:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 05:16:12.513323 :9584EB94: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-05-11 05:16:12.513369 :9584EB9C: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-05-11 05:26:13.450335 :9585AF0F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 05:26:13.751767 :9585AF66:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 05:26:13.747 2025-05-11 05:26:13.751769 :9585AF67:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12428 pso_num=22 pso_serial#=117 2025-05-11 05:26:13.751909 :9585AF68:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12428 osp_idx=0 osp_ver=178362 osp_pg=0 (spawn #170120) 2025-05-11 05:26:13.751910 :9585AF69:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 05:26:13.751911 :9585AF6A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=49 time=4211950921 2025-05-11 05:26:13.751911 :9585AF6B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 05:26:13.751911 :9585AF6C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 05:26:13.751912 :9585AF6D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 05:26:13.751912 :9585AF6E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 05:26:13.751912 :9585AF6F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 05:26:13.752416 :9585AF70: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-05-11 05:26:13.752466 :9585AF77: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-05-11 05:36:14.573223 :9586730A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 05:36:15.140381 :95867362:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 05:36:15.135 2025-05-11 05:36:15.140383 :95867363:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9240 pso_num=22 pso_serial#=118 2025-05-11 05:36:15.140524 :95867364:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9240 osp_idx=0 osp_ver=178363 osp_pg=0 (spawn #170142) 2025-05-11 05:36:15.140525 :95867365:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 05:36:15.140525 :95867366:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=50 time=4212552312 2025-05-11 05:36:15.140526 :95867367:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 05:36:15.140526 :95867368:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 05:36:15.140526 :95867369:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 05:36:15.140527 :9586736A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 05:36:15.140527 :9586736B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 05:36:15.141039 :9586736C: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-05-11 05:36:15.141086 :95867374: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-05-11 05:46:16.181430 :958736BA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 05:46:16.420417 :95873710:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 05:46:16.415 2025-05-11 05:46:16.420420 :95873711:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12604 pso_num=22 pso_serial#=119 2025-05-11 05:46:16.420607 :95873712:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12604 osp_idx=0 osp_ver=178364 osp_pg=0 (spawn #170163) 2025-05-11 05:46:16.420607 :95873713:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 05:46:16.420608 :95873714:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=51 time=4213153593 2025-05-11 05:46:16.420608 :95873715:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 05:46:16.420609 :95873716:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 05:46:16.420609 :95873717:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 05:46:16.420609 :95873718:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 05:46:16.420610 :95873719:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 05:46:16.421124 :9587371A: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-05-11 05:46:16.421170 :95873723: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-05-11 05:56:17.321450 :9587FA0D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 05:56:17.548366 :9587FA10:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 05:56:17.540 2025-05-11 05:56:17.548369 :9587FA11:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9412 pso_num=22 pso_serial#=120 2025-05-11 05:56:17.548505 :9587FA12:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=9412 (legacy spawn) 2025-05-11 05:56:17.554946 :9587FA13: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-05-11 05:56:17.555113 :9587FA18: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-05-11 05:56:17.556109 :9587FA1D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 05:56:17.564679 :9587FA20:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 05:56:17.556 2025-05-11 05:56:17.564681 :9587FA21:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10664 pso_num=22 pso_serial#=121 2025-05-11 05:56:17.564819 :9587FA22:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10664 (legacy spawn) 2025-05-11 05:56:17.571242 :9587FA23: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-05-11 05:56:17.571399 :9587FA2C: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-05-11 05:56:17.572221 :9587FA2D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-11 05:56:17.716741 :9587FA82:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-11 05:56:17.712 2025-05-11 05:56:17.716742 :9587FA83:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11128 pso_num=22 pso_serial#=122 2025-05-11 05:56:17.716876 :9587FA84:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11128 osp_idx=0 osp_ver=178367 osp_pg=0 (spawn #170187) 2025-05-11 05:56:17.716877 :9587FA85:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-11 05:56:17.716877 :9587FA86:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=52 time=4213754890 2025-05-11 05:56:17.716877 :9587FA87:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-11 05:56:17.716878 :9587FA88:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-11 05:56:17.716878 :9587FA89:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-11 05:56:17.716879 :9587FA8A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-11 05:56:17.716879 :9587FA8B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-11 05:56:17.717375 :9587FA8C: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-05-11 05:56:17.717425 :9587FA96: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: 11128, W000)
Ms-Dos/Windows
Unix
Write backup
jsp File Browser version 1.2 by
www.vonloesch.de