Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250711081122\orcl_w000_4224_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:36439M/63366M, Ph+PgF:42490M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 4224, image: ORACLE.EXE (W000) *** 2025-07-11 08:11:22.291 *** SESSION ID:(177.10137) 2025-07-11 08:11:22.291 *** 2025-07-11 08:11:22.291 Process diagnostic dump for ORACLE.EXE (W000), OS id=4224, pid: 22, proc_ser: 218, sid: 177, sess_ser: 10137 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=94 seq_num=95 snap_id=1 wait times: snap=2.702824 sec, exc=2.702824 sec, total=2.702824 sec wait times: max=5.000000 sec, heur=7 min 48 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.000024 sec since current wait 0: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=93 seq_num=94 snap_id=1 wait times: snap=5.015239 sec, exc=5.015239 sec, total=5.015239 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=92 seq_num=93 snap_id=1 wait times: snap=5.003494 sec, exc=5.003494 sec, total=5.003494 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000020 sec of elapsed time 2: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=91 seq_num=92 snap_id=1 wait times: snap=5.012993 sec, exc=5.012993 sec, total=5.012993 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=90 seq_num=91 snap_id=1 wait times: snap=5.012983 sec, exc=5.012983 sec, total=5.012983 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=89 seq_num=90 snap_id=1 wait times: snap=5.010149 sec, exc=5.010149 sec, total=5.010149 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=88 seq_num=89 snap_id=1 wait times: snap=5.014726 sec, exc=5.014726 sec, total=5.014726 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time 6: 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.009729 sec, exc=5.009729 sec, total=5.009729 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=86 seq_num=87 snap_id=1 wait times: snap=5.003232 sec, exc=5.003232 sec, total=5.003232 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000021 sec of elapsed time 8: 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.015038 sec, exc=5.015038 sec, total=5.015038 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000027 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=84 seq_num=85 snap_id=1 wait times: snap=5.009654 sec, exc=5.009654 sec, total=5.009654 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time Sampled Session History of session 177 serial 10137 --------------------------------------------------- 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:09:22 - 08:11:22] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-07-11 08:11:22.291 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 4224, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-07-11 05:52:45.749499 :B06B50B1:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 05:52:45.749499 :B06B50B2:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 05:52:45.749500 :B06B50B3:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 05:52:45.749500 :B06B50B4:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 05:52:45.750016 :B06B50B5:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:177] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-07-11 05:52:45.750671 :B06B50CA: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-07-11 05:52:45.761669 :B06B50E6:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-11 05:52:45.762150 :B06B50E7:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-07-11 05:52:45.764565 :B06B50E8:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-07-11 05:52:45.764918 :B06B50E9: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-07-11 05:52:45.764955 :B06B50EC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-07-11 05:52:45.765929 :B06B50F2: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-07-11 05:52:45.766060 :B06B50FB: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-07-11 05:53:15.852753 :B06B5AB5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 05:53:16.148785 :B06B5AF4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 05:53:16.144 2025-07-11 05:53:16.148787 :B06B5AF5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4696 pso_num=22 pso_serial#=206 2025-07-11 05:53:16.148925 :B06B5AF6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4696 osp_idx=0 osp_ver=389918 osp_pg=0 (spawn #373582) 2025-07-11 05:53:16.148925 :B06B5AF7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 05:53:16.148926 :B06B5AF8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=130 time=893972048 2025-07-11 05:53:16.148926 :B06B5AF9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 05:53:16.148926 :B06B5AFA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 05:53:16.148927 :B06B5AFB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 05:53:16.148927 :B06B5AFC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 05:53:16.148927 :B06B5AFD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 05:53:16.149410 :B06B5AFE: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-07-11 05:53:16.149452 :B06B5B06: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-07-11 06:03:17.121557 :B06C2561:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 06:03:17.438831 :B06C25A5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 06:03:17.434 2025-07-11 06:03:17.438833 :B06C25A6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15660 pso_num=22 pso_serial#=207 2025-07-11 06:03:17.438968 :B06C25A7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15660 osp_idx=0 osp_ver=389919 osp_pg=0 (spawn #373607) 2025-07-11 06:03:17.438969 :B06C25A8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 06:03:17.438969 :B06C25A9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=131 time=894573329 2025-07-11 06:03:17.438970 :B06C25AA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 06:03:17.438970 :B06C25AB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 06:03:17.438970 :B06C25AC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 06:03:17.438971 :B06C25AD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 06:03:17.438971 :B06C25AE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 06:03:17.439457 :B06C25AF: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-07-11 06:03:17.439504 :B06C25B7: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-07-11 06:13:18.309613 :B06CF7E2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 06:13:18.751812 :B06CF81A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 06:13:18.747 2025-07-11 06:13:18.751814 :B06CF81B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14392 pso_num=22 pso_serial#=208 2025-07-11 06:13:18.751948 :B06CF81C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14392 osp_idx=0 osp_ver=389920 osp_pg=0 (spawn #373630) 2025-07-11 06:13:18.751949 :B06CF81D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 06:13:18.751949 :B06CF81E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=132 time=895174642 2025-07-11 06:13:18.751950 :B06CF81F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 06:13:18.751950 :B06CF820:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 06:13:18.751951 :B06CF821:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 06:13:18.751951 :B06CF822:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 06:13:18.751951 :B06CF823:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 06:13:18.752500 :B06CF824: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-07-11 06:13:18.752542 :B06CF82D: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-07-11 06:23:19.617647 :B06DC15D:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 06:23:20.106785 :B06DC1A2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 06:23:20.102 2025-07-11 06:23:20.106786 :B06DC1A3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13824 pso_num=22 pso_serial#=209 2025-07-11 06:23:20.106926 :B06DC1A4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13824 osp_idx=0 osp_ver=389921 osp_pg=0 (spawn #373653) 2025-07-11 06:23:20.106927 :B06DC1A5:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 06:23:20.106927 :B06DC1A6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=133 time=895776001 2025-07-11 06:23:20.106928 :B06DC1A7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 06:23:20.106928 :B06DC1A8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 06:23:20.106928 :B06DC1A9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 06:23:20.106929 :B06DC1AA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 06:23:20.106929 :B06DC1AB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 06:23:20.107438 :B06DC1AC: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-07-11 06:23:20.107485 :B06DC1B3: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-07-11 06:33:21.029450 :B06E8B41:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 06:33:21.581085 :B06E8B79:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 06:33:21.576 2025-07-11 06:33:21.581087 :B06E8B7A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16524 pso_num=22 pso_serial#=210 2025-07-11 06:33:21.581225 :B06E8B7B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16524 osp_idx=0 osp_ver=389922 osp_pg=0 (spawn #373675) 2025-07-11 06:33:21.581226 :B06E8B7C:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 06:33:21.581226 :B06E8B7D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=134 time=896377470 2025-07-11 06:33:21.581226 :B06E8B7E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 06:33:21.581227 :B06E8B7F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 06:33:21.581227 :B06E8B80:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 06:33:21.581228 :B06E8B81:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 06:33:21.581228 :B06E8B82:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 06:33:21.581723 :B06E8B83: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-07-11 06:33:21.581771 :B06E8B8D: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-07-11 06:43:22.420285 :B06F54CF:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 06:43:22.940549 :B06F5513:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 06:43:22.936 2025-07-11 06:43:22.940551 :B06F5514:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17708 pso_num=22 pso_serial#=211 2025-07-11 06:43:22.940685 :B06F5515:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17708 osp_idx=0 osp_ver=389923 osp_pg=0 (spawn #373698) 2025-07-11 06:43:22.940685 :B06F5516:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 06:43:22.940686 :B06F5517:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=135 time=896978829 2025-07-11 06:43:22.940686 :B06F5518:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 06:43:22.940686 :B06F5519:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 06:43:22.940687 :B06F551A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 06:43:22.940687 :B06F551B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 06:43:22.940687 :B06F551C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 06:43:22.941184 :B06F551D: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-07-11 06:43:22.941229 :B06F5524: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-07-11 06:53:23.765073 :B0701EC8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 06:53:24.285447 :B0701F0F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 06:53:24.280 2025-07-11 06:53:24.285450 :B0701F10:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17564 pso_num=22 pso_serial#=212 2025-07-11 06:53:24.285643 :B0701F11:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17564 osp_idx=0 osp_ver=389924 osp_pg=0 (spawn #373721) 2025-07-11 06:53:24.285643 :B0701F12:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 06:53:24.285644 :B0701F13:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=136 time=897580173 2025-07-11 06:53:24.285644 :B0701F14:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 06:53:24.285644 :B0701F15:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 06:53:24.285645 :B0701F16:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 06:53:24.285645 :B0701F17:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 06:53:24.285645 :B0701F18:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 06:53:24.286149 :B0701F19: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-07-11 06:53:24.286197 :B0701F21: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-07-11 07:03:25.194206 :B070E956:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 07:03:25.699034 :B070E993:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 07:03:25.694 2025-07-11 07:03:25.699037 :B070E994:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=7436 pso_num=22 pso_serial#=213 2025-07-11 07:03:25.699175 :B070E995:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=7436 osp_idx=0 osp_ver=389925 osp_pg=0 (spawn #373746) 2025-07-11 07:03:25.699175 :B070E996:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 07:03:25.699176 :B070E997:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=137 time=898181595 2025-07-11 07:03:25.699176 :B070E998:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 07:03:25.699177 :B070E999:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 07:03:25.699177 :B070E99A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 07:03:25.699177 :B070E99B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 07:03:25.699178 :B070E99C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 07:03:25.699668 :B070E99D: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-07-11 07:03:25.699714 :B070E9A4: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-07-11 07:13:26.632023 :B071BBC1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 07:13:27.115628 :B071BC02:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 07:13:27.110 2025-07-11 07:13:27.115629 :B071BC03:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18404 pso_num=22 pso_serial#=214 2025-07-11 07:13:27.115772 :B071BC04:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18404 osp_idx=0 osp_ver=389926 osp_pg=0 (spawn #373769) 2025-07-11 07:13:27.115773 :B071BC05:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 07:13:27.115773 :B071BC06:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=138 time=898783017 2025-07-11 07:13:27.115774 :B071BC07:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 07:13:27.115774 :B071BC08:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 07:13:27.115775 :B071BC09:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 07:13:27.115775 :B071BC0A:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 07:13:27.115775 :B071BC0B:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 07:13:27.116277 :B071BC0C: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-07-11 07:13:27.116322 :B071BC14: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-07-11 07:18:26.384624 :B0721FDD: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-07-11 07:23:28.481484 :B0728520: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-07-11 07:24:13.559276 :B0729460:db_trace:ksl2.c@2477:kslwtbctx(): [10005:22:177] KSL WAIT BEG [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=132 seq_num=133 snap_id=1 2025-07-11 07:24:13.559309 :B0729465:db_trace:ksl2.c@2611:kslwtectx(): [10005:22:177] KSL WAIT END [latch free] 136537728/0x8236680 259/0x103 0/0x0 wait_id=132 seq_num=133 snap_id=1 2025-07-11 07:24:13.559310 :B0729466:db_trace:ksl2.c@2619:kslwtectx(): [10005:22:177] KSL WAIT END wait times (usecs) - snap=34, exc=34, tot=34 2025-07-11 07:33:29.396546 :B0734ECC:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 07:33:29.708251 :B0734EFF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 07:33:29.703 2025-07-11 07:33:29.708254 :B0734F00:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9384 pso_num=22 pso_serial#=215 2025-07-11 07:33:29.708390 :B0734F01:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9384 osp_idx=0 osp_ver=389927 osp_pg=0 (spawn #373813) 2025-07-11 07:33:29.708390 :B0734F02:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 07:33:29.708391 :B0734F03:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=139 time=899985595 2025-07-11 07:33:29.708391 :B0734F04:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 07:33:29.708391 :B0734F05:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 07:33:29.708392 :B0734F06:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 07:33:29.708392 :B0734F07:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 07:33:29.708392 :B0734F08:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 07:33:29.708911 :B0734F09: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-07-11 07:33:29.708967 :B0734F12: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-07-11 07:43:30.571305 :B0741918:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 07:43:31.101797 :B074197A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 07:43:31.097 2025-07-11 07:43:31.101799 :B074197B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16108 pso_num=22 pso_serial#=216 2025-07-11 07:43:31.101943 :B074197C:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=16108 osp_idx=0 osp_ver=389928 osp_pg=0 (spawn #373837) 2025-07-11 07:43:31.101944 :B074197D:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 07:43:31.101944 :B074197E:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=140 time=900587001 2025-07-11 07:43:31.101945 :B074197F:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 07:43:31.101945 :B0741980:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 07:43:31.101946 :B0741981:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 07:43:31.101946 :B0741982:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 07:43:31.101946 :B0741983:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 07:43:31.102427 :B0741984: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-07-11 07:43:31.102472 :B074198B: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-07-11 07:53:32.035315 :B074E29C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 07:53:32.446344 :B074E2F8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 07:53:32.442 2025-07-11 07:53:32.446346 :B074E2F9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9144 pso_num=22 pso_serial#=217 2025-07-11 07:53:32.446483 :B074E2FA:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9144 osp_idx=0 osp_ver=389929 osp_pg=0 (spawn #373860) 2025-07-11 07:53:32.446484 :B074E2FB:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 07:53:32.446484 :B074E2FC:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=141 time=901188345 2025-07-11 07:53:32.446485 :B074E2FD:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 07:53:32.446485 :B074E2FE:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 07:53:32.446486 :B074E2FF:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 07:53:32.446486 :B074E300:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 07:53:32.446486 :B074E301:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 07:53:32.446981 :B074E302: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-07-11 07:53:32.447025 :B074E30A: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-07-11 08:03:33.360330 :B075AFE0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-07-11 08:03:33.833979 :B075B03D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 07-11 08:03:33.829 2025-07-11 08:03:33.833981 :B075B03E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4224 pso_num=22 pso_serial#=218 2025-07-11 08:03:33.834127 :B075B03F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4224 osp_idx=0 osp_ver=389930 osp_pg=0 (spawn #373885) 2025-07-11 08:03:33.834128 :B075B040:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-07-11 08:03:33.834128 :B075B041:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=142 time=901789720 2025-07-11 08:03:33.834129 :B075B042:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-07-11 08:03:33.834129 :B075B043:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-07-11 08:03:33.834130 :B075B044:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-07-11 08:03:33.834130 :B075B045:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-07-11 08:03:33.834130 :B075B046:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-07-11 08:03:33.834642 :B075B047: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-07-11 08:03:33.834690 :B075B04F: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: 4224, W000)