Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250913060005\orcl_vkrm_19808_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:34820M/63366M, Ph+PgF:39474M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 22 Windows thread id: 19808, image: ORACLE.EXE (VKRM) *** 2025-09-13 06:00:06.005 *** SESSION ID:(177.56587) 2025-09-13 06:00:06.005 *** 2025-09-13 06:00:06.005 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=19808, pid: 22, proc_ser: 216, sid: 177, sess_ser: 56587 ------------------------------------------------------------------------------- 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=6.001702 sec, exc=6.001702 sec, total=6.001702 sec wait times: max=infinite, heur=6.001702 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 177 serial 56587 --------------------------------------------------- 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 --------------------------------------------------- [6 samples, 06:00:00 - 06:00:06] idle wait at each sample [session created at: 06:00:00] ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-09-13 06:00:06.005 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 22 (osid: 19808, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-09-13 05:57:05.404533 :CCBAB84A: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-09-13 05:57:05.405319 :CCBAB84F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:57:05.413926 :CCBAB852:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:57:05.399 2025-09-13 05:57:05.413928 :CCBAB853:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=14268 pso_num=22 pso_serial#=199 2025-09-13 05:57:05.414065 :CCBAB854:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=14268 (legacy spawn) 2025-09-13 05:57:05.420483 :CCBAB855: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-09-13 05:57:05.420640 :CCBAB85E: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-09-13 05:57:05.421450 :CCBAB85F:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:57:05.487560 :CCBAB862:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:57:05.477 2025-09-13 05:57:05.487562 :CCBAB863:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24700 pso_num=22 pso_serial#=200 2025-09-13 05:57:05.487695 :CCBAB864:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24700 (legacy spawn) 2025-09-13 05:57:05.494306 :CCBAB865: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-09-13 05:57:05.494473 :CCBAB86D: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-09-13 05:57:05.495299 :CCBAB86E:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:57:08.598534 :CCBAB963:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:57:08.590 2025-09-13 05:57:08.598536 :CCBAB964:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=20372 pso_num=22 pso_serial#=201 2025-09-13 05:57:08.598704 :CCBAB965:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=20372 (legacy spawn) 2025-09-13 05:57:08.605240 :CCBAB966: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-09-13 05:57:08.605415 :CCBAB96E: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-09-13 05:57:08.606284 :CCBAB970:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:57:08.615088 :CCBAB973:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:57:08.606 2025-09-13 05:57:08.615089 :CCBAB974:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23332 pso_num=22 pso_serial#=202 2025-09-13 05:57:08.615221 :CCBAB975:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23332 (legacy spawn) 2025-09-13 05:57:08.621817 :CCBAB976: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-09-13 05:57:08.621982 :CCBAB97F: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-09-13 05:57:08.622800 :CCBAB980:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:57:17.658793 :CCBABC63:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:57:17.653 2025-09-13 05:57:17.658795 :CCBABC64:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24480 pso_num=22 pso_serial#=203 2025-09-13 05:57:17.658933 :CCBABC65:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24480 osp_idx=0 osp_ver=604115 osp_pg=0 (spawn #586930) 2025-09-13 05:57:17.658934 :CCBABC66:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 05:57:17.658934 :CCBABC67:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=108 time=2128688955 2025-09-13 05:57:17.658934 :CCBABC68:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 05:57:17.658935 :CCBABC69:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 05:57:17.658935 :CCBABC6A:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 05:57:17.658935 :CCBABC6B:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 05:57:17.658936 :CCBABC6C:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 05:57:17.659439 :CCBABC6D: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-09-13 05:57:17.660110 :CCBABC82: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-09-13 05:57:17.667852 :CCBABC9E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-13 05:57:17.668252 :CCBABC9F: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-09-13 05:57:17.668287 :CCBABCA2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-13 05:57:17.669181 :CCBABCA8: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-09-13 05:57:17.669324 :CCBABCB1: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-09-13 05:57:47.753605 :CCBAC69A:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:58:01.166936 :CCBACAA8:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:58:01.153 2025-09-13 05:58:01.166938 :CCBACAA9:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19868 pso_num=22 pso_serial#=204 2025-09-13 05:58:01.167081 :CCBACAAA:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=19868 (legacy spawn) 2025-09-13 05:58:01.173601 :CCBACAAB: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-09-13 05:58:01.173777 :CCBACAB0: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-09-13 05:58:01.174592 :CCBACAB5:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:58:13.729336 :CCBACEED:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:58:13.719 2025-09-13 05:58:13.729338 :CCBACEEE:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=21344 pso_num=22 pso_serial#=205 2025-09-13 05:58:13.729474 :CCBACEEF:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=21344 (legacy spawn) 2025-09-13 05:58:13.752901 :CCBACEF0: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-09-13 05:58:13.753079 :CCBACEF6: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-09-13 05:58:13.754032 :CCBACEFA:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:58:17.742069 :CCBAD03D:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:58:17.742 2025-09-13 05:58:17.742071 :CCBAD03E:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23744 pso_num=22 pso_serial#=206 2025-09-13 05:58:17.742212 :CCBAD03F:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=23744 osp_idx=0 osp_ver=604118 osp_pg=0 (spawn #586932) 2025-09-13 05:58:17.742213 :CCBAD040:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 05:58:17.742213 :CCBAD041:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=109 time=2128749049 2025-09-13 05:58:17.742214 :CCBAD042:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 05:58:17.742214 :CCBAD043:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 05:58:17.742215 :CCBAD044:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 05:58:17.742215 :CCBAD045:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 05:58:17.742215 :CCBAD046:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 05:58:17.742698 :CCBAD047: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-09-13 05:58:17.743416 :CCBAD05C: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-09-13 05:58:17.758185 :CCBAD079:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-13 05:58:17.758268 :CCBAD07A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-13 05:58:17.778460 :CCBAD07B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-13 05:58:17.778530 :CCBAD07C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-13 05:58:17.778823 :CCBAD07D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-13 05:58:17.778860 :CCBAD07E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-13 05:58:17.778911 :CCBAD07F:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-13 05:58:17.779249 :CCBAD080: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-09-13 05:58:17.779287 :CCBAD083:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-13 05:58:17.790208 :CCBAD089: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-09-13 05:58:17.790291 :CCBAD08C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-09-13 05:58:17.790330 :CCBAD08D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-09-13 05:58:17.792209 :CCBAD093:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-13 05:58:17.792253 :CCBAD094:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-13 05:58:17.792829 :CCBAD095:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-13 05:58:17.793065 :CCBAD096: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-09-13 05:58:17.793102 :CCBAD099:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-13 05:58:17.798260 :CCBAD09F: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-09-13 05:58:17.798325 :CCBAD0A2:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:ktcc.c:879 2025-09-13 05:58:17.798359 :CCBAD0A3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:ktcc.c:879 2025-09-13 05:58:17.798659 :CCBAD0A9:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-13 05:58:17.798979 :CCBAD0AA:db_trace:kwqid.c@2146:kwqidrdq(): [10960:22:177] kwqidrdq: Error: 1403 occured in OCI execute 2025-09-13 05:58:17.799167 :CCBAD0AB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-13 05:58:17.799389 :CCBAD0AC: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-09-13 05:58:17.799414 :CCBAD0AF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-13 05:58:17.801029 :CCBAD0B5:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-13 05:58:17.801245 :CCBAD0B6: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-09-13 05:58:17.801273 :CCBAD0B9:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-13 05:58:17.802180 :CCBAD0BF: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-09-13 05:58:17.802297 :CCBAD0C8: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-09-13 05:58:47.865705 :CCBADA90:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:58:53.292369 :CCBADC46:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:58:53.289 2025-09-13 05:58:53.292372 :CCBADC47:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24280 pso_num=22 pso_serial#=207 2025-09-13 05:58:53.292522 :CCBADC48:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24280 (legacy spawn) 2025-09-13 05:58:53.299454 :CCBADC49: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-09-13 05:58:53.299629 :CCBADC52: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-09-13 05:58:53.300588 :CCBADC53:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:58:53.309901 :CCBADC56:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:58:53.305 2025-09-13 05:58:53.309902 :CCBADC57:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=23688 pso_num=22 pso_serial#=208 2025-09-13 05:58:53.310046 :CCBADC58:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=23688 (legacy spawn) 2025-09-13 05:58:53.316982 :CCBADC59: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-09-13 05:58:53.317178 :CCBADC62: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-09-13 05:58:53.318136 :CCBADC63:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:58:54.891119 :CCBADCB1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:58:54.884 2025-09-13 05:58:54.891121 :CCBADCB2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24588 pso_num=22 pso_serial#=209 2025-09-13 05:58:54.891262 :CCBADCB3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24588 (legacy spawn) 2025-09-13 05:58:54.897851 :CCBADCB4: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-09-13 05:58:54.898029 :CCBADCB9: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-09-13 05:58:54.898838 :CCBADCBE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:58:54.907741 :CCBADCC1:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:58:54.900 2025-09-13 05:58:54.907742 :CCBADCC2:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24348 pso_num=22 pso_serial#=210 2025-09-13 05:58:54.907883 :CCBADCC3:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24348 (legacy spawn) 2025-09-13 05:58:54.914447 :CCBADCC4: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-09-13 05:58:54.914648 :CCBADCCD: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-09-13 05:58:54.915484 :CCBADCCE:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:58:58.109401 :CCBADDCA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:58:58.094 2025-09-13 05:58:58.109404 :CCBADDCB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=22072 pso_num=22 pso_serial#=211 2025-09-13 05:58:58.109551 :CCBADDCC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=22072 (legacy spawn) 2025-09-13 05:58:58.116622 :CCBADDCD: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-09-13 05:58:58.116810 :CCBADDD2: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-09-13 05:58:58.117698 :CCBADDD7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:58:58.127314 :CCBADDDA:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:58:58.126 2025-09-13 05:58:58.127316 :CCBADDDB:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25316 pso_num=22 pso_serial#=212 2025-09-13 05:58:58.127460 :CCBADDDC:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=25316 (legacy spawn) 2025-09-13 05:58:58.134407 :CCBADDDD: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-09-13 05:58:58.134587 :CCBADDE6: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-09-13 05:58:58.135544 :CCBADDE7:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:59:03.404536 :CCBADFC9:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:59:03.396 2025-09-13 05:59:03.404538 :CCBADFCA:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24720 pso_num=22 pso_serial#=213 2025-09-13 05:59:03.404674 :CCBADFCB:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=24720 (legacy spawn) 2025-09-13 05:59:03.411179 :CCBADFCC: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-09-13 05:59:03.411356 :CCBADFD1: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-09-13 05:59:03.412186 :CCBADFD6:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:59:17.816171 :CCBAE491:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:59:17.810 2025-09-13 05:59:17.816173 :CCBAE492:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=24724 pso_num=22 pso_serial#=214 2025-09-13 05:59:17.816318 :CCBAE493:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=24724 osp_idx=0 osp_ver=604126 osp_pg=0 (spawn #586934) 2025-09-13 05:59:17.816319 :CCBAE494:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 05:59:17.816319 :CCBAE495:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=110 time=2128809127 2025-09-13 05:59:17.816320 :CCBAE496:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 05:59:17.816320 :CCBAE497:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 05:59:17.816320 :CCBAE498:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 05:59:17.816321 :CCBAE499:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 05:59:17.816321 :CCBAE49A:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 05:59:17.816818 :CCBAE49B: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-09-13 05:59:17.817460 :CCBAE4B0: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-09-13 05:59:17.825048 :CCBAE4CC:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpus:psdpgi.c:2235 2025-09-13 05:59:17.825415 :CCBAE4CD: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-09-13 05:59:17.825453 :CCBAE4D0:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:22:177] atxpop:psdpgi.c:2235 2025-09-13 05:59:17.826309 :CCBAE4D6: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-09-13 05:59:17.826430 :CCBAE4DF: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-09-13 05:59:47.887334 :CCBAEEB9:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 05:59:56.340932 :CCBAF147:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 05:59:56.327 2025-09-13 05:59:56.340934 :CCBAF148:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=25016 pso_num=22 pso_serial#=215 2025-09-13 05:59:56.341069 :CCBAF149:db_trace:kso.c@4070:ksonfy(): [10420:22:0] kso: new process: pid=25016 (legacy spawn) 2025-09-13 05:59:56.347615 :CCBAF14A: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-09-13 05:59:56.347789 :CCBAF150: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-09-13 05:59:56.348594 :CCBAF154:db_trace:kst.c@698:kstpsodel(): [10280:22:0] kst: process state object about to be deleted 2025-09-13 06:00:00.012036 :CCBAF2A0:db_trace:kst.c@673:kstipg(): [10280:22:0] kst: process state object created on 09-13 06:00:00.004 2025-09-13 06:00:00.012038 :CCBAF2A1:db_trace:kst.c@679:kstipg(): [10280:22:0] kst: process info: ospid=19808 pso_num=22 pso_serial#=216 2025-09-13 06:00:00.012156 :CCBAF2A2:db_trace:kso.c@4093:ksonfy(): [10420:22:0] kso: new process: pid=19808 osp_idx=0 osp_ver=604128 osp_pg=0 (spawn #586936) 2025-09-13 06:00:00.012157 :CCBAF2A3:db_trace:kso.c@4099:ksonfy(): [10420:22:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-09-13 06:00:00.012157 :CCBAF2A4:db_trace:kso.c@4103:ksonfy(): [10420:22:0] kso: new process: pso_index=22 reservation=111 time=2128851315 2025-09-13 06:00:00.012158 :CCBAF2A5:db_trace:kso.c@4109:ksonfy(): [10420:22:0] kso: spawn diagnostics: queue time=0 secs 2025-09-13 06:00:00.012158 :CCBAF2A6:db_trace:kso.c@4111:ksonfy(): [10420:22:0] kso: spawn diagnostics: fork time=0 secs 2025-09-13 06:00:00.012158 :CCBAF2A7:db_trace:kso.c@4113:ksonfy(): [10420:22:0] kso: spawn diagnostics: exec time=0 secs 2025-09-13 06:00:00.012159 :CCBAF2A8:db_trace:kso.c@4115:ksonfy(): [10420:22:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-09-13 06:00:00.012159 :CCBAF2A9:db_trace:kso.c@4117:ksonfy(): [10420:22:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-09-13 06:00:00.012231 :CCBAF2AA:db_trace:ksl2.c@14083:ksl_update_post_stats(): [10005:22:0] KSL POST SENT postee=6 loc='kso2.h LINE:416 ID:ksoreq_reply' id1=0 id2=0 name= type=0 2025-09-13 06:00:00.012238 :CCBAF2AB:db_trace:kso.c@5554:kso_spawn_diag_end(): [10420:22:0] kso: spawn diagnostics: init time=0 secs ------------------------------------------------------------------------------- Trace Bucket Dump End: default bucket for process 22 (osid: 19808, VKRM)