Trace file d:\app\administrator\diag\rdbms\orcl\orcl\trace\cdmp_20250518140049\orcl_vkrm_9748_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:37403M/63366M, Ph+PgF:44614M/72582M Instance name: orcl Redo thread mounted by this instance: 1 Oracle process number: 24 Windows thread id: 9748, image: ORACLE.EXE (VKRM) *** 2025-05-18 14:00:49.166 *** SESSION ID:(193.4463) 2025-05-18 14:00:49.166 *** 2025-05-18 14:00:49.166 Process diagnostic dump for ORACLE.EXE (VKRM), OS id=9748, pid: 24, proc_ser: 94, sid: 193, sess_ser: 4463 ------------------------------------------------------------------------------- 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=480 min 49 sec, exc=480 min 49 sec, total=480 min 49 sec wait times: max=infinite, heur=480 min 49 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 4463 --------------------------------------------------- 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, 13:58:49 - 14:00:49] idle wait at each sample ------------------------------------------------------------------------------- Process diagnostic dump actual duration=0.000000 sec (max dump time=30.000000 sec) *** 2025-05-18 14:00:49.166 ------------------------------------------------------------------------------- Trace Bucket Dump Begin: default bucket for process 24 (osid: 9748, VKRM) TIME(*=approx):SEQ:COMPONENT:FILE@LINE:FUNCTION:SECT/DUMP: [EVENT#:PID:SID] DATA ------------------------------------------------------------------------------- 2025-05-18 05:56:21.251642 :989C77C6:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 05:56:21.252171 :989C77C7: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-05-18 05:56:21.252817 :989C77DC: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-05-18 05:56:21.263917 :989C77F9:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-18 05:56:21.264397 :989C77FA:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-18 05:56:21.266714 :989C77FB:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-18 05:56:21.267066 :989C77FC: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-05-18 05:56:21.267102 :989C77FF:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-18 05:56:21.267987 :989C7805: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-05-18 05:56:21.268129 :989C780E: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-05-18 05:56:51.343991 :989C8218:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:57:03.469820 :989C85F1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:57:03.457 2025-05-18 05:57:03.469821 :989C85F2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9508 pso_num=24 pso_serial#=76 2025-05-18 05:57:03.469957 :989C85F3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9508 (legacy spawn) 2025-05-18 05:57:03.476381 :989C85F4: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-05-18 05:57:03.476549 :989C85F9: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-05-18 05:57:03.477326 :989C85FE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:57:14.995646 :989C89B6:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:57:14.988 2025-05-18 05:57:14.995648 :989C89B7:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12192 pso_num=24 pso_serial#=77 2025-05-18 05:57:14.995780 :989C89B8:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12192 (legacy spawn) 2025-05-18 05:57:15.002306 :989C89B9: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-05-18 05:57:15.002472 :989C89BE: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-05-18 05:57:15.003343 :989C89C3:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:57:16.323279 :989C8A39:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:57:16.318 2025-05-18 05:57:16.323281 :989C8A3A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13716 pso_num=24 pso_serial#=78 2025-05-18 05:57:16.323420 :989C8A3B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13716 osp_idx=22 osp_ver=285167 osp_pg=0 (spawn #193605) 2025-05-18 05:57:16.323420 :989C8A3C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 05:57:16.323421 :989C8A3D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=53 time=523646188 2025-05-18 05:57:16.323421 :989C8A3E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 05:57:16.323422 :989C8A3F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 05:57:16.323422 :989C8A40:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 05:57:16.323422 :989C8A41:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 05:57:16.323423 :989C8A42:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 05:57:16.323899 :989C8A43: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-05-18 05:57:16.324516 :989C8A58: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-05-18 05:57:16.330002 :989C8A74: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-05-18 05:57:16.330989 :989C8A7C: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-05-18 05:57:16.331119 :989C8A85: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-05-18 05:57:21.335399 :989C8C3C:db_trace:ksl2.c@12699:ksliwat(): [10005:24:193] KSL POST RCVD poster=59 loc='kkj.h LINE:914 ID:kkjpstnp' id1=0 id2=0 name= type=0 fac#=0 facpost=1 2025-05-18 05:57:51.437922 :989C9652:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:57:57.628266 :989C984E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:57:57.614 2025-05-18 05:57:57.628268 :989C984F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13764 pso_num=24 pso_serial#=79 2025-05-18 05:57:57.628399 :989C9850:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=13764 (legacy spawn) 2025-05-18 05:57:57.635155 :989C9861: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-05-18 05:57:57.635325 :989C9866: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-05-18 05:57:57.636294 :989C986B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:57:57.644783 :989C986E:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:57:57.630 2025-05-18 05:57:57.644785 :989C986F:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=11908 pso_num=24 pso_serial#=80 2025-05-18 05:57:57.644920 :989C9870:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=11908 (legacy spawn) 2025-05-18 05:57:57.651478 :989C9871: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-05-18 05:57:57.651636 :989C987A: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-05-18 05:57:57.652454 :989C987B:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:58:01.098445 :989C999C:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:58:01.098 2025-05-18 05:58:01.098446 :989C999D:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14176 pso_num=24 pso_serial#=81 2025-05-18 05:58:01.098590 :989C999E:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14176 (legacy spawn) 2025-05-18 05:58:01.105006 :989C999F: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-05-18 05:58:01.105188 :989C99A8: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-05-18 05:58:01.105985 :989C99A9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:58:01.114923 :989C99AC:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:58:01.113 2025-05-18 05:58:01.114925 :989C99AD:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=3624 pso_num=24 pso_serial#=82 2025-05-18 05:58:01.115060 :989C99AE:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=3624 (legacy spawn) 2025-05-18 05:58:01.121440 :989C99AF: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-05-18 05:58:01.121603 :989C99B8: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-05-18 05:58:01.122390 :989C99B9:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:58:11.454147 :989C9CC2:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:58:11.445 2025-05-18 05:58:11.454149 :989C9CC3:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10896 pso_num=24 pso_serial#=83 2025-05-18 05:58:11.454326 :989C9CC4:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10896 (legacy spawn) 2025-05-18 05:58:11.460719 :989C9CC5: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-05-18 05:58:11.460751 :989C9CC8: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-05-18 05:58:11.460913 :989C9CCB: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-05-18 05:58:11.461753 :989C9CDC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:58:17.215052 :989C9EBF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:58:17.207 2025-05-18 05:58:17.215054 :989C9EC0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10720 pso_num=24 pso_serial#=84 2025-05-18 05:58:17.215193 :989C9EC1:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10720 (legacy spawn) 2025-05-18 05:58:17.221676 :989C9EC2: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-05-18 05:58:17.221852 :989C9EC7: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-05-18 05:58:17.222706 :989C9ECC:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:58:21.463540 :989CA050:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:58:21.457 2025-05-18 05:58:21.463542 :989CA051:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13960 pso_num=24 pso_serial#=85 2025-05-18 05:58:21.463680 :989CA052:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13960 osp_idx=22 osp_ver=285174 osp_pg=0 (spawn #193607) 2025-05-18 05:58:21.463681 :989CA053:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 05:58:21.463681 :989CA054:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=54 time=523711329 2025-05-18 05:58:21.463681 :989CA055:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 05:58:21.463682 :989CA056:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 05:58:21.463682 :989CA057:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 05:58:21.463682 :989CA058:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 05:58:21.463683 :989CA059:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 05:58:21.464154 :989CA05A: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-05-18 05:58:21.464929 :989CA06F: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-05-18 05:58:21.479244 :989CA08B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-18 05:58:21.479326 :989CA08C:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-18 05:58:21.480206 :989CA08D:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-18 05:58:21.480549 :989CA08E: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-05-18 05:58:21.480589 :989CA091:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-18 05:58:21.488542 :989CA097: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-05-18 05:58:21.488629 :989CA09A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:ktcc.c:879 2025-05-18 05:58:21.488669 :989CA09B:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:ktcc.c:879 2025-05-18 05:58:21.489101 :989CA0A1:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-18 05:58:21.489494 :989CA0A2:db_trace:kwqid.c@2146:kwqidrdq(): [10960:24:193] kwqidrdq: Error: 1403 occured in OCI execute 2025-05-18 05:58:21.489694 :989CA0A3:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-18 05:58:21.489958 :989CA0A4: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-05-18 05:58:21.489987 :989CA0A7:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-18 05:58:21.491619 :989CA0AD:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-18 05:58:21.491873 :989CA0AE: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-05-18 05:58:21.491904 :989CA0B1:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-18 05:58:21.492820 :989CA0B7: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-05-18 05:58:51.585747 :989CAAC8:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:59:10.626916 :989CB0FA:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:59:10.624 2025-05-18 05:59:10.626918 :989CB0FB:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9168 pso_num=24 pso_serial#=86 2025-05-18 05:59:10.627052 :989CB0FC:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9168 (legacy spawn) 2025-05-18 05:59:10.633472 :989CB0FD: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-05-18 05:59:10.633655 :989CB103: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-05-18 05:59:10.634491 :989CB107:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:59:10.663810 :989CB10A:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:59:10.655 2025-05-18 05:59:10.663812 :989CB10B:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10624 pso_num=24 pso_serial#=87 2025-05-18 05:59:10.663954 :989CB10C:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10624 (legacy spawn) 2025-05-18 05:59:10.670303 :989CB10D: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-05-18 05:59:10.670468 :989CB116: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-05-18 05:59:10.671279 :989CB117:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:59:13.928860 :989CB211:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:59:13.915 2025-05-18 05:59:13.928862 :989CB212:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=12692 pso_num=24 pso_serial#=88 2025-05-18 05:59:13.928995 :989CB213:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=12692 (legacy spawn) 2025-05-18 05:59:13.935448 :989CB214: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-05-18 05:59:13.935621 :989CB21C: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-05-18 05:59:13.936406 :989CB21E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:59:13.944969 :989CB221:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:59:13.931 2025-05-18 05:59:13.944970 :989CB222:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9064 pso_num=24 pso_serial#=89 2025-05-18 05:59:13.945104 :989CB223:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=9064 (legacy spawn) 2025-05-18 05:59:13.951556 :989CB224: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-05-18 05:59:13.951721 :989CB22D: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-05-18 05:59:13.952523 :989CB22E:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:59:19.381464 :989CB417:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:59:19.373 2025-05-18 05:59:19.381467 :989CB418:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=14136 pso_num=24 pso_serial#=90 2025-05-18 05:59:19.381595 :989CB419:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=14136 (legacy spawn) 2025-05-18 05:59:19.388065 :989CB41A: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-05-18 05:59:19.388359 :989CB423: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-05-18 05:59:19.389219 :989CB424:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:59:19.845130 :989CB427:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:59:19.836 2025-05-18 05:59:19.845132 :989CB428:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=10216 pso_num=24 pso_serial#=91 2025-05-18 05:59:19.845276 :989CB429:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=10216 (legacy spawn) 2025-05-18 05:59:19.851679 :989CB42A: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-05-18 05:59:19.851857 :989CB42F: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-05-18 05:59:19.852730 :989CB434:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:59:21.562331 :989CB4DF:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:59:21.556 2025-05-18 05:59:21.562333 :989CB4E0:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=13996 pso_num=24 pso_serial#=92 2025-05-18 05:59:21.562471 :989CB4E1:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=13996 osp_idx=22 osp_ver=285181 osp_pg=0 (spawn #193610) 2025-05-18 05:59:21.562472 :989CB4E2:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=4308 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 05:59:21.562473 :989CB4E3:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=55 time=523771438 2025-05-18 05:59:21.562473 :989CB4E4:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 05:59:21.562474 :989CB4E5:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 05:59:21.562474 :989CB4E6:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 05:59:21.562474 :989CB4E7:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 05:59:21.562474 :989CB4E8:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 05:59:21.562974 :989CB4E9: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-05-18 05:59:21.563608 :989CB4FE: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-05-18 05:59:21.571141 :989CB51A:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpus:psdpgi.c:2235 2025-05-18 05:59:21.571501 :989CB51B: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-05-18 05:59:21.571537 :989CB51E:db_trace:ktc.c@8711:ktcAutoTxnTrace(): [10441:24:193] atxpop:psdpgi.c:2235 2025-05-18 05:59:21.572384 :989CB524: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-05-18 05:59:21.572500 :989CB52D: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-05-18 05:59:51.648786 :989CBF79:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 05:59:53.871121 :989CBFE1:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 05:59:53.862 2025-05-18 05:59:53.871123 :989CBFE2:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=8928 pso_num=24 pso_serial#=93 2025-05-18 05:59:53.871260 :989CBFE3:db_trace:kso.c@4070:ksonfy(): [10420:24:0] kso: new process: pid=8928 (legacy spawn) 2025-05-18 05:59:53.877669 :989CBFE4: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-05-18 05:59:53.877853 :989CBFE9: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-05-18 05:59:53.878641 :989CBFEE:db_trace:kst.c@698:kstpsodel(): [10280:24:0] kst: process state object about to be deleted 2025-05-18 06:00:00.014431 :989CC219:db_trace:kst.c@673:kstipg(): [10280:24:0] kst: process state object created on 05-18 06:00:00.007 2025-05-18 06:00:00.014433 :989CC21A:db_trace:kst.c@679:kstipg(): [10280:24:0] kst: process info: ospid=9748 pso_num=24 pso_serial#=94 2025-05-18 06:00:00.014543 :989CC21B:db_trace:kso.c@4093:ksonfy(): [10420:24:0] kso: new process: pid=9748 osp_idx=22 osp_ver=285183 osp_pg=0 (spawn #193613) 2025-05-18 06:00:00.014544 :989CC21C:db_trace:kso.c@4099:ksonfy(): [10420:24:0] kso: new process: first_req_pid=3656 spawner_pid=3660 sga_crt_ts=x8A8A 2025-05-18 06:00:00.014545 :989CC21D:db_trace:kso.c@4103:ksonfy(): [10420:24:0] kso: new process: pso_index=24 reservation=56 time=523809875 2025-05-18 06:00:00.014545 :989CC21E:db_trace:kso.c@4109:ksonfy(): [10420:24:0] kso: spawn diagnostics: queue time=0 secs 2025-05-18 06:00:00.014545 :989CC21F:db_trace:kso.c@4111:ksonfy(): [10420:24:0] kso: spawn diagnostics: fork time=0 secs 2025-05-18 06:00:00.014546 :989CC220:db_trace:kso.c@4113:ksonfy(): [10420:24:0] kso: spawn diagnostics: exec time=0 secs 2025-05-18 06:00:00.014546 :989CC221:db_trace:kso.c@4115:ksonfy(): [10420:24:0] kso: spawn diagnostics: pre dbkc time=0 secs 2025-05-18 06:00:00.014547 :989CC222:db_trace:kso.c@4117:ksonfy(): [10420:24:0] kso: spawn diagnostics: post dbkc time=0 secs 2025-05-18 06:00:00.014620 :989CC223: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-05-18 06:00:00.014628 :989CC224: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: 9748, VKRM)