Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250802082727\orcl_w000_11292_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:35813M/63366M, Ph+PgF:41451M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 11292, image: ORACLE.EXE (W000) *** 2025-08-02 08:27:27.393 *** SESSION ID:(177.19383) 2025-08-02 08:27:27.393 *** 2025-08-02 08:27:27.393 Process diagnostic dump for ORACLE.EXE (W000), OS id=11292, pid: 22, proc_ser: 12, sid: 177, sess_ser: 19383 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=86 seq_num=87 snap_id=1 wait times: snap=2.188788 sec, exc=2.188788 sec, total=2.188788 sec wait times: max=5.000000 sec, heur=7 min 7 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.000019 sec since current wait 0: 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.000368 sec, exc=5.000368 sec, total=5.000368 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=84 seq_num=85 snap_id=1 wait times: snap=5.000223 sec, exc=5.000223 sec, total=5.000223 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 2: 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.000456 sec, exc=5.000456 sec, total=5.000456 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 3: 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.000956 sec, exc=5.000956 sec, total=5.000956 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=81 seq_num=82 snap_id=1 wait times: snap=5.004616 sec, exc=5.004616 sec, total=5.004616 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=80 seq_num=81 snap_id=1 wait times: snap=5.014731 sec, exc=5.014731 sec, total=5.014731 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=79 seq_num=80 snap_id=1 wait times: snap=5.009730 sec, exc=5.009730 sec, total=5.009730 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=78 seq_num=79 snap_id=1 wait times: snap=5.002142 sec, exc=5.002142 sec, total=5.002142 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=77 seq_num=78 snap_id=1 wait times: snap=5.001430 sec, exc=5.001430 sec, total=5.001430 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=76 seq_num=77 snap_id=1 wait times: snap=5.002444 sec, exc=5.002444 sec, total=5.002444 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000029 sec of elapsed time Sampled Session History of session 177 serial 19383 --------------------------------------------------- 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:25:27 - 08:27:27] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-02 08:27:27.393 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 11292, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-02 06:58:25.837728 :BA38989F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19084 pso_num=22 pso_serial#=251 2025-08-02 06:58:25.837864 :BA3898A0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19084 (legacy spawn) 2025-08-02 06:58:25.844442 :BA3898A1: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-08-02 06:58:25.844608 :BA3898AA: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-08-02 06:58:25.845451 :BA3898AB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 06:58:39.547644 :BA389CF5:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 06:58:39.541 2025-08-02 06:58:39.547645 :BA389CF6:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18404 pso_num=22 pso_serial#=252 2025-08-02 06:58:39.547786 :BA389CF7:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18404 osp_idx=0 osp_ver=476919 osp_pg=0 (spawn #447051) 2025-08-02 06:58:39.547787 :BA389CF8:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 06:58:39.547787 :BA389CF9:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=228 time=2798588845 2025-08-02 06:58:39.547788 :BA389CFA:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 06:58:39.547788 :BA389CFB:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 06:58:39.547788 :BA389CFC:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 06:58:39.547788 :BA389CFD:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 06:58:39.547789 :BA389CFE:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 06:58:39.548277 :BA389CFF: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-08-02 06:58:39.548928 :BA389D14: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-08-02 06:58:39.560028 :BA389D30:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-02 06:58:39.560516 :BA389D31:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-02 06:58:39.562981 :BA389D32:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-02 06:58:39.563358 :BA389D33: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-08-02 06:58:39.563401 :BA389D36:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-02 06:58:39.564310 :BA389D3C: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-08-02 06:58:39.564447 :BA389D44: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-08-02 06:59:09.656332 :BA38A6D1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 06:59:15.526661 :BA38A8AE:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 06:59:15.518 2025-08-02 06:59:15.526664 :BA38A8AF:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=8004 pso_num=22 pso_serial#=253 2025-08-02 06:59:15.526795 :BA38A8B0:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=8004 (legacy spawn) 2025-08-02 06:59:15.533202 :BA38A8B1: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-08-02 06:59:15.533382 :BA38A8B6: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-08-02 06:59:15.534238 :BA38A8BB:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 06:59:33.113205 :BA38AE3C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 06:59:33.098 2025-08-02 06:59:33.113208 :BA38AE3D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20120 pso_num=22 pso_serial#=254 2025-08-02 06:59:33.113341 :BA38AE3E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20120 (legacy spawn) 2025-08-02 06:59:33.119826 :BA38AE3F: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-08-02 06:59:33.119999 :BA38AE44: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-08-02 06:59:33.120810 :BA38AE49:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 06:59:33.129366 :BA38AE4C:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 06:59:33.114 2025-08-02 06:59:33.129368 :BA38AE4D:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18544 pso_num=22 pso_serial#=255 2025-08-02 06:59:33.129503 :BA38AE4E:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18544 (legacy spawn) 2025-08-02 06:59:33.135953 :BA38AE5B: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-08-02 06:59:33.136112 :BA38AE64: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-08-02 06:59:33.136896 :BA38AE65:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 06:59:33.951942 :BA38AE9A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 06:59:33.942 2025-08-02 06:59:33.951944 :BA38AE9B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14024 pso_num=22 pso_serial#=1 2025-08-02 06:59:33.952101 :BA38AE9C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14024 (legacy spawn) 2025-08-02 06:59:33.958714 :BA38AE9D: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-08-02 06:59:33.958872 :BA38AEA2: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-08-02 06:59:33.959667 :BA38AEA7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 06:59:36.205571 :BA38AF6A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 06:59:36.192 2025-08-02 06:59:36.205573 :BA38AF6B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19296 pso_num=22 pso_serial#=2 2025-08-02 06:59:36.205711 :BA38AF6C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19296 (legacy spawn) 2025-08-02 06:59:36.212245 :BA38AF6D: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-08-02 06:59:36.212421 :BA38AF72: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-08-02 06:59:36.213221 :BA38AF77:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 06:59:36.232177 :BA38AF7A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 06:59:36.224 2025-08-02 06:59:36.232179 :BA38AF7B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17796 pso_num=22 pso_serial#=3 2025-08-02 06:59:36.232313 :BA38AF7C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17796 (legacy spawn) 2025-08-02 06:59:36.238770 :BA38AF7D: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-08-02 06:59:36.238956 :BA38AF86: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-08-02 06:59:36.239789 :BA38AF87:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 06:59:39.637944 :BA38B095:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 06:59:39.632 2025-08-02 06:59:39.637946 :BA38B096:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13868 pso_num=22 pso_serial#=4 2025-08-02 06:59:39.638080 :BA38B097:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13868 osp_idx=0 osp_ver=476926 osp_pg=0 (spawn #447053) 2025-08-02 06:59:39.638081 :BA38B098:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 06:59:39.638081 :BA38B099:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=229 time=2798648939 2025-08-02 06:59:39.638082 :BA38B09A:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 06:59:39.638082 :BA38B09B:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 06:59:39.638082 :BA38B09C:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 06:59:39.638083 :BA38B09D:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 06:59:39.638083 :BA38B09E:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 06:59:39.638618 :BA38B09F: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-08-02 06:59:39.639258 :BA38B0B4: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-08-02 06:59:39.646862 :BA38B0D0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-08-02 06:59:39.647223 :BA38B0D1: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-08-02 06:59:39.647259 :BA38B0D4:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-08-02 06:59:39.648316 :BA38B0DA: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-08-02 06:59:39.648482 :BA38B0E3: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-08-02 07:00:09.765095 :BA38BAB9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 07:00:09.988403 :BA38BAF3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 07:00:09.984 2025-08-02 07:00:09.988405 :BA38BAF4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12000 pso_num=22 pso_serial#=5 2025-08-02 07:00:09.988540 :BA38BAF5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=12000 osp_idx=0 osp_ver=476927 osp_pg=0 (spawn #447056) 2025-08-02 07:00:09.988541 :BA38BAF6:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 07:00:09.988541 :BA38BAF7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=230 time=2798679298 2025-08-02 07:00:09.988542 :BA38BAF8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 07:00:09.988542 :BA38BAF9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 07:00:09.988542 :BA38BAFA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 07:00:09.988542 :BA38BAFB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 07:00:09.988542 :BA38BAFC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 07:00:09.989032 :BA38BAFD: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-08-02 07:00:09.989077 :BA38BB05: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-08-02 07:10:10.721350 :BA398C59:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 07:10:11.164088 :BA398C9E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 07:10:11.158 2025-08-02 07:10:11.164092 :BA398C9F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4764 pso_num=22 pso_serial#=6 2025-08-02 07:10:11.164257 :BA398CA0:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4764 osp_idx=0 osp_ver=476928 osp_pg=0 (spawn #447081) 2025-08-02 07:10:11.164257 :BA398CA1:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 07:10:11.164258 :BA398CA2:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=231 time=2799280470 2025-08-02 07:10:11.164258 :BA398CA3:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 07:10:11.164258 :BA398CA4:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 07:10:11.164259 :BA398CA5:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 07:10:11.164259 :BA398CA6:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 07:10:11.164260 :BA398CA7:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 07:10:11.164799 :BA398CA8: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-08-02 07:10:11.164848 :BA398CAF: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-08-02 07:17:32.833375 :BA3A22A8: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-08-02 07:20:12.381277 :BA3A5861: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-08-02 07:30:13.257815 :BA3B211A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 07:30:13.688176 :BA3B2157:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 07:30:13.683 2025-08-02 07:30:13.688178 :BA3B2158:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19464 pso_num=22 pso_serial#=7 2025-08-02 07:30:13.688322 :BA3B2159:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19464 osp_idx=0 osp_ver=476929 osp_pg=0 (spawn #447126) 2025-08-02 07:30:13.688322 :BA3B215A:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 07:30:13.688323 :BA3B215B:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=232 time=2800483001 2025-08-02 07:30:13.688323 :BA3B215C:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 07:30:13.688324 :BA3B215D:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 07:30:13.688324 :BA3B215E:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 07:30:13.688324 :BA3B215F:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 07:30:13.688325 :BA3B2160:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 07:30:13.688818 :BA3B2161: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-08-02 07:30:13.688869 :BA3B2168: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-08-02 07:40:14.416114 :BA3BEBCE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 07:40:14.858561 :BA3BEC06:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 07:40:14.853 2025-08-02 07:40:14.858563 :BA3BEC07:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17616 pso_num=22 pso_serial#=8 2025-08-02 07:40:14.858700 :BA3BEC08:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17616 osp_idx=0 osp_ver=476930 osp_pg=0 (spawn #447148) 2025-08-02 07:40:14.858701 :BA3BEC09:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 07:40:14.858701 :BA3BEC0A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=233 time=2801084158 2025-08-02 07:40:14.858701 :BA3BEC0B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 07:40:14.858702 :BA3BEC0C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 07:40:14.858702 :BA3BEC0D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 07:40:14.858702 :BA3BEC0E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 07:40:14.858703 :BA3BEC0F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 07:40:14.859270 :BA3BEC10: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-08-02 07:40:14.859330 :BA3BEC16: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-08-02 07:50:15.804347 :BA3CB60E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 07:50:16.090408 :BA3CB644:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 07:50:16.085 2025-08-02 07:50:16.090410 :BA3CB645:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=4884 pso_num=22 pso_serial#=9 2025-08-02 07:50:16.090553 :BA3CB646:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=4884 osp_idx=0 osp_ver=476931 osp_pg=0 (spawn #447171) 2025-08-02 07:50:16.090554 :BA3CB647:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 07:50:16.090554 :BA3CB648:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=234 time=2801685392 2025-08-02 07:50:16.090554 :BA3CB649:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 07:50:16.090555 :BA3CB64A:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 07:50:16.090555 :BA3CB64B:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 07:50:16.090556 :BA3CB64C:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 07:50:16.090556 :BA3CB64D:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 07:50:16.091055 :BA3CB64E: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-08-02 07:50:16.091104 :BA3CB655: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-08-02 08:00:16.900225 :BA3D80D7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 08:00:17.326804 :BA3D8111:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 08:00:17.322 2025-08-02 08:00:17.326806 :BA3D8112:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=9912 pso_num=22 pso_serial#=10 2025-08-02 08:00:17.326945 :BA3D8113:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=9912 osp_idx=0 osp_ver=476932 osp_pg=0 (spawn #447195) 2025-08-02 08:00:17.326946 :BA3D8114:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 08:00:17.326946 :BA3D8115:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=235 time=2802286626 2025-08-02 08:00:17.326946 :BA3D8116:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 08:00:17.326947 :BA3D8117:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 08:00:17.326947 :BA3D8118:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 08:00:17.326948 :BA3D8119:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 08:00:17.326948 :BA3D811A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 08:00:17.327444 :BA3D811B: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-08-02 08:00:17.327490 :BA3D8124: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-08-02 08:10:18.182425 :BA3E52D2:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 08:10:18.484128 :BA3E5308:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 08:10:18.479 2025-08-02 08:10:18.484129 :BA3E5309:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19952 pso_num=22 pso_serial#=11 2025-08-02 08:10:18.484274 :BA3E530A:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19952 osp_idx=0 osp_ver=476933 osp_pg=0 (spawn #447220) 2025-08-02 08:10:18.484274 :BA3E530B:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 08:10:18.484275 :BA3E530C:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=236 time=2802887783 2025-08-02 08:10:18.484275 :BA3E530D:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 08:10:18.484275 :BA3E530E:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 08:10:18.484276 :BA3E530F:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 08:10:18.484276 :BA3E5310:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 08:10:18.484277 :BA3E5311:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 08:10:18.484782 :BA3E5312: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-08-02 08:10:18.484830 :BA3E5319: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-08-02 08:20:19.310831 :BA3F1FB0:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-08-02 08:20:19.643841 :BA3F1FEA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 08-02 08:20:19.638 2025-08-02 08:20:19.643843 :BA3F1FEB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=11292 pso_num=22 pso_serial#=12 2025-08-02 08:20:19.643984 :BA3F1FEC:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=11292 osp_idx=0 osp_ver=476934 osp_pg=0 (spawn #447243) 2025-08-02 08:20:19.643985 :BA3F1FED:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=1236 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-02 08:20:19.643985 :BA3F1FEE:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=237 time=2803488954 2025-08-02 08:20:19.643986 :BA3F1FEF:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-08-02 08:20:19.643986 :BA3F1FF0:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-08-02 08:20:19.643987 :BA3F1FF1:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-08-02 08:20:19.643987 :BA3F1FF2:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-02 08:20:19.643988 :BA3F1FF3:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-02 08:20:19.644524 :BA3F1FF4: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-08-02 08:20:19.644567 :BA3F1FFA: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: 11292, W000)