Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250820220004\orcl_vkrm_16844_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:35341M/63366M, Ph+PgF:40563M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 16844, image: ORACLE.EXE (VKRM) *** 2025-08-20 22:00:04.519 *** SESSION ID:(193.58347) 2025-08-20 22:00:04.519 *** 2025-08-20 22:00:04.519 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=16844, pid: 24, proc_ser: 145, sid: 193, sess_ser: 58347 ------------------------------------------------------------------------------- current sql: Current Wait Stack: 0: waiting for 'VKRM Idle' =0x0, =0x0, =0x0 wait_id=0 seq_num=1 snap_id=1 wait times: snap=4.506289 sec, exc=4.506289 sec, total=4.506289 sec wait times: max=infinite, heur=4.506289 sec wait counts: calls=0 os=0 in_wait=1 iflags=0x5a0 Wait State: fixed_waits=0 flags=0x22 boundary=0x0000000000000000/-1 Session Wait History: elapsed time of 0.000000 sec since current wait Sampled Session History of session 193 serial 58347 --------------------------------------------------- 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 --------------------------------------------------- [5 samples, 22:00:00 - 22:00:04] idle wait at each sample [session created at: 22:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-08-20 22:00:04.519 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 16844, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-08-20 21:56:04.994135 :C26C708B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 21:56:04.994135 :C26C708C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=24 time=112667002 2025-08-20 21:56:04.994135 :C26C708D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 21:56:04.994136 :C26C708E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 21:56:04.994137 :C26C708F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 21:56:04.994137 :C26C7090:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 21:56:04.994137 :C26C7091:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 21:56:04.994617 :C26C7092:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 21:56:04.995278 :C26C70A7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:56:05.006318 :C26C70C4:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-20 21:56:05.006771 :C26C70C5:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-20 21:56:05.009177 :C26C70C6:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-20 21:56:05.009507 :C26C70C7:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:56:05.009547 :C26C70CA:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-20 21:56:05.010401 :C26C70D0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:56:05.010514 :C26C70D9:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:56:35.087017 :C26C7A83:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:56:57.997271 :C26C81B6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:56:57.994 2025-08-20 21:56:57.997273 :C26C81B7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20496 pso_num=24 pso_serial#=126 2025-08-20 21:56:57.997410 :C26C81B8:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20496 (legacy spawn) 2025-08-20 21:56:58.003857 :C26C81B9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:56:58.004030 :C26C81C2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:56:58.004799 :C26C81C3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:56:58.032827 :C26C81C6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:56:58.032 2025-08-20 21:56:58.032829 :C26C81C7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22304 pso_num=24 pso_serial#=127 2025-08-20 21:56:58.032960 :C26C81C8:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=22304 (legacy spawn) 2025-08-20 21:56:58.039486 :C26C81C9:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:56:58.039660 :C26C81D2:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:56:58.040472 :C26C81D3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:57:01.198799 :C26C82C7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:57:01.186 2025-08-20 21:57:01.198801 :C26C82C8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21000 pso_num=24 pso_serial#=128 2025-08-20 21:57:01.198935 :C26C82C9:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21000 (legacy spawn) 2025-08-20 21:57:01.205489 :C26C82CA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:57:01.205658 :C26C82CF:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:57:01.206486 :C26C82D4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:57:01.215077 :C26C82D7:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:57:01.201 2025-08-20 21:57:01.215079 :C26C82D8:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19784 pso_num=24 pso_serial#=129 2025-08-20 21:57:01.215210 :C26C82D9:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19784 (legacy spawn) 2025-08-20 21:57:01.221727 :C26C82DA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:57:01.221899 :C26C82E3:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:57:01.222764 :C26C82E4:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:57:05.086879 :C26C8417:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:57:05.081 2025-08-20 21:57:05.086881 :C26C8418:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21128 pso_num=24 pso_serial#=130 2025-08-20 21:57:05.087016 :C26C8419:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21128 osp_idx=22 osp_ver=761825 osp_pg=0 (spawn #509145) 2025-08-20 21:57:05.087017 :C26C841A:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 21:57:05.087017 :C26C841B:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=25 time=112727096 2025-08-20 21:57:05.087019 :C26C841C:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 21:57:05.087020 :C26C841D:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 21:57:05.087021 :C26C841E:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 21:57:05.087021 :C26C841F:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 21:57:05.087021 :C26C8420:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 21:57:05.087507 :C26C8421:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 21:57:05.088150 :C26C8436:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:57:05.095673 :C26C8452:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-20 21:57:05.096028 :C26C8453:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:57:05.096063 :C26C8456:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-20 21:57:05.097000 :C26C845C:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:57:05.097121 :C26C8465:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:57:35.185942 :C26C8ED1:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:57:37.505555 :C26C8FAD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:57:37.492 2025-08-20 21:57:37.505557 :C26C8FAE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19584 pso_num=24 pso_serial#=131 2025-08-20 21:57:37.505703 :C26C8FAF:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19584 (legacy spawn) 2025-08-20 21:57:37.512378 :C26C8FB0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:57:37.512548 :C26C8FB9:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:57:37.513345 :C26C8FBA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:57:37.521995 :C26C8FBD:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:57:37.508 2025-08-20 21:57:37.521997 :C26C8FBE:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=20164 pso_num=24 pso_serial#=132 2025-08-20 21:57:37.522130 :C26C8FBF:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=20164 (legacy spawn) 2025-08-20 21:57:37.528562 :C26C8FC0:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:57:37.528732 :C26C8FC9:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:57:37.529543 :C26C8FCA:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:57:42.250456 :C26C90D2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:57:42.241 2025-08-20 21:57:42.250458 :C26C90D3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21788 pso_num=24 pso_serial#=133 2025-08-20 21:57:42.250609 :C26C90D4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21788 (legacy spawn) 2025-08-20 21:57:42.257546 :C26C90E8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:57:42.257718 :C26C90F1:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:57:42.258539 :C26C90F2:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:57:51.526654 :C26C9401:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:57:51.518 2025-08-20 21:57:51.526657 :C26C9402:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=17604 pso_num=24 pso_serial#=134 2025-08-20 21:57:51.526792 :C26C9403:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=17604 (legacy spawn) 2025-08-20 21:57:51.533338 :C26C9404:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:57:51.533521 :C26C940D:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:57:51.534322 :C26C9419:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:58:05.171884 :C26C9838:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:58:05.166 2025-08-20 21:58:05.171886 :C26C9839:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9904 pso_num=24 pso_serial#=135 2025-08-20 21:58:05.172018 :C26C983A:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9904 osp_idx=22 osp_ver=761830 osp_pg=0 (spawn #509148) 2025-08-20 21:58:05.172019 :C26C983B:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 21:58:05.172020 :C26C983C:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=26 time=112787174 2025-08-20 21:58:05.172020 :C26C983D:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 21:58:05.172020 :C26C983E:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 21:58:05.172021 :C26C983F:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 21:58:05.172021 :C26C9840:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 21:58:05.172021 :C26C9841:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 21:58:05.172511 :C26C9842:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 21:58:05.173244 :C26C9857:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:58:05.184225 :C26C9873:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-20 21:58:05.184698 :C26C9874:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-08-20 21:58:05.187063 :C26C9875:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-20 21:58:05.187400 :C26C9876:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:58:05.187437 :C26C9879:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-20 21:58:05.188311 :C26C987F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:58:05.188438 :C26C9888:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:58:35.269505 :C26CA238:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:58:47.297754 :C26CA5EE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:58:47.283 2025-08-20 21:58:47.297757 :C26CA5EF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19388 pso_num=24 pso_serial#=136 2025-08-20 21:58:47.297894 :C26CA5F0:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19388 (legacy spawn) 2025-08-20 21:58:47.316415 :C26CA5F1:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:58:47.316589 :C26CA5F6:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:58:47.317430 :C26CA5FB:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:58:47.326133 :C26CA5FE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:58:47.315 2025-08-20 21:58:47.326135 :C26CA5FF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22416 pso_num=24 pso_serial#=137 2025-08-20 21:58:47.326272 :C26CA600:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=22416 (legacy spawn) 2025-08-20 21:58:47.332909 :C26CA601:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:58:47.333081 :C26CA60A:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:58:47.333952 :C26CA60B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:58:50.357074 :C26CA706:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:58:50.348 2025-08-20 21:58:50.357076 :C26CA707:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19024 pso_num=24 pso_serial#=138 2025-08-20 21:58:50.357218 :C26CA708:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19024 (legacy spawn) 2025-08-20 21:58:50.363672 :C26CA709:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:58:50.363840 :C26CA70E:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:58:50.364626 :C26CA713:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:58:50.486466 :C26CA716:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:58:50.473 2025-08-20 21:58:50.486468 :C26CA717:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21784 pso_num=24 pso_serial#=139 2025-08-20 21:58:50.486612 :C26CA718:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=21784 (legacy spawn) 2025-08-20 21:58:50.493440 :C26CA719:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:58:50.493618 :C26CA722:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:58:50.494458 :C26CA723:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:58:50.513760 :C26CA726:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:58:50.504 2025-08-20 21:58:50.513762 :C26CA727:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22240 pso_num=24 pso_serial#=140 2025-08-20 21:58:50.513904 :C26CA728:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=22240 (legacy spawn) 2025-08-20 21:58:50.520822 :C26CA729:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:58:50.520992 :C26CA731:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:58:50.521917 :C26CA733:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:58:53.715347 :C26CA814:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:58:53.702 2025-08-20 21:58:53.715349 :C26CA815:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=22088 pso_num=24 pso_serial#=141 2025-08-20 21:58:53.715489 :C26CA816:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=22088 (legacy spawn) 2025-08-20 21:58:53.722015 :C26CA817:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:58:53.722192 :C26CA81C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:58:53.723009 :C26CA821:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:59:05.246089 :C26CABDE:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:59:05.240 2025-08-20 21:59:05.246091 :C26CABDF:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=21752 pso_num=24 pso_serial#=142 2025-08-20 21:59:05.246232 :C26CABE0:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=21752 osp_idx=22 osp_ver=761837 osp_pg=0 (spawn #509150) 2025-08-20 21:59:05.246232 :C26CABE1:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 21:59:05.246232 :C26CABE2:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=27 time=112847252 2025-08-20 21:59:05.246233 :C26CABE3:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 21:59:05.246233 :C26CABE4:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 21:59:05.246234 :C26CABE5:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 21:59:05.246234 :C26CABE6:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 21:59:05.246234 :C26CABE7:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 21:59:05.246727 :C26CABE8:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=59 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 21:59:05.247434 :C26CABFD:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:59:05.254884 :C26CAC19:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-08-20 21:59:05.255298 :C26CAC1A:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:59:05.255334 :C26CAC1D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-08-20 21:59:05.256212 :C26CAC2F:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:59:05.256343 :C26CAC38:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:59:35.355879 :C26CB5E8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:59:56.261768 :C26CBC40:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:59:56.249 2025-08-20 21:59:56.261770 :C26CBC41:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19072 pso_num=24 pso_serial#=143 2025-08-20 21:59:56.261911 :C26CBC42:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19072 (legacy spawn) 2025-08-20 21:59:56.284870 :C26CBC43:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:59:56.285048 :C26CBC48:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:59:56.285888 :C26CBC4D:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 21:59:58.590617 :C26CBD05:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 21:59:58.581 2025-08-20 21:59:58.590619 :C26CBD06:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=19908 pso_num=24 pso_serial#=144 2025-08-20 21:59:58.590762 :C26CBD07:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=19908 (legacy spawn) 2025-08-20 21:59:58.597147 :C26CBD08:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:193] KSL POST SENT postee=14 loc='ksa2.h LINE:282 ID:ksasnd' id1=0 id2=0 name= type=0 2025-08-20 21:59:58.597326 :C26CBD11:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] 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-20 21:59:58.598129 :C26CBD12:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-08-20 22:00:00.026269 :C26CBD66:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 08-20 22:00:00.018 2025-08-20 22:00:00.026271 :C26CBD67:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=16844 pso_num=24 pso_serial#=145 2025-08-20 22:00:00.026384 :C26CBD68:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=16844 osp_idx=22 osp_ver=761840 osp_pg=0 (spawn #509152) 2025-08-20 22:00:00.026384 :C26CBD69:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-08-20 22:00:00.026385 :C26CBD6A:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=28 time=112902033 2025-08-20 22:00:00.026386 :C26CBD6B:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-08-20 22:00:00.026386 :C26CBD6C:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-08-20 22:00:00.026386 :C26CBD6D:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-08-20 22:00:00.026387 :C26CBD6E:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-08-20 22:00:00.026387 :C26CBD6F:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-08-20 22:00:00.026463 :C26CBD70:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:24:0] KSL POST SENT postee=6 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-08-20 22:00:00.026469 :C26CBD71:db_trace:kso.c@5554:kso_spawn_diag_end(): [10420:24:0] kso: spawn diagnostics: init time=0 secs ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 24 (osid: 16844, VKRM)