Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250629100943\orcl_w000_3836_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:36533M/63366M, Ph+PgF:42623M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 3836, image: ORACLE.EXE (W000) *** 2025-06-29 10:09:43.359 *** SESSION ID:(177.64895) 2025-06-29 10:09:43.359 *** 2025-06-29 10:09:43.359 Process diagnostic dump for ORACLE.EXE (W000), OS id=3836, pid: 22, proc_ser: 247, sid: 177, sess_ser: 64895 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=35 seq_num=36 snap_id=1 wait times: snap=4.235633 sec, exc=4.235633 sec, total=4.235633 sec wait times: max=5.000000 sec, heur=2 min 54 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.000025 sec since current wait 0: 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.015469 sec, exc=5.015469 sec, total=5.015469 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000019 sec of elapsed time 1: 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.004632 sec, exc=5.004632 sec, total=5.004632 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 2: 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.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.000025 sec of elapsed time 3: 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.004609 sec, exc=5.004609 sec, total=5.004609 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000017 sec of elapsed time 4: 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.014748 sec, exc=5.014748 sec, total=5.014748 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 5: 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.010136 sec, exc=5.010136 sec, total=5.010136 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 6: 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.001102 sec, exc=5.001102 sec, total=5.001102 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 7: 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.004855 sec, exc=5.004855 sec, total=5.004855 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 8: 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.004625 sec, exc=5.004625 sec, total=5.004625 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=25 seq_num=26 snap_id=1 wait times: snap=5.004613 sec, exc=5.004613 sec, total=5.004613 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 64895 --------------------------------------------------- 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, 10:07:43 - 10:09:43] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-29 10:09:43.359 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 3836, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-29 07:36:30.649381 :AB1EA6E3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:36:30.649381 :AB1EA6E4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:36:30.649879 :AB1EA6E5: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-06-29 07:36:30.649926 :AB1EA6EB: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-06-29 07:46:31.442389 :AB1F6D59:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 07:46:31.822160 :AB1F6DAE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 07:46:31.817 2025-06-29 07:46:31.822162 :AB1F6DAF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15168 pso_num=22 pso_serial#=234 2025-06-29 07:46:31.822306 :AB1F6DB0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15168 osp_idx=0 osp_ver=357561 osp_pg=0 (spawn #333869) 2025-06-29 07:46:31.822306 :AB1F6DB1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 07:46:31.822306 :AB1F6DB2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=75 time=4158935016 2025-06-29 07:46:31.822307 :AB1F6DB3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:46:31.822307 :AB1F6DB4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:46:31.822308 :AB1F6DB5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 07:46:31.822308 :AB1F6DB6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:46:31.822308 :AB1F6DB7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:46:31.822815 :AB1F6DB8: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-06-29 07:46:31.822860 :AB1F6DC0: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-06-29 07:56:32.665476 :AB2034C0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 07:56:32.982578 :AB203524:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 07:56:32.978 2025-06-29 07:56:32.982580 :AB203525:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17988 pso_num=22 pso_serial#=235 2025-06-29 07:56:32.982717 :AB203526:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17988 osp_idx=0 osp_ver=357562 osp_pg=0 (spawn #333891) 2025-06-29 07:56:32.982717 :AB203527:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 07:56:32.982718 :AB203528:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=76 time=4159536172 2025-06-29 07:56:32.982718 :AB203529:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:56:32.982719 :AB20352A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:56:32.982719 :AB20352B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 07:56:32.982720 :AB20352C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:56:32.982720 :AB20352D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:56:32.983214 :AB20352E: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-06-29 07:56:32.983259 :AB203536: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-06-29 08:06:33.818697 :AB21494D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 08:06:34.182933 :AB2149A7:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 08:06:34.178 2025-06-29 08:06:34.182935 :AB2149A8:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14028 pso_num=22 pso_serial#=236 2025-06-29 08:06:34.183079 :AB2149A9:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14028 osp_idx=0 osp_ver=357563 osp_pg=0 (spawn #333922) 2025-06-29 08:06:34.183079 :AB2149AA:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 08:06:34.183080 :AB2149AB:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=77 time=4160137375 2025-06-29 08:06:34.183080 :AB2149AC:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 08:06:34.183081 :AB2149AD:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 08:06:34.183081 :AB2149AE:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 08:06:34.183082 :AB2149AF:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 08:06:34.183082 :AB2149B0:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 08:06:34.183577 :AB2149B1: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-06-29 08:06:34.183621 :AB2149B9: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-06-29 08:16:34.931190 :AB2232DF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 08:16:35.310985 :AB223341:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 08:16:35.306 2025-06-29 08:16:35.310987 :AB223342:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17960 pso_num=22 pso_serial#=237 2025-06-29 08:16:35.311132 :AB223343:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17960 osp_idx=0 osp_ver=357564 osp_pg=0 (spawn #333945) 2025-06-29 08:16:35.311133 :AB223344:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 08:16:35.311133 :AB223345:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=78 time=4160738500 2025-06-29 08:16:35.311134 :AB223346:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 08:16:35.311134 :AB223347:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 08:16:35.311135 :AB223348:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 08:16:35.311135 :AB223349:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 08:16:35.311135 :AB22334A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 08:16:35.311646 :AB22334B: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-06-29 08:16:35.311694 :AB223352: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-06-29 08:23:54.815039 :AB22C4F3: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-06-29 08:26:36.483848 :AB22FB45: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-06-29 08:36:37.498716 :AB23CD09:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 08:36:37.768959 :AB23CD7A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 08:36:37.764 2025-06-29 08:36:37.768962 :AB23CD7B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7924 pso_num=22 pso_serial#=238 2025-06-29 08:36:37.769109 :AB23CD7C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7924 osp_idx=0 osp_ver=357565 osp_pg=0 (spawn #333989) 2025-06-29 08:36:37.769110 :AB23CD7D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 08:36:37.769110 :AB23CD7E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=79 time=4161940954 2025-06-29 08:36:37.769111 :AB23CD7F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 08:36:37.769111 :AB23CD80:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 08:36:37.769112 :AB23CD81:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 08:36:37.769112 :AB23CD82:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 08:36:37.769112 :AB23CD83:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 08:36:37.769626 :AB23CD84: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-06-29 08:36:37.769669 :AB23CD8B: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-06-29 08:46:38.638059 :AB249611:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 08:46:39.064798 :AB2496A9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 08:46:39.060 2025-06-29 08:46:39.064800 :AB2496AA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13944 pso_num=22 pso_serial#=239 2025-06-29 08:46:39.064946 :AB2496AB:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13944 osp_idx=0 osp_ver=357566 osp_pg=0 (spawn #334012) 2025-06-29 08:46:39.064947 :AB2496AC:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 08:46:39.064947 :AB2496AD:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=80 time=4162542250 2025-06-29 08:46:39.064948 :AB2496AE:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 08:46:39.064948 :AB2496AF:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 08:46:39.064949 :AB2496B0:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 08:46:39.064949 :AB2496B1:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 08:46:39.064949 :AB2496B2:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 08:46:39.065467 :AB2496B3: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-06-29 08:46:39.065513 :AB2496BA: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-06-29 08:56:39.946299 :AB255E52:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 08:56:40.341546 :AB255EBA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 08:56:40.337 2025-06-29 08:56:40.341548 :AB255EBB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4500 pso_num=22 pso_serial#=240 2025-06-29 08:56:40.341691 :AB255EBC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4500 osp_idx=0 osp_ver=357567 osp_pg=0 (spawn #334034) 2025-06-29 08:56:40.341691 :AB255EBD:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 08:56:40.341692 :AB255EBE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=81 time=4163143532 2025-06-29 08:56:40.341692 :AB255EBF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 08:56:40.341693 :AB255EC0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 08:56:40.341693 :AB255EC1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 08:56:40.341693 :AB255EC2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 08:56:40.341694 :AB255EC3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 08:56:40.342184 :AB255EC4: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-06-29 08:56:40.342228 :AB255ECB: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-06-29 09:06:41.248204 :AB2633E7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 09:06:41.471728 :AB263424:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 09:06:41.466 2025-06-29 09:06:41.471730 :AB263425:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14152 pso_num=22 pso_serial#=241 2025-06-29 09:06:41.471877 :AB263426:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14152 osp_idx=0 osp_ver=357568 osp_pg=0 (spawn #334060) 2025-06-29 09:06:41.471877 :AB263427:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 09:06:41.471878 :AB263428:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=82 time=4163744657 2025-06-29 09:06:41.471878 :AB263429:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 09:06:41.471879 :AB26342A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 09:06:41.471880 :AB26342B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 09:06:41.471880 :AB26342C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 09:06:41.471880 :AB26342D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 09:06:41.472431 :AB26342E: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-06-29 09:06:41.472476 :AB263436: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-06-29 09:16:42.280575 :AB26FB70:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 09:16:42.660196 :AB26FBDF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 09:16:42.656 2025-06-29 09:16:42.660198 :AB26FBE0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7960 pso_num=22 pso_serial#=242 2025-06-29 09:16:42.660335 :AB26FBE1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7960 osp_idx=0 osp_ver=357569 osp_pg=0 (spawn #334083) 2025-06-29 09:16:42.660336 :AB26FBE2:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 09:16:42.660337 :AB26FBE3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=83 time=4164345844 2025-06-29 09:16:42.660337 :AB26FBE4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 09:16:42.660338 :AB26FBE5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 09:16:42.660338 :AB26FBE6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 09:16:42.660338 :AB26FBE7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 09:16:42.660339 :AB26FBE8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 09:16:42.660824 :AB26FBE9: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-06-29 09:16:42.660870 :AB26FBF0: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-06-29 09:26:43.541180 :AB27C426:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 09:26:43.842737 :AB27C482:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 09:26:43.837 2025-06-29 09:26:43.842739 :AB27C483:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15788 pso_num=22 pso_serial#=243 2025-06-29 09:26:43.842877 :AB27C484:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15788 osp_idx=0 osp_ver=357570 osp_pg=0 (spawn #334105) 2025-06-29 09:26:43.842878 :AB27C485:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 09:26:43.842878 :AB27C486:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=84 time=4164947032 2025-06-29 09:26:43.842879 :AB27C487:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 09:26:43.842879 :AB27C488:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 09:26:43.842879 :AB27C489:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 09:26:43.842879 :AB27C48A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 09:26:43.842880 :AB27C48B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 09:26:43.843379 :AB27C48C: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-06-29 09:26:43.843423 :AB27C493: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-06-29 09:36:44.660331 :AB288CFC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 09:36:45.071588 :AB288D66:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 09:36:45.066 2025-06-29 09:36:45.071591 :AB288D67:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13624 pso_num=22 pso_serial#=244 2025-06-29 09:36:45.071731 :AB288D68:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13624 osp_idx=0 osp_ver=357571 osp_pg=0 (spawn #334128) 2025-06-29 09:36:45.071732 :AB288D69:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 09:36:45.071733 :AB288D6A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=85 time=4165548266 2025-06-29 09:36:45.071733 :AB288D6B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 09:36:45.071733 :AB288D6C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 09:36:45.071734 :AB288D6D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 09:36:45.071734 :AB288D6E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 09:36:45.071735 :AB288D6F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 09:36:45.072243 :AB288D70: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-06-29 09:36:45.072293 :AB288D77: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-06-29 09:46:45.957926 :AB29565D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 09:46:46.379234 :AB2956BD:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 09:46:46.374 2025-06-29 09:46:46.379236 :AB2956BE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18056 pso_num=22 pso_serial#=245 2025-06-29 09:46:46.379389 :AB2956BF:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18056 osp_idx=0 osp_ver=357572 osp_pg=0 (spawn #334151) 2025-06-29 09:46:46.379389 :AB2956C0:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 09:46:46.379389 :AB2956C1:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=86 time=4166149563 2025-06-29 09:46:46.379389 :AB2956C2:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 09:46:46.379390 :AB2956C3:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 09:46:46.379390 :AB2956C4:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 09:46:46.379390 :AB2956C5:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 09:46:46.379391 :AB2956C6:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 09:46:46.379898 :AB2956C7: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-06-29 09:46:46.379942 :AB2956CF: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-06-29 09:56:47.175079 :AB2A1EB5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 09:56:47.586058 :AB2A1F1A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 09:56:47.581 2025-06-29 09:56:47.586060 :AB2A1F1B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12612 pso_num=22 pso_serial#=246 2025-06-29 09:56:47.586192 :AB2A1F1C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12612 osp_idx=0 osp_ver=357573 osp_pg=0 (spawn #334173) 2025-06-29 09:56:47.586193 :AB2A1F1D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 09:56:47.586194 :AB2A1F1E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=87 time=4166750782 2025-06-29 09:56:47.586194 :AB2A1F1F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 09:56:47.586195 :AB2A1F20:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 09:56:47.586195 :AB2A1F21:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 09:56:47.586195 :AB2A1F22:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 09:56:47.586196 :AB2A1F23:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 09:56:47.586683 :AB2A1F24: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-06-29 09:56:47.586730 :AB2A1F2D: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-06-29 10:06:48.439324 :AB2AF014:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 10:06:48.850440 :AB2AF072:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 10:06:48.846 2025-06-29 10:06:48.850442 :AB2AF073:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=3836 pso_num=22 pso_serial#=247 2025-06-29 10:06:48.850576 :AB2AF074:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=3836 osp_idx=0 osp_ver=357574 osp_pg=0 (spawn #334199) 2025-06-29 10:06:48.850577 :AB2AF075:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-29 10:06:48.850577 :AB2AF076:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=88 time=4167352047 2025-06-29 10:06:48.850578 :AB2AF077:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 10:06:48.850578 :AB2AF078:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 10:06:48.850578 :AB2AF079:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 10:06:48.850579 :AB2AF07A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 10:06:48.850579 :AB2AF07B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 10:06:48.851067 :AB2AF07C: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-06-29 10:06:48.851114 :AB2AF083: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: 3836, W000)