Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250624080101\orcl_w000_5020_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:36320M/63366M, Ph+PgF:42511M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 5020, image: ORACLE.EXE (W000) *** 2025-06-24 08:01:01.161 *** SESSION ID:(177.39701) 2025-06-24 08:01:01.161 *** 2025-06-24 08:01:01.161 Process diagnostic dump for ORACLE.EXE (W000), OS id=5020, pid: 22, proc_ser: 250, sid: 177, sess_ser: 39701 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=114 seq_num=115 snap_id=1 wait times: snap=2.955038 sec, exc=2.955038 sec, total=2.955038 sec wait times: max=5.000000 sec, heur=2.955038 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.001824 sec since current wait 0: waited for 'Disk file operations I/O' FileOperation=0x2, fileno=0x1, filetype=0x2 wait_id=113 seq_num=114 snap_id=1 wait times: snap=0.000092 sec, exc=0.000092 sec, total=0.000092 sec wait times: max=infinite wait counts: calls=0 os=0 occurred after 0.000184 sec of elapsed time 1: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=112 seq_num=113 snap_id=1 wait times: snap=1.680819 sec, exc=1.680819 sec, total=1.680819 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=111 seq_num=112 snap_id=1 wait times: snap=5.003381 sec, exc=5.003381 sec, total=5.003381 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000025 sec of elapsed time 3: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=110 seq_num=111 snap_id=1 wait times: snap=5.000435 sec, exc=5.000435 sec, total=5.000435 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 4: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=109 seq_num=110 snap_id=1 wait times: snap=5.007175 sec, exc=5.007175 sec, total=5.007175 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000023 sec of elapsed time 5: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=108 seq_num=109 snap_id=1 wait times: snap=5.005092 sec, exc=5.005092 sec, total=5.005092 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000024 sec of elapsed time 6: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=107 seq_num=108 snap_id=1 wait times: snap=5.014715 sec, exc=5.014715 sec, total=5.014715 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000022 sec of elapsed time 7: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=106 seq_num=107 snap_id=1 wait times: snap=5.004631 sec, exc=5.004631 sec, total=5.004631 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000026 sec of elapsed time 8: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=105 seq_num=106 snap_id=1 wait times: snap=5.009697 sec, exc=5.009697 sec, total=5.009697 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000052 sec of elapsed time 9: waited for 'Space Manager: slave idle wait' Slave ID=0x0, =0x0, =0x0 wait_id=104 seq_num=105 snap_id=1 wait times: snap=5.003199 sec, exc=5.003199 sec, total=5.003199 sec wait times: max=5.000000 sec wait counts: calls=1 os=1 occurred after 0.000048 sec of elapsed time Sampled Session History of session 177 serial 39701 --------------------------------------------------- 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, 07:59:01 - 08:01:01] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-06-24 08:01:01.161 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 5020, W000) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-06-24 06:49:42.624696 :A8E2A760:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 06:49:50.362994 :A8E2A9C1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 06:49:50.357 2025-06-24 06:49:50.362996 :A8E2A9C2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16732 pso_num=22 pso_serial#=233 2025-06-24 06:49:50.363133 :A8E2A9C3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16732 (legacy spawn) 2025-06-24 06:49:50.369655 :A8E2A9C4: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-24 06:49:50.369832 :A8E2A9CD: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-24 06:49:50.370656 :A8E2A9CE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 06:50:02.121671 :A8E2AD3E:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 06:50:02.115 2025-06-24 06:50:02.121673 :A8E2AD3F:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14644 pso_num=22 pso_serial#=234 2025-06-24 06:50:02.121810 :A8E2AD40:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=14644 osp_idx=0 osp_ver=346851 osp_pg=0 (spawn #317069) 2025-06-24 06:50:02.121811 :A8E2AD41:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 06:50:02.121811 :A8E2AD42:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=64 time=3723545313 2025-06-24 06:50:02.121812 :A8E2AD43:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 06:50:02.121812 :A8E2AD44:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 06:50:02.121812 :A8E2AD45:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 06:50:02.121813 :A8E2AD46:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 06:50:02.121813 :A8E2AD47:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 06:50:02.122299 :A8E2AD48: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-06-24 06:50:02.122947 :A8E2AD5D: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-24 06:50:02.133960 :A8E2AD79:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-24 06:50:02.134420 :A8E2AD7A:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-06-24 06:50:02.136825 :A8E2AD7B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-24 06:50:02.137204 :A8E2AD7C: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-24 06:50:02.137239 :A8E2AD7F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-24 06:50:02.138108 :A8E2AD85: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-24 06:50:02.138226 :A8E2AD8E: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-24 06:50:32.225816 :A8E2B752:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 06:50:45.465392 :A8E2BB73:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 06:50:45.457 2025-06-24 06:50:45.465395 :A8E2BB74:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=16948 pso_num=22 pso_serial#=235 2025-06-24 06:50:45.465534 :A8E2BB75:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=16948 (legacy spawn) 2025-06-24 06:50:45.471878 :A8E2BB76: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-24 06:50:45.472053 :A8E2BB7C: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-24 06:50:45.472911 :A8E2BB80:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 06:50:52.008539 :A8E2BD7F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 06:50:51.994 2025-06-24 06:50:52.008541 :A8E2BD80:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13440 pso_num=22 pso_serial#=236 2025-06-24 06:50:52.008677 :A8E2BD81:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13440 (legacy spawn) 2025-06-24 06:50:52.015117 :A8E2BD82: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-24 06:50:52.015299 :A8E2BD89: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-24 06:50:52.016084 :A8E2BD8C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 06:50:52.024728 :A8E2BD8F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 06:50:52.010 2025-06-24 06:50:52.024730 :A8E2BD90:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13284 pso_num=22 pso_serial#=237 2025-06-24 06:50:52.024861 :A8E2BD91:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13284 (legacy spawn) 2025-06-24 06:50:52.031292 :A8E2BD92: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-24 06:50:52.031454 :A8E2BD97: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-24 06:50:52.032234 :A8E2BD9C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 06:50:52.533965 :A8E2BDE1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 06:50:52.525 2025-06-24 06:50:52.533967 :A8E2BDE2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=12004 pso_num=22 pso_serial#=238 2025-06-24 06:50:52.534107 :A8E2BDE3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=12004 (legacy spawn) 2025-06-24 06:50:52.540774 :A8E2BDE4: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-24 06:50:52.540985 :A8E2BDEC: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-24 06:50:52.541843 :A8E2BDEE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 06:50:55.111109 :A8E2BEB0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 06:50:55.104 2025-06-24 06:50:55.111111 :A8E2BEB1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17620 pso_num=22 pso_serial#=239 2025-06-24 06:50:55.111245 :A8E2BEB2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17620 (legacy spawn) 2025-06-24 06:50:55.117665 :A8E2BEB3: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-24 06:50:55.117826 :A8E2BEB8: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-24 06:50:55.118637 :A8E2BEBD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 06:50:55.127251 :A8E2BEC0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 06:50:55.119 2025-06-24 06:50:55.127252 :A8E2BEC1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13244 pso_num=22 pso_serial#=240 2025-06-24 06:50:55.127385 :A8E2BEC2:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=13244 (legacy spawn) 2025-06-24 06:50:55.133799 :A8E2BEC3: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-24 06:50:55.133964 :A8E2BECB: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-24 06:50:55.134800 :A8E2BECD:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 06:51:02.196470 :A8E2C0F4:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 06:51:02.190 2025-06-24 06:51:02.196473 :A8E2C0F5:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18244 pso_num=22 pso_serial#=241 2025-06-24 06:51:02.196630 :A8E2C0F6:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18244 osp_idx=0 osp_ver=346858 osp_pg=0 (spawn #317071) 2025-06-24 06:51:02.196631 :A8E2C0F7:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-06-24 06:51:02.196631 :A8E2C0F8:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=65 time=3723605391 2025-06-24 06:51:02.196632 :A8E2C0F9:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 06:51:02.196632 :A8E2C0FA:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 06:51:02.196633 :A8E2C0FB:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 06:51:02.196633 :A8E2C0FC:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 06:51:02.196634 :A8E2C0FD:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 06:51:02.197128 :A8E2C0FE: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-06-24 06:51:02.197774 :A8E2C113: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-24 06:51:02.205326 :A8E2C130:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-06-24 06:51:02.205678 :A8E2C131: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-24 06:51:02.205715 :A8E2C134:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-06-24 06:51:02.206573 :A8E2C13A: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-24 06:51:02.206693 :A8E2C143: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-24 06:51:32.285502 :A8E2CAE1:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 06:51:32.446192 :A8E2CB26:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 06:51:32.441 2025-06-24 06:51:32.446194 :A8E2CB27:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18280 pso_num=22 pso_serial#=242 2025-06-24 06:51:32.446336 :A8E2CB28:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18280 osp_idx=0 osp_ver=346859 osp_pg=0 (spawn #317073) 2025-06-24 06:51:32.446336 :A8E2CB29: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-24 06:51:32.446337 :A8E2CB2A:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=66 time=3723635641 2025-06-24 06:51:32.446337 :A8E2CB2B:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 06:51:32.446337 :A8E2CB2C:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 06:51:32.446338 :A8E2CB2D:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 06:51:32.446338 :A8E2CB2E:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 06:51:32.446339 :A8E2CB2F:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 06:51:32.446836 :A8E2CB30: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-24 06:51:32.446884 :A8E2CB37: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-24 07:01:33.262794 :A8E39557:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 07:01:33.463682 :A8E3956A:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 07:01:33.450 2025-06-24 07:01:33.463684 :A8E3956B:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17668 pso_num=22 pso_serial#=243 2025-06-24 07:01:33.463816 :A8E3956C:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=17668 (legacy spawn) 2025-06-24 07:01:33.470279 :A8E3956D: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-24 07:01:33.470450 :A8E39572: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-24 07:01:33.471248 :A8E39577:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 07:01:33.814393 :A8E395AF:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 07:01:33.810 2025-06-24 07:01:33.814395 :A8E395B0:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=15516 pso_num=22 pso_serial#=244 2025-06-24 07:01:33.814536 :A8E395B1:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=15516 osp_idx=0 osp_ver=346861 osp_pg=0 (spawn #317099) 2025-06-24 07:01:33.814536 :A8E395B2: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-24 07:01:33.814537 :A8E395B3:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=67 time=3724237000 2025-06-24 07:01:33.814537 :A8E395B4:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:01:33.814538 :A8E395B5:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:01:33.814538 :A8E395B6:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:01:33.814539 :A8E395B7:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:01:33.814539 :A8E395B8:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:01:33.815077 :A8E395B9: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-24 07:01:33.815121 :A8E395C0: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-24 07:11:34.649210 :A8E4626C:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 07:11:35.195295 :A8E462D2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 07:11:35.190 2025-06-24 07:11:35.195297 :A8E462D3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=17936 pso_num=22 pso_serial#=245 2025-06-24 07:11:35.195444 :A8E462D4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=17936 osp_idx=0 osp_ver=346862 osp_pg=0 (spawn #317122) 2025-06-24 07:11:35.195445 :A8E462D5: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-24 07:11:35.195445 :A8E462D6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=68 time=3724838391 2025-06-24 07:11:35.195445 :A8E462D7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:11:35.195446 :A8E462D8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:11:35.195446 :A8E462D9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:11:35.195446 :A8E462DA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:11:35.195447 :A8E462DB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:11:35.195975 :A8E462DC: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-24 07:11:35.196023 :A8E462E4: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-24 07:21:36.068475 :A8E52CE8:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 07:21:36.533830 :A8E52CFB:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 07:21:36.521 2025-06-24 07:21:36.533832 :A8E52CFC:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18352 pso_num=22 pso_serial#=246 2025-06-24 07:21:36.533969 :A8E52CFD:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=18352 (legacy spawn) 2025-06-24 07:21:36.540394 :A8E52CFE: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-24 07:21:36.540574 :A8E52D03: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-24 07:21:36.541373 :A8E52D08:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 07:21:36.620000 :A8E52D59:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 07:21:36.615 2025-06-24 07:21:36.620003 :A8E52D5A:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=13760 pso_num=22 pso_serial#=247 2025-06-24 07:21:36.620145 :A8E52D5B:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=13760 osp_idx=0 osp_ver=346864 osp_pg=0 (spawn #317145) 2025-06-24 07:21:36.620146 :A8E52D5C: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-24 07:21:36.620146 :A8E52D5D:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=69 time=3725439813 2025-06-24 07:21:36.620147 :A8E52D5E:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:21:36.620147 :A8E52D5F:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:21:36.620147 :A8E52D60:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:21:36.620148 :A8E52D61:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:21:36.620148 :A8E52D62:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:21:36.620658 :A8E52D63: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-24 07:21:36.620705 :A8E52D6C: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-24 07:31:37.505341 :A8E5F821:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 07:31:38.020192 :A8E5F87F:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 07:31:38.015 2025-06-24 07:31:38.020194 :A8E5F880:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18356 pso_num=22 pso_serial#=248 2025-06-24 07:31:38.020338 :A8E5F881:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18356 osp_idx=0 osp_ver=346865 osp_pg=0 (spawn #317168) 2025-06-24 07:31:38.020339 :A8E5F882: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-24 07:31:38.020340 :A8E5F883:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=70 time=3726041204 2025-06-24 07:31:38.020341 :A8E5F884:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:31:38.020341 :A8E5F885:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:31:38.020341 :A8E5F886:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:31:38.020342 :A8E5F887:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:31:38.020342 :A8E5F888:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:31:38.020849 :A8E5F889: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-24 07:31:38.020893 :A8E5F891: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-24 07:41:38.910351 :A8E6C26A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 07:41:39.415194 :A8E6C2C3:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 07:41:39.411 2025-06-24 07:41:39.415196 :A8E6C2C4:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=18124 pso_num=22 pso_serial#=249 2025-06-24 07:41:39.415341 :A8E6C2C5:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=18124 osp_idx=0 osp_ver=346866 osp_pg=0 (spawn #317190) 2025-06-24 07:41:39.415341 :A8E6C2C6: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-24 07:41:39.415342 :A8E6C2C7:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=71 time=3726642610 2025-06-24 07:41:39.415342 :A8E6C2C8:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:41:39.415343 :A8E6C2C9:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:41:39.415343 :A8E6C2CA:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:41:39.415343 :A8E6C2CB:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:41:39.415344 :A8E6C2CC:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:41:39.415846 :A8E6C2CD: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-24 07:41:39.415892 :A8E6C2D5: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-24 07:51:40.240477 :A8E78B84:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-06-24 07:51:40.698239 :A8E78BD2:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 06-24 07:51:40.693 2025-06-24 07:51:40.698241 :A8E78BD3:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=5020 pso_num=22 pso_serial#=250 2025-06-24 07:51:40.698377 :A8E78BD4:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=5020 osp_idx=0 osp_ver=346867 osp_pg=0 (spawn #317213) 2025-06-24 07:51:40.698378 :A8E78BD5: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-24 07:51:40.698378 :A8E78BD6:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=72 time=3727243891 2025-06-24 07:51:40.698379 :A8E78BD7:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-06-24 07:51:40.698379 :A8E78BD8:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-06-24 07:51:40.698379 :A8E78BD9:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-06-24 07:51:40.698380 :A8E78BDA:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-06-24 07:51:40.698380 :A8E78BDB:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-06-24 07:51:40.698864 :A8E78BDC: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-24 07:51:40.698911 :A8E78BE4: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-24 08:00:58.212361 :A8E880B8: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 ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 5020, W000)