Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250629080356\orcl_w000_17988_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:36540M/63366M, Ph+PgF:42621M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 17988, image: ORACLE.EXE (W000) *** 2025-06-29 08:03:57.011 *** SESSION ID:(177.64871) 2025-06-29 08:03:57.011 *** 2025-06-29 08:03:57.011 Process diagnostic dump for ORACLE.EXE (W000), OS id=17988, pid: 22, proc_ser: 235, sid: 177, sess_ser: 64871 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=89 seq_num=90 snap_id=1 wait times: snap=3.419475 sec, exc=3.419475 sec, total=3.419475 sec wait times: max=5.000000 sec, heur=7 min 24 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.000020 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=88 seq_num=89 snap_id=1 wait times: snap=5.014737 sec, exc=5.014737 sec, total=5.014737 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=87 seq_num=88 snap_id=1 wait times: snap=5.004620 sec, exc=5.004620 sec, total=5.004620 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000028 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=86 seq_num=87 snap_id=1 wait times: snap=5.014737 sec, exc=5.014737 sec, total=5.014737 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=85 seq_num=86 snap_id=1 wait times: snap=5.005135 sec, exc=5.005135 sec, total=5.005135 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000030 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=84 seq_num=85 snap_id=1 wait times: snap=5.003474 sec, exc=5.003474 sec, total=5.003474 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=83 seq_num=84 snap_id=1 wait times: snap=5.007780 sec, exc=5.007780 sec, total=5.007780 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=82 seq_num=83 snap_id=1 wait times: snap=5.003310 sec, exc=5.003310 sec, total=5.003310 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=81 seq_num=82 snap_id=1 wait times: snap=5.004633 sec, exc=5.004633 sec, total=5.004633 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=80 seq_num=81 snap_id=1 wait times: snap=5.014729 sec, exc=5.014729 sec, total=5.014729 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=79 seq_num=80 snap_id=1 wait times: snap=5.005149 sec, exc=5.005149 sec, total=5.005149 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time Sampled Session History of session 177 serial 64871 --------------------------------------------------- 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, 08:01:57 - 08:03:57] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-29 08:03:57.011 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 17988, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-29 05:36:15.507760 :AB152F05:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13948 pso_num=22 pso_serial#=221 2025-06-29 05:36:15.507896 :AB152F06:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13948 osp_idx=0 osp_ver=357548 osp_pg=0 (spawn #333563) 2025-06-29 05:36:15.507897 :AB152F07: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 05:36:15.507897 :AB152F08:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=63 time=4151118704 2025-06-29 05:36:15.507898 :AB152F09:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 05:36:15.507898 :AB152F0A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 05:36:15.507898 :AB152F0B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 05:36:15.507899 :AB152F0C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 05:36:15.507899 :AB152F0D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 05:36:15.508388 :AB152F0E: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 05:36:15.508435 :AB152F17: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 05:46:16.390423 :AB15F404:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 05:46:16.707535 :AB15F4A0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 05:46:16.703 2025-06-29 05:46:16.707536 :AB15F4A1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7824 pso_num=22 pso_serial#=222 2025-06-29 05:46:16.707673 :AB15F4A2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7824 osp_idx=0 osp_ver=357549 osp_pg=0 (spawn #333586) 2025-06-29 05:46:16.707674 :AB15F4A3: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 05:46:16.707674 :AB15F4A4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=64 time=4151719891 2025-06-29 05:46:16.707674 :AB15F4A5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 05:46:16.707675 :AB15F4A6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 05:46:16.707675 :AB15F4A7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 05:46:16.707675 :AB15F4A8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 05:46:16.707676 :AB15F4A9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 05:46:16.708171 :AB15F4AA: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 05:46:16.708214 :AB15F4B2: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 05:56:17.596215 :AB16B8B5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 05:56:17.946801 :AB16B937:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 05:56:17.941 2025-06-29 05:56:17.946803 :AB16B938:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13400 pso_num=22 pso_serial#=223 2025-06-29 05:56:17.946943 :AB16B939:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13400 osp_idx=0 osp_ver=357550 osp_pg=0 (spawn #333608) 2025-06-29 05:56:17.946944 :AB16B93A: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 05:56:17.946945 :AB16B93B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=65 time=4152321141 2025-06-29 05:56:17.946945 :AB16B93C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 05:56:17.946945 :AB16B93D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 05:56:17.946946 :AB16B93E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 05:56:17.946946 :AB16B93F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 05:56:17.946947 :AB16B940:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 05:56:17.947457 :AB16B941: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 05:56:17.947512 :AB16B94A: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 06:06:18.732348 :AB179957:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 06:06:18.944533 :AB17995A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 06:06:18.936 2025-06-29 06:06:18.944535 :AB17995B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=2472 pso_num=22 pso_serial#=224 2025-06-29 06:06:18.944679 :AB17995C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=2472 (legacy spawn) 2025-06-29 06:06:18.951126 :AB17997D: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-06-29 06:06:18.951285 :AB179982: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-06-29 06:06:18.952077 :AB179987:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 06:06:19.174544 :AB1799D2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 06:06:19.170 2025-06-29 06:06:19.174546 :AB1799D3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14912 pso_num=22 pso_serial#=225 2025-06-29 06:06:19.174679 :AB1799D4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14912 osp_idx=0 osp_ver=357552 osp_pg=0 (spawn #333639) 2025-06-29 06:06:19.174679 :AB1799D5: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 06:06:19.174680 :AB1799D6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=66 time=4152922360 2025-06-29 06:06:19.174680 :AB1799D7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 06:06:19.174681 :AB1799D8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 06:06:19.174681 :AB1799D9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 06:06:19.174681 :AB1799DA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 06:06:19.174681 :AB1799DB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 06:06:19.175164 :AB1799DC: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 06:06:19.175210 :AB1799E3: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 06:16:19.981858 :AB1861C9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 06:16:20.330218 :AB186225:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 06:16:20.325 2025-06-29 06:16:20.330221 :AB186226:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=1388 pso_num=22 pso_serial#=226 2025-06-29 06:16:20.330358 :AB186227:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=1388 osp_idx=0 osp_ver=357553 osp_pg=0 (spawn #333662) 2025-06-29 06:16:20.330359 :AB186228: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 06:16:20.330359 :AB186229:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=67 time=4153523516 2025-06-29 06:16:20.330360 :AB18622A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 06:16:20.330360 :AB18622B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 06:16:20.330360 :AB18622C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 06:16:20.330361 :AB18622D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 06:16:20.330361 :AB18622E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 06:16:20.330859 :AB18622F: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 06:16:20.330905 :AB186236: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 06:19:23.516225 :AB189EE2: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 06:26:21.643262 :AB192AE3: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 06:36:22.480425 :AB19F3CF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 06:36:22.828821 :AB19F435:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 06:36:22.824 2025-06-29 06:36:22.828824 :AB19F436:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17844 pso_num=22 pso_serial#=227 2025-06-29 06:36:22.828962 :AB19F437:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17844 osp_idx=0 osp_ver=357554 osp_pg=0 (spawn #333706) 2025-06-29 06:36:22.828962 :AB19F438: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 06:36:22.828963 :AB19F439:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=68 time=4154726016 2025-06-29 06:36:22.828963 :AB19F43A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 06:36:22.828964 :AB19F43B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 06:36:22.828964 :AB19F43C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 06:36:22.828964 :AB19F43D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 06:36:22.828965 :AB19F43E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 06:36:22.829463 :AB19F43F: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 06:36:22.829507 :AB19F447: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 06:46:23.611908 :AB1ABCE4:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 06:46:24.048521 :AB1ABD49:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 06:46:24.043 2025-06-29 06:46:24.048523 :AB1ABD4A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7692 pso_num=22 pso_serial#=228 2025-06-29 06:46:24.048657 :AB1ABD4B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7692 osp_idx=0 osp_ver=357555 osp_pg=0 (spawn #333729) 2025-06-29 06:46:24.048657 :AB1ABD4C: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 06:46:24.048657 :AB1ABD4D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=69 time=4155327235 2025-06-29 06:46:24.048658 :AB1ABD4E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 06:46:24.048658 :AB1ABD4F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 06:46:24.048659 :AB1ABD50:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 06:46:24.048659 :AB1ABD51:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 06:46:24.048659 :AB1ABD52:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 06:46:24.049183 :AB1ABD53: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 06:46:24.049229 :AB1ABD5B: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 06:56:24.911459 :AB1B8294:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 06:56:25.478811 :AB1B830B:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 06:56:25.473 2025-06-29 06:56:25.478813 :AB1B830C:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11036 pso_num=22 pso_serial#=229 2025-06-29 06:56:25.478957 :AB1B830D:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11036 osp_idx=0 osp_ver=357556 osp_pg=0 (spawn #333751) 2025-06-29 06:56:25.478958 :AB1B830E: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 06:56:25.478959 :AB1B830F:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=70 time=4155928672 2025-06-29 06:56:25.478959 :AB1B8310:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 06:56:25.478960 :AB1B8311:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 06:56:25.478960 :AB1B8312:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 06:56:25.478961 :AB1B8313:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 06:56:25.478961 :AB1B8314:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 06:56:25.479463 :AB1B8315: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 06:56:25.479510 :AB1B831D: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:06:26.287958 :AB1C515B:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 07:06:26.802894 :AB1C51AF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 07:06:26.798 2025-06-29 07:06:26.802896 :AB1C51B0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17380 pso_num=22 pso_serial#=230 2025-06-29 07:06:26.803049 :AB1C51B1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17380 osp_idx=0 osp_ver=357557 osp_pg=0 (spawn #333778) 2025-06-29 07:06:26.803050 :AB1C51B2: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:06:26.803050 :AB1C51B3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=71 time=4156530000 2025-06-29 07:06:26.803050 :AB1C51B4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:06:26.803050 :AB1C51B5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:06:26.803051 :AB1C51B6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 07:06:26.803051 :AB1C51B7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:06:26.803051 :AB1C51B8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:06:26.803558 :AB1C51B9: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:06:26.803601 :AB1C51C1: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:16:27.642817 :AB1D1955:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 07:16:28.100836 :AB1D19C2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 07:16:28.096 2025-06-29 07:16:28.100838 :AB1D19C3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=10556 pso_num=22 pso_serial#=231 2025-06-29 07:16:28.100986 :AB1D19C4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=10556 osp_idx=0 osp_ver=357558 osp_pg=0 (spawn #333801) 2025-06-29 07:16:28.100986 :AB1D19C5: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:16:28.100987 :AB1D19C6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=72 time=4157131297 2025-06-29 07:16:28.100989 :AB1D19C7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:16:28.100989 :AB1D19C8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:16:28.100990 :AB1D19C9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 07:16:28.100990 :AB1D19CA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:16:28.100990 :AB1D19CB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:16:28.101516 :AB1D19CC: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:16:28.101563 :AB1D19D2: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:26:29.011829 :AB1DDEDC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 07:26:29.407374 :AB1DDF3A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 07:26:29.402 2025-06-29 07:26:29.407376 :AB1DDF3B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4288 pso_num=22 pso_serial#=232 2025-06-29 07:26:29.407513 :AB1DDF3C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4288 osp_idx=0 osp_ver=357559 osp_pg=0 (spawn #333823) 2025-06-29 07:26:29.407514 :AB1DDF3D: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:26:29.407515 :AB1DDF3E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=73 time=4157732594 2025-06-29 07:26:29.407515 :AB1DDF3F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:26:29.407515 :AB1DDF40:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:26:29.407516 :AB1DDF41:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-29 07:26:29.407516 :AB1DDF42:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-29 07:26:29.407516 :AB1DDF43:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-29 07:26:29.408019 :AB1DDF44: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:26:29.408063 :AB1DDF4C: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:36:30.238209 :AB1EA678:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-29 07:36:30.649238 :AB1EA6DB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-29 07:36:30.644 2025-06-29 07:36:30.649240 :AB1EA6DC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17332 pso_num=22 pso_serial#=233 2025-06-29 07:36:30.649379 :AB1EA6DD:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17332 osp_idx=0 osp_ver=357560 osp_pg=0 (spawn #333846) 2025-06-29 07:36:30.649379 :AB1EA6DE: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:36:30.649380 :AB1EA6DF:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=74 time=4158333844 2025-06-29 07:36:30.649380 :AB1EA6E0:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-29 07:36:30.649381 :AB1EA6E1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-29 07:36:30.649381 :AB1EA6E2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 17988, W000)