Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250504220241\orcl_w000_12244_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:37541M/63366M, Ph+PgF:45039M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 12244, image: ORACLE.EXE (W000) *** 2025-05-04 22:02:41.593 *** SESSION ID:(177.33389) 2025-05-04 22:02:41.593 *** 2025-05-04 22:02:41.593 Process diagnostic dump for ORACLE.EXE (W000), OS id=12244, pid: 22, proc_ser: 98, sid: 177, sess_ser: 33389 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=78 seq_num=79 snap_id=1 wait times: snap=3.640512 sec, exc=3.640512 sec, total=3.640512 sec wait times: max=5.000000 sec, heur=6 min 29 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.000023 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=77 seq_num=78 snap_id=1 wait times: snap=5.001727 sec, exc=5.001727 sec, total=5.001727 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=76 seq_num=77 snap_id=1 wait times: snap=5.001240 sec, exc=5.001240 sec, total=5.001240 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=75 seq_num=76 snap_id=1 wait times: snap=5.008452 sec, exc=5.008452 sec, total=5.008452 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=74 seq_num=75 snap_id=1 wait times: snap=5.005560 sec, exc=5.005560 sec, total=5.005560 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=73 seq_num=74 snap_id=1 wait times: snap=5.008399 sec, exc=5.008399 sec, total=5.008399 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=72 seq_num=73 snap_id=1 wait times: snap=5.010144 sec, exc=5.010144 sec, total=5.010144 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=71 seq_num=72 snap_id=1 wait times: snap=5.009199 sec, exc=5.009199 sec, total=5.009199 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=70 seq_num=71 snap_id=1 wait times: snap=5.004615 sec, exc=5.004615 sec, total=5.004615 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=69 seq_num=70 snap_id=1 wait times: snap=5.007245 sec, exc=5.007245 sec, total=5.007245 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 9: 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.010762 sec, exc=5.010762 sec, total=5.010762 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time Sampled Session History of session 177 serial 33389 --------------------------------------------------- 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, 22:00:41 - 22:02:41] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-04 22:02:41.593 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 12244, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-04 19:45:55.327284 :92B3FA28:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 19:45:55.327781 :92B3FA29: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-04 19:45:55.327826 :92B3FA30: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-04 19:55:56.231634 :92B4C1E1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 19:55:56.567647 :92B4C1F0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 19:55:56.560 2025-05-04 19:55:56.567649 :92B4C1F1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=3560 pso_num=22 pso_serial#=83 2025-05-04 19:55:56.567782 :92B4C1F2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=3560 (legacy spawn) 2025-05-04 19:55:56.574189 :92B4C1F3: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-04 19:55:56.574218 :92B4C1F6: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-04 19:55:56.574370 :92B4C1F9: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-04 19:55:56.575185 :92B4C20A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 19:55:56.658176 :92B4C244:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 19:55:56.654 2025-05-04 19:55:56.658178 :92B4C245:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11028 pso_num=22 pso_serial#=84 2025-05-04 19:55:56.658374 :92B4C246:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11028 osp_idx=0 osp_ver=155379 osp_pg=0 (spawn #148446) 2025-05-04 19:55:56.658374 :92B4C247: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-04 19:55:56.658375 :92B4C248:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=136 time=3659333828 2025-05-04 19:55:56.658375 :92B4C249:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 19:55:56.658375 :92B4C24A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 19:55:56.658376 :92B4C24B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 19:55:56.658376 :92B4C24C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 19:55:56.658377 :92B4C24D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 19:55:56.658891 :92B4C24E: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-04 19:55:56.658941 :92B4C258: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-04 20:05:57.663188 :92B595B7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 20:05:57.886669 :92B595F2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 20:05:57.882 2025-05-04 20:05:57.886671 :92B595F3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8352 pso_num=22 pso_serial#=85 2025-05-04 20:05:57.886805 :92B595F4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=8352 osp_idx=0 osp_ver=155380 osp_pg=0 (spawn #148474) 2025-05-04 20:05:57.886806 :92B595F5: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-04 20:05:57.886806 :92B595F6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=137 time=3659935046 2025-05-04 20:05:57.886806 :92B595F7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 20:05:57.886807 :92B595F8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 20:05:57.886807 :92B595F9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 20:05:57.886807 :92B595FA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 20:05:57.886808 :92B595FB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 20:05:57.887345 :92B595FC: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-04 20:05:57.887391 :92B59605: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-04 20:15:58.827573 :92B65F00:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 20:15:59.086666 :92B65F03:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 20:15:59.077 2025-05-04 20:15:59.086669 :92B65F04:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8760 pso_num=22 pso_serial#=86 2025-05-04 20:15:59.086852 :92B65F05:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8760 (legacy spawn) 2025-05-04 20:15:59.093282 :92B65F06: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-04 20:15:59.093462 :92B65F0B: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-04 20:15:59.094269 :92B65F10:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 20:15:59.254002 :92B65F56:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 20:15:59.249 2025-05-04 20:15:59.254004 :92B65F57:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9172 pso_num=22 pso_serial#=87 2025-05-04 20:15:59.254141 :92B65F58:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9172 osp_idx=0 osp_ver=155382 osp_pg=0 (spawn #148496) 2025-05-04 20:15:59.254142 :92B65F59: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-04 20:15:59.254142 :92B65F5A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=138 time=3660536421 2025-05-04 20:15:59.254142 :92B65F5B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 20:15:59.254143 :92B65F5C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 20:15:59.254143 :92B65F5D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 20:15:59.254143 :92B65F5E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 20:15:59.254144 :92B65F5F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 20:15:59.254636 :92B65F60: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-04 20:15:59.254685 :92B65F68: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-04 20:26:00.175691 :92B7297F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 20:26:00.414690 :92B729C3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 20:26:00.410 2025-05-04 20:26:00.414692 :92B729C4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12256 pso_num=22 pso_serial#=88 2025-05-04 20:26:00.414828 :92B729C5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12256 osp_idx=0 osp_ver=155383 osp_pg=0 (spawn #148519) 2025-05-04 20:26:00.414829 :92B729C6: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-04 20:26:00.414829 :92B729C7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=139 time=3661137578 2025-05-04 20:26:00.414830 :92B729C8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 20:26:00.414830 :92B729C9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 20:26:00.414830 :92B729CA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 20:26:00.414830 :92B729CB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 20:26:00.414831 :92B729CC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 20:26:00.415328 :92B729CD: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-04 20:26:00.415386 :92B729D7: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-04 20:36:01.366309 :92B7F686:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 20:36:01.730298 :92B7F6CF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 20:36:01.726 2025-05-04 20:36:01.730300 :92B7F6D0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11160 pso_num=22 pso_serial#=89 2025-05-04 20:36:01.730438 :92B7F6D1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11160 osp_idx=0 osp_ver=155384 osp_pg=0 (spawn #148542) 2025-05-04 20:36:01.730439 :92B7F6D2: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-04 20:36:01.730439 :92B7F6D3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=140 time=3661738890 2025-05-04 20:36:01.730439 :92B7F6D4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 20:36:01.730440 :92B7F6D5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 20:36:01.730440 :92B7F6D6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 20:36:01.730440 :92B7F6D7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 20:36:01.730441 :92B7F6D8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 20:36:01.730941 :92B7F6D9: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-04 20:36:01.730993 :92B7F6E3: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-04 20:46:02.805069 :92B8C12E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 20:46:03.044191 :92B8C167:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 20:46:03.039 2025-05-04 20:46:03.044194 :92B8C168:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12112 pso_num=22 pso_serial#=90 2025-05-04 20:46:03.044329 :92B8C169:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12112 osp_idx=0 osp_ver=155385 osp_pg=0 (spawn #148564) 2025-05-04 20:46:03.044330 :92B8C16A: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-04 20:46:03.044330 :92B8C16B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=141 time=3662340203 2025-05-04 20:46:03.044330 :92B8C16C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 20:46:03.044331 :92B8C16D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 20:46:03.044331 :92B8C16E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 20:46:03.044331 :92B8C16F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 20:46:03.044332 :92B8C170:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 20:46:03.044829 :92B8C171: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-04 20:46:03.044873 :92B8C179: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-04 20:56:03.959978 :92B98B88:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 20:56:04.240384 :92B98BBC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 20:56:04.236 2025-05-04 20:56:04.240386 :92B98BBD:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10664 pso_num=22 pso_serial#=91 2025-05-04 20:56:04.240516 :92B98BBE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10664 osp_idx=0 osp_ver=155386 osp_pg=0 (spawn #148587) 2025-05-04 20:56:04.240517 :92B98BBF: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-04 20:56:04.240517 :92B98BC0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=142 time=3662941406 2025-05-04 20:56:04.240518 :92B98BC1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 20:56:04.240518 :92B98BC2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 20:56:04.240519 :92B98BC3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 20:56:04.240519 :92B98BC4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 20:56:04.240519 :92B98BC5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 20:56:04.241007 :92B98BC6: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-04 20:56:04.241049 :92B98BCE: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-04 21:05:18.599794 :92BA48DE: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-04 21:06:05.645681 :92BA5EBC: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-04 21:16:06.623310 :92BB2BE6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 21:16:06.862229 :92BB2C20:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 21:16:06.857 2025-05-04 21:16:06.862231 :92BB2C21:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11712 pso_num=22 pso_serial#=92 2025-05-04 21:16:06.862368 :92BB2C22:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11712 osp_idx=0 osp_ver=155387 osp_pg=0 (spawn #148636) 2025-05-04 21:16:06.862368 :92BB2C23: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-04 21:16:06.862369 :92BB2C24:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=143 time=3664144031 2025-05-04 21:16:06.862370 :92BB2C25:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 21:16:06.862370 :92BB2C26:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 21:16:06.862371 :92BB2C27:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 21:16:06.862371 :92BB2C28:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 21:16:06.862371 :92BB2C29:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 21:16:06.862862 :92BB2C2A: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-04 21:16:06.862910 :92BB2C31: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-04 21:26:07.884492 :92BBF637:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 21:26:08.225291 :92BBF646:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 21:26:08.213 2025-05-04 21:26:08.225294 :92BBF647:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10440 pso_num=22 pso_serial#=93 2025-05-04 21:26:08.225482 :92BBF648:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=10440 (legacy spawn) 2025-05-04 21:26:08.231914 :92BBF649: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-04 21:26:08.232093 :92BBF64E: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-04 21:26:08.232946 :92BBF653:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 21:26:08.241549 :92BBF656:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 21:26:08.228 2025-05-04 21:26:08.241551 :92BBF657:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11936 pso_num=22 pso_serial#=94 2025-05-04 21:26:08.241697 :92BBF658:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=11936 (legacy spawn) 2025-05-04 21:26:08.248159 :92BBF659: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-04 21:26:08.248325 :92BBF662: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-04 21:26:08.249129 :92BBF663:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 21:26:08.279694 :92BBF69D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 21:26:08.275 2025-05-04 21:26:08.279697 :92BBF69E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11336 pso_num=22 pso_serial#=95 2025-05-04 21:26:08.279837 :92BBF69F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11336 osp_idx=0 osp_ver=155390 osp_pg=0 (spawn #148659) 2025-05-04 21:26:08.279837 :92BBF6A0: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-04 21:26:08.279838 :92BBF6A1:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=144 time=3664745453 2025-05-04 21:26:08.279838 :92BBF6A2:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 21:26:08.279839 :92BBF6A3:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 21:26:08.279839 :92BBF6A4:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 21:26:08.279839 :92BBF6A5:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 21:26:08.279840 :92BBF6A6:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 21:26:08.280335 :92BBF6A7: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-04 21:26:08.280377 :92BBF6AF: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-04 21:36:09.176060 :92BCC1B3:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 21:36:09.614515 :92BCC1EC:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 21:36:09.609 2025-05-04 21:36:09.614518 :92BCC1ED:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11372 pso_num=22 pso_serial#=96 2025-05-04 21:36:09.614668 :92BCC1EE:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11372 osp_idx=0 osp_ver=155391 osp_pg=0 (spawn #148682) 2025-05-04 21:36:09.614669 :92BCC1EF: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-04 21:36:09.614669 :92BCC1F0:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=145 time=3665346781 2025-05-04 21:36:09.614670 :92BCC1F1:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 21:36:09.614670 :92BCC1F2:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 21:36:09.614670 :92BCC1F3:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 21:36:09.614671 :92BCC1F4:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 21:36:09.614671 :92BCC1F5:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 21:36:09.615180 :92BCC1F6: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-04 21:36:09.615224 :92BCC1FE: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-04 21:46:10.471809 :92BD8C7A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 21:46:11.038929 :92BD8CBF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 21:46:11.034 2025-05-04 21:46:11.038931 :92BD8CC0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9712 pso_num=22 pso_serial#=97 2025-05-04 21:46:11.039066 :92BD8CC1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9712 osp_idx=0 osp_ver=155392 osp_pg=0 (spawn #148704) 2025-05-04 21:46:11.039067 :92BD8CC2: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-04 21:46:11.039067 :92BD8CC3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=146 time=3665948203 2025-05-04 21:46:11.039067 :92BD8CC4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 21:46:11.039068 :92BD8CC5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 21:46:11.039068 :92BD8CC6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 21:46:11.039068 :92BD8CC7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 21:46:11.039069 :92BD8CC8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 21:46:11.039554 :92BD8CC9: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-04 21:46:11.039600 :92BD8CD0: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-04 21:56:11.965176 :92BE56EC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-05-04 21:56:12.407546 :92BE572A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 05-04 21:56:12.403 2025-05-04 21:56:12.407548 :92BE572B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12244 pso_num=22 pso_serial#=98 2025-05-04 21:56:12.407686 :92BE572C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12244 osp_idx=0 osp_ver=155393 osp_pg=0 (spawn #148727) 2025-05-04 21:56:12.407686 :92BE572D: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-04 21:56:12.407687 :92BE572E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=147 time=3666549578 2025-05-04 21:56:12.407687 :92BE572F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-05-04 21:56:12.407688 :92BE5730:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-05-04 21:56:12.407688 :92BE5731:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-05-04 21:56:12.407689 :92BE5732:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-04 21:56:12.407689 :92BE5733:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-04 21:56:12.408171 :92BE5734: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-04 21:56:12.408216 :92BE573B: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: 12244, W000)